builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0169 starttime: 1461170256.41 results: success (0) buildid: 20160420073644 builduid: 0869c7578221412bbcb606f82f34be8c revision: c678a3c4169ecdb9bb761e98978307b008659246 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.414286) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.416461) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.417210) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.439744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.440138) ========= 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.usOVBtfBod/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.AWeqh2WYlh/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-20 09:37:36-- 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% 8.26M=0.001s 2016-04-20 09:37:36 (8.26 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.592965) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.593315) ========= 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.usOVBtfBod/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.AWeqh2WYlh/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.020115 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:36.642514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 09:37:36.643001) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c678a3c4169ecdb9bb761e98978307b008659246 --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 c678a3c4169ecdb9bb761e98978307b008659246 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.usOVBtfBod/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.AWeqh2WYlh/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-20 09:37:36,715 truncating revision to first 12 chars 2016-04-20 09:37:36,715 Setting DEBUG logging. 2016-04-20 09:37:36,715 attempt 1/10 2016-04-20 09:37:36,715 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c678a3c4169e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 09:37:38,584 unpacking tar archive at: mozilla-aurora-c678a3c4169e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.122955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 09:37:38.783552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:38.783886) ========= script_repo_revision: c678a3c4169ecdb9bb761e98978307b008659246 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:38.784278) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:38.784545) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 09:37:38.798419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-04-20 09:37:38.798820) ========= /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.usOVBtfBod/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.AWeqh2WYlh/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:37:38 INFO - MultiFileLogger online at 20160420 09:37:38 in /builds/slave/test 09:37:38 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 09:37:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:38 INFO - {'append_to_log': False, 09:37:38 INFO - 'base_work_dir': '/builds/slave/test', 09:37:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:37:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:38 INFO - 'branch': 'Mozilla-Aurora', 09:37:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:38 INFO - 'config_files': ('talos/mac_config.py',), 09:37:38 INFO - 'default_actions': ('clobber', 09:37:38 INFO - 'read-buildbot-config', 09:37:38 INFO - 'download-and-extract', 09:37:38 INFO - 'populate-webroot', 09:37:38 INFO - 'create-virtualenv', 09:37:38 INFO - 'install', 09:37:38 INFO - 'run-tests'), 09:37:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:38 INFO - 'download_minidump_stackwalk': True, 09:37:38 INFO - 'download_symbols': 'ondemand', 09:37:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:38 INFO - 'installer_path': 'installer.exe', 09:37:38 INFO - 'log_level': 'info', 09:37:38 INFO - 'log_name': 'talos', 09:37:38 INFO - 'log_to_console': True, 09:37:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:37:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:37:38 INFO - 'opt_config_files': (), 09:37:38 INFO - 'pip_index': False, 09:37:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:38 INFO - 'cmd': ('bash', 09:37:38 INFO - '-c', 09:37:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:38 INFO - 'enabled': True, 09:37:38 INFO - 'halt_on_failure': False, 09:37:38 INFO - 'name': 'check_screen_resolution'},), 09:37:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:38 INFO - 'cmd': ('bash', 09:37:38 INFO - '-c', 09:37:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:38 INFO - 'enabled': True, 09:37:38 INFO - 'halt_on_failure': False, 09:37:38 INFO - 'name': 'check_screen_resolution'},), 09:37:38 INFO - 'run_cmd_checks_enabled': True, 09:37:38 INFO - 'sps_profile': False, 09:37:38 INFO - 'sps_profile_interval': 0, 09:37:38 INFO - 'suite': 'dromaeojs', 09:37:38 INFO - 'system_bits': '32', 09:37:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:38 INFO - 'title': 't-yosemite-r7-0169', 09:37:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:38 INFO - 'use_talos_json': True, 09:37:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:38 INFO - 'work_dir': 'build'} 09:37:38 INFO - ##### 09:37:38 INFO - ##### Running clobber step. 09:37:38 INFO - ##### 09:37:38 INFO - Running pre-action listener: _resource_record_pre_action 09:37:38 INFO - Running main action method: clobber 09:37:38 INFO - rmtree: /builds/slave/test/build 09:37:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:40 INFO - Running post-action listener: _resource_record_post_action 09:37:40 INFO - ##### 09:37:40 INFO - ##### Running read-buildbot-config step. 09:37:40 INFO - ##### 09:37:40 INFO - Running pre-action listener: _resource_record_pre_action 09:37:40 INFO - Running main action method: read_buildbot_config 09:37:40 INFO - Using buildbot properties: 09:37:40 INFO - { 09:37:40 INFO - "project": "", 09:37:40 INFO - "product": "firefox", 09:37:40 INFO - "script_repo_revision": "production", 09:37:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:37:40 INFO - "repository": "", 09:37:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:37:40 INFO - "buildid": "20160420073644", 09:37:40 INFO - "pgo_build": "False", 09:37:40 INFO - "basedir": "/builds/slave/test", 09:37:40 INFO - "buildnumber": 298, 09:37:40 INFO - "slavename": "t-yosemite-r7-0169", 09:37:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:37:40 INFO - "platform": "yosemite_r7", 09:37:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:37:40 INFO - "branch": "mozilla-aurora", 09:37:40 INFO - "repo_path": "releases/mozilla-aurora", 09:37:40 INFO - "revision": "c678a3c4169ecdb9bb761e98978307b008659246", 09:37:40 INFO - "stage_platform": "macosx64", 09:37:40 INFO - "builduid": "0869c7578221412bbcb606f82f34be8c", 09:37:40 INFO - "slavebuilddir": "test" 09:37:40 INFO - } 09:37:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 09:37:40 INFO - Running post-action listener: _resource_record_post_action 09:37:40 INFO - ##### 09:37:40 INFO - ##### Running download-and-extract step. 09:37:40 INFO - ##### 09:37:40 INFO - Running pre-action listener: _resource_record_pre_action 09:37:40 INFO - Running main action method: download_and_extract 09:37:40 INFO - mkdir: /builds/slave/test/build/tests 09:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:40 INFO - https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:37:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 09:37:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 09:37:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 09:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 09:37:41 INFO - Downloaded 1277 bytes. 09:37:41 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 09:37:41 INFO - Using the following test package requirements: 09:37:41 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:37:41 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 09:37:41 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 09:37:41 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'jsshell-mac.zip'], 09:37:41 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 09:37:41 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:37:41 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 09:37:41 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 09:37:41 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 09:37:41 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:37:41 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:37:41 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 09:37:41 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:41 INFO - https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:37:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:37:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:37:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:37:42 INFO - Downloaded 17912066 bytes. 09:37:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:37:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:37:45 INFO - Return code: 0 09:37:45 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:45 INFO - https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:37:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:37:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:37:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:37:47 INFO - Downloaded 17912066 bytes. 09:37:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:37:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:37:51 INFO - Return code: 0 09:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:51 INFO - https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:37:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:37:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:37:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:37:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:37:53 INFO - Downloaded 10845168 bytes. 09:37:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:37:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:37:53 INFO - Return code: 0 09:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:53 INFO - https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:37:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:37:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:37:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 09:37:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 09:37:56 INFO - Downloaded 115249984 bytes. 09:37:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:37:56 INFO - mkdir: /builds/slave/test/properties 09:37:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:56 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:56 INFO - Contents: 09:37:56 INFO - build_url:https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:37:57 INFO - Running post-action listener: _resource_record_post_action 09:37:57 INFO - Running post-action listener: set_extra_try_arguments 09:37:57 INFO - ##### 09:37:57 INFO - ##### Running populate-webroot step. 09:37:57 INFO - ##### 09:37:57 INFO - Running pre-action listener: _resource_record_pre_action 09:37:57 INFO - Running main action method: populate_webroot 09:37:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:57 INFO - '--tppagecycles', 09:37:57 INFO - '7'], 09:37:57 INFO - 'tests': ['tsvgm']}}, 09:37:57 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:37:57 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:57 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:37:57 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'tests': ['damp', 'tps']}, 09:37:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['damp', 'tps']}, 09:37:57 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:37:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:57 INFO - 'other': {'tests': ['a11yr', 09:37:57 INFO - 'ts_paint', 09:37:57 INFO - 'tpaint', 09:37:57 INFO - 'sessionrestore', 09:37:57 INFO - 'sessionrestore_no_auto_restore', 09:37:57 INFO - 'tabpaint']}, 09:37:57 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['a11yr', 09:37:57 INFO - 'ts_paint', 09:37:57 INFO - 'tpaint', 09:37:57 INFO - 'sessionrestore', 09:37:57 INFO - 'sessionrestore_no_auto_restore', 09:37:57 INFO - 'tabpaint']}, 09:37:57 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['a11yr', 09:37:57 INFO - 'ts_paint', 09:37:57 INFO - 'tpaint', 09:37:57 INFO - 'sessionrestore', 09:37:57 INFO - 'sessionrestore_no_auto_restore', 09:37:57 INFO - 'tabpaint']}, 09:37:57 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['a11yr', 09:37:57 INFO - 'ts_paint', 09:37:57 INFO - 'tpaint', 09:37:57 INFO - 'sessionrestore', 09:37:57 INFO - 'sessionrestore_no_auto_restore', 09:37:57 INFO - 'tabpaint']}, 09:37:57 INFO - 'other_l64': {'tests': ['a11yr', 09:37:57 INFO - 'ts_paint', 09:37:57 INFO - 'tpaint', 09:37:57 INFO - 'sessionrestore', 09:37:57 INFO - 'sessionrestore_no_auto_restore', 09:37:57 INFO - 'tabpaint']}, 09:37:57 INFO - 'other_nol64': {'tests': ['a11yr', 09:37:57 INFO - 'ts_paint', 09:37:57 INFO - 'tpaint', 09:37:57 INFO - 'sessionrestore', 09:37:57 INFO - 'sessionrestore_no_auto_restore', 09:37:57 INFO - 'tabpaint']}, 09:37:57 INFO - 'svgr': {'tests': ['tsvgx', 09:37:57 INFO - 'tsvgr_opacity', 09:37:57 INFO - 'tart', 09:37:57 INFO - 'tscrollx', 09:37:57 INFO - 'cart']}, 09:37:57 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['tsvgx', 09:37:57 INFO - 'tsvgr_opacity', 09:37:57 INFO - 'tart', 09:37:57 INFO - 'tscrollx', 09:37:57 INFO - 'cart']}, 09:37:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'tests': ['tp5o']}, 09:37:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'talos_options': ['--e10s'], 09:37:57 INFO - 'tests': ['tp5o']}, 09:37:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'talos_options': ['--xperf_path', 09:37:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:57 INFO - 'tests': ['tp5n']}, 09:37:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:57 INFO - 'talos_options': ['--e10s', 09:37:57 INFO - '--xperf_path', 09:37:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:57 INFO - 'tests': ['tp5n']}}, 09:37:57 INFO - 'talos.zip': {'path': '', 09:37:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:57 INFO - Running post-action listener: _resource_record_post_action 09:37:57 INFO - ##### 09:37:57 INFO - ##### Running create-virtualenv step. 09:37:57 INFO - ##### 09:37:57 INFO - Running pre-action listener: _resource_record_pre_action 09:37:57 INFO - Running main action method: create_virtualenv 09:37:57 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:37:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:57 INFO - Using real prefix '/tools/python27' 09:37:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:01 INFO - Installing pip.................done. 09:38:01 INFO - Return code: 0 09:38:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:01 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:01 INFO - 'HOME': '/Users/cltbld', 09:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:01 INFO - 'LOGNAME': 'cltbld', 09:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:01 INFO - 'MOZ_NO_REMOTE': '1', 09:38:01 INFO - 'NO_EM_RESTART': '1', 09:38:01 INFO - 'PAGER': '/bin/cat', 09:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:01 INFO - 'PWD': '/builds/slave/test', 09:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:01 INFO - 'SHELL': '/bin/bash', 09:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:01 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:01 INFO - 'USER': 'cltbld', 09:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:01 INFO - 'XPC_FLAGS': '0x0', 09:38:01 INFO - 'XPC_SERVICE_NAME': '0', 09:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:38:01 INFO - Cleaning up... 09:38:01 INFO - Return code: 0 09:38:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:01 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:01 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 09:38:01 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 09:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:01 INFO - 'HOME': '/Users/cltbld', 09:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:01 INFO - 'LOGNAME': 'cltbld', 09:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:01 INFO - 'MOZ_NO_REMOTE': '1', 09:38:01 INFO - 'NO_EM_RESTART': '1', 09:38:01 INFO - 'PAGER': '/bin/cat', 09:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:01 INFO - 'PWD': '/builds/slave/test', 09:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:01 INFO - 'SHELL': '/bin/bash', 09:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:01 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:01 INFO - 'USER': 'cltbld', 09:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:01 INFO - 'XPC_FLAGS': '0x0', 09:38:01 INFO - 'XPC_SERVICE_NAME': '0', 09:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:01 INFO - Downloading/unpacking psutil>=0.7.1 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:04 INFO - Installing collected packages: psutil 09:38:04 INFO - Running setup.py install for psutil 09:38:04 INFO - building 'psutil._psutil_osx' extension 09:38:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:38:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:38:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:38:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:38:05 INFO - building 'psutil._psutil_posix' extension 09:38:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:38:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:38:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:38:05 INFO - ^ 09:38:05 INFO - 1 warning generated. 09:38:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:38:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:05 INFO - Successfully installed psutil 09:38:05 INFO - Cleaning up... 09:38:05 INFO - Return code: 0 09:38:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:05 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 09:38:05 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 09:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:05 INFO - 'HOME': '/Users/cltbld', 09:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:05 INFO - 'LOGNAME': 'cltbld', 09:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:05 INFO - 'MOZ_NO_REMOTE': '1', 09:38:05 INFO - 'NO_EM_RESTART': '1', 09:38:05 INFO - 'PAGER': '/bin/cat', 09:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:05 INFO - 'PWD': '/builds/slave/test', 09:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:05 INFO - 'SHELL': '/bin/bash', 09:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:05 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:05 INFO - 'USER': 'cltbld', 09:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:05 INFO - 'XPC_FLAGS': '0x0', 09:38:05 INFO - 'XPC_SERVICE_NAME': '0', 09:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:38:08 INFO - Installing collected packages: mozsystemmonitor 09:38:08 INFO - Running setup.py install for mozsystemmonitor 09:38:08 INFO - Successfully installed mozsystemmonitor 09:38:08 INFO - Cleaning up... 09:38:08 INFO - Return code: 0 09:38:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:08 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:38:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:08 INFO - 'HOME': '/Users/cltbld', 09:38:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:08 INFO - 'LOGNAME': 'cltbld', 09:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:08 INFO - 'MOZ_NO_REMOTE': '1', 09:38:08 INFO - 'NO_EM_RESTART': '1', 09:38:08 INFO - 'PAGER': '/bin/cat', 09:38:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:08 INFO - 'PWD': '/builds/slave/test', 09:38:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:08 INFO - 'SHELL': '/bin/bash', 09:38:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:08 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:08 INFO - 'USER': 'cltbld', 09:38:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:08 INFO - 'XPC_FLAGS': '0x0', 09:38:08 INFO - 'XPC_SERVICE_NAME': '0', 09:38:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:12 INFO - Downloading docopt-0.6.1.tar.gz 09:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:12 INFO - Running setup.py install for blobuploader 09:38:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:12 INFO - Running setup.py install for requests 09:38:13 INFO - Running setup.py install for docopt 09:38:13 INFO - Successfully installed blobuploader requests docopt 09:38:13 INFO - Cleaning up... 09:38:13 INFO - Return code: 0 09:38:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:13 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:13 INFO - 'HOME': '/Users/cltbld', 09:38:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:13 INFO - 'LOGNAME': 'cltbld', 09:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:13 INFO - 'MOZ_NO_REMOTE': '1', 09:38:13 INFO - 'NO_EM_RESTART': '1', 09:38:13 INFO - 'PAGER': '/bin/cat', 09:38:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:13 INFO - 'PWD': '/builds/slave/test', 09:38:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:13 INFO - 'SHELL': '/bin/bash', 09:38:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:13 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:13 INFO - 'USER': 'cltbld', 09:38:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:13 INFO - 'XPC_FLAGS': '0x0', 09:38:13 INFO - 'XPC_SERVICE_NAME': '0', 09:38:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7SE2kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-0MCf5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-cnEOD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-PzhnSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-9bQBTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-nuci3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-S35Zan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-YAyWFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-AhH8i5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-osy6BL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-5PMDti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-W2QfoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-i1Yhss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pBkmwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vYDBZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pySQNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-hUhkmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:15 INFO - Running setup.py install for manifestparser 09:38:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:15 INFO - Running setup.py install for mozcrash 09:38:15 INFO - Running setup.py install for mozdebug 09:38:15 INFO - Running setup.py install for mozdevice 09:38:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:38:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:15 INFO - Running setup.py install for mozfile 09:38:16 INFO - Running setup.py install for mozhttpd 09:38:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Running setup.py install for mozinfo 09:38:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Running setup.py install for mozInstall 09:38:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Running setup.py install for mozleak 09:38:16 INFO - Running setup.py install for mozlog 09:38:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Running setup.py install for moznetwork 09:38:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozprocess 09:38:17 INFO - Running setup.py install for mozprofile 09:38:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozrunner 09:38:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozscreenshot 09:38:17 INFO - Running setup.py install for moztest 09:38:17 INFO - Running setup.py install for mozversion 09:38:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:17 INFO - Cleaning up... 09:38:17 INFO - Return code: 0 09:38:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:17 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:17 INFO - 'HOME': '/Users/cltbld', 09:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:17 INFO - 'LOGNAME': 'cltbld', 09:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:17 INFO - 'MOZ_NO_REMOTE': '1', 09:38:17 INFO - 'NO_EM_RESTART': '1', 09:38:17 INFO - 'PAGER': '/bin/cat', 09:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:17 INFO - 'PWD': '/builds/slave/test', 09:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:17 INFO - 'SHELL': '/bin/bash', 09:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:17 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:17 INFO - 'USER': 'cltbld', 09:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:17 INFO - 'XPC_FLAGS': '0x0', 09:38:17 INFO - 'XPC_SERVICE_NAME': '0', 09:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Hhwd1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-P8BbDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sg8QHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-1m8jwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vCdjI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-wQ1xcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-wT0B9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ZscwwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-d9Jft7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-GlJONv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:19 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)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-upZLOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-oCA2XA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-cKWRGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-LvNd4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-naofyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-DpS7iJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-858vzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:22 INFO - Downloading blessings-1.6.tar.gz 09:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:22 INFO - Installing collected packages: blessings 09:38:22 INFO - Running setup.py install for blessings 09:38:22 INFO - Successfully installed blessings 09:38:22 INFO - Cleaning up... 09:38:22 INFO - Return code: 0 09:38:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:23 INFO - Reading from file tmpfile_stdout 09:38:23 INFO - Current package versions: 09:38:23 INFO - blessings == 1.6 09:38:23 INFO - blobuploader == 1.2.4 09:38:23 INFO - docopt == 0.6.1 09:38:23 INFO - manifestparser == 1.1 09:38:23 INFO - mozInstall == 1.12 09:38:23 INFO - mozcrash == 0.17 09:38:23 INFO - mozdebug == 0.1 09:38:23 INFO - mozdevice == 0.48 09:38:23 INFO - mozfile == 1.2 09:38:23 INFO - mozhttpd == 0.7 09:38:23 INFO - mozinfo == 0.9 09:38:23 INFO - mozleak == 0.1 09:38:23 INFO - mozlog == 3.1 09:38:23 INFO - moznetwork == 0.27 09:38:23 INFO - mozprocess == 0.22 09:38:23 INFO - mozprofile == 0.28 09:38:23 INFO - mozrunner == 6.11 09:38:23 INFO - mozscreenshot == 0.1 09:38:23 INFO - mozsystemmonitor == 0.0 09:38:23 INFO - moztest == 0.7 09:38:23 INFO - mozversion == 1.4 09:38:23 INFO - psutil == 3.1.1 09:38:23 INFO - requests == 1.2.3 09:38:23 INFO - wsgiref == 0.1.2 09:38:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:23 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:23 INFO - 'HOME': '/Users/cltbld', 09:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:23 INFO - 'LOGNAME': 'cltbld', 09:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:23 INFO - 'MOZ_NO_REMOTE': '1', 09:38:23 INFO - 'NO_EM_RESTART': '1', 09:38:23 INFO - 'PAGER': '/bin/cat', 09:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:23 INFO - 'PWD': '/builds/slave/test', 09:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:23 INFO - 'SHELL': '/bin/bash', 09:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:23 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:23 INFO - 'USER': 'cltbld', 09:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:23 INFO - 'XPC_FLAGS': '0x0', 09:38:23 INFO - 'XPC_SERVICE_NAME': '0', 09:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:23 INFO - Cleaning up... 09:38:23 INFO - Return code: 0 09:38:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:23 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:23 INFO - 'HOME': '/Users/cltbld', 09:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:23 INFO - 'LOGNAME': 'cltbld', 09:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:23 INFO - 'MOZ_NO_REMOTE': '1', 09:38:23 INFO - 'NO_EM_RESTART': '1', 09:38:23 INFO - 'PAGER': '/bin/cat', 09:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:23 INFO - 'PWD': '/builds/slave/test', 09:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:23 INFO - 'SHELL': '/bin/bash', 09:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:23 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:23 INFO - 'USER': 'cltbld', 09:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:23 INFO - 'XPC_FLAGS': '0x0', 09:38:23 INFO - 'XPC_SERVICE_NAME': '0', 09:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:23 INFO - Downloading/unpacking jsonschema 09:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:25 INFO - Downloading/unpacking functools32 (from jsonschema) 09:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:26 INFO - warning: no files found matching '*.txt' 09:38:26 INFO - no previously-included directories found matching 'build' 09:38:26 INFO - no previously-included directories found matching 'dist' 09:38:26 INFO - no previously-included directories found matching '.git*' 09:38:26 INFO - Installing collected packages: jsonschema, functools32 09:38:26 INFO - Running setup.py install for functools32 09:38:26 INFO - warning: no files found matching '*.txt' 09:38:26 INFO - no previously-included directories found matching 'build' 09:38:26 INFO - no previously-included directories found matching 'dist' 09:38:26 INFO - no previously-included directories found matching '.git*' 09:38:26 INFO - Successfully installed jsonschema functools32 09:38:26 INFO - Cleaning up... 09:38:26 INFO - Return code: 0 09:38:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:38:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:27 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 0x10f0aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0b54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9290da5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, '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.usOVBtfBod/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.AWeqh2WYlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:27 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 09:38:27 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 09:38:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:38:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:27 INFO - 'HOME': '/Users/cltbld', 09:38:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:27 INFO - 'LOGNAME': 'cltbld', 09:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:27 INFO - 'MOZ_NO_REMOTE': '1', 09:38:27 INFO - 'NO_EM_RESTART': '1', 09:38:27 INFO - 'PAGER': '/bin/cat', 09:38:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:27 INFO - 'PWD': '/builds/slave/test', 09:38:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:27 INFO - 'SHELL': '/bin/bash', 09:38:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:38:27 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:38:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:27 INFO - 'USER': 'cltbld', 09:38:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:27 INFO - 'XPC_FLAGS': '0x0', 09:38:27 INFO - 'XPC_SERVICE_NAME': '0', 09:38:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:27 INFO - Downloading/unpacking flake8 09:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:38:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:38:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:38:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:38:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:38:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:38:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:38:31 INFO - no previously-included directories found matching 'docs/_build' 09:38:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:38:31 INFO - Running setup.py install for pep8 09:38:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:38:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:38:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:38:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:38:31 INFO - no previously-included directories found matching 'docs/_build' 09:38:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:38:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:38:31 INFO - Cleaning up... 09:38:31 INFO - Return code: 0 09:38:31 INFO - Running post-action listener: _resource_record_post_action 09:38:31 INFO - Running post-action listener: _start_resource_monitoring 09:38:31 INFO - Starting resource monitoring. 09:38:31 INFO - ##### 09:38:31 INFO - ##### Running install step. 09:38:31 INFO - ##### 09:38:31 INFO - Running pre-action listener: _resource_record_pre_action 09:38:31 INFO - Running main action method: install 09:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:32 INFO - Reading from file tmpfile_stdout 09:38:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:32 INFO - Reading from file tmpfile_stdout 09:38:32 INFO - Output received: 09:38:32 INFO - Usage: mozinstall [options] installer 09:38:32 INFO - Options: 09:38:32 INFO - -h, --help show this help message and exit 09:38:32 INFO - -d DEST, --destination=DEST 09:38:32 INFO - Directory to install application into. [default: 09:38:32 INFO - "/builds/slave/test"] 09:38:32 INFO - --app=APP Application being installed. [default: firefox] 09:38:32 INFO - mkdir: /builds/slave/test/build/application 09:38:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:39:04 INFO - Reading from file tmpfile_stdout 09:39:04 INFO - Output received: 09:39:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:39:04 INFO - Running post-action listener: _resource_record_post_action 09:39:04 INFO - ##### 09:39:04 INFO - ##### Running run-tests step. 09:39:04 INFO - ##### 09:39:04 INFO - Running pre-action listener: _resource_record_pre_action 09:39:04 INFO - Running main action method: run_tests 09:39:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:04 INFO - 2016-04-20 09:39:04.959 screenresolution[1977:14733] starting screenresolution argv=screenresolution get 09:39:04 INFO - 2016-04-20 09:39:04.962 screenresolution[1977:14733] Display 0: 1600x1200x32@60 09:39:04 INFO - 2016-04-20 09:39:04.967 screenresolution[1978:14735] starting screenresolution argv=screenresolution list 09:39:04 INFO - Available Modes on Display 0 09:39:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:05 INFO - Intel Iris: 09:39:05 INFO - Chipset Model: Intel Iris 09:39:05 INFO - Type: GPU 09:39:05 INFO - Bus: Built-In 09:39:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:05 INFO - Vendor: Intel (0x8086) 09:39:05 INFO - Device ID: 0x0a2e 09:39:05 INFO - Revision ID: 0x0009 09:39:05 INFO - Displays: 09:39:05 INFO - Display: 09:39:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:05 INFO - Display Serial Number: 1600x1200 60 09:39:05 INFO - Main Display: Yes 09:39:05 INFO - Mirror: Off 09:39:05 INFO - Online: Yes 09:39:05 INFO - Rotation: Supported 09:39:05 INFO - Return code: 0 09:39:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:05 INFO - Python 2.7.3 09:39:05 INFO - Return code: 0 09:39:05 INFO - grabbing minidump binary from tooltool 09:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:05 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 0x10ef52d30>, '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 0x10edb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef49b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:39:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:39:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:05 INFO - Return code: 0 09:39:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:39:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:39:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:39:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:39:06 INFO - Return code: 0 09:39:06 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/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:39:06 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/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:39:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.usOVBtfBod/Render', 09:39:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:06 INFO - 'HOME': '/Users/cltbld', 09:39:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:06 INFO - 'LOGNAME': 'cltbld', 09:39:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:06 INFO - 'MOZ_NO_REMOTE': '1', 09:39:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:06 INFO - 'NO_EM_RESTART': '1', 09:39:06 INFO - 'PAGER': '/bin/cat', 09:39:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:06 INFO - 'PWD': '/builds/slave/test', 09:39:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:06 INFO - 'SHELL': '/bin/bash', 09:39:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWeqh2WYlh/Listeners', 09:39:06 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:39:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:06 INFO - 'USER': 'cltbld', 09:39:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:06 INFO - 'XPC_FLAGS': '0x0', 09:39:06 INFO - 'XPC_SERVICE_NAME': '0', 09:39:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:06 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/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:39:06 INFO - mozversion application_buildid: 20160420073644 09:39:06 INFO - mozversion application_changeset: c678a3c4169ecdb9bb761e98978307b008659246 09:39:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:39:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:06 INFO - mozversion application_name: Firefox 09:39:06 INFO - mozversion application_remotingname: firefox-dev 09:39:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:39:06 INFO - mozversion application_vendor: Mozilla 09:39:06 INFO - mozversion application_version: 47.0a2 09:39:06 INFO - mozversion platform_buildid: 20160420073644 09:39:06 INFO - mozversion platform_changeset: c678a3c4169ecdb9bb761e98978307b008659246 09:39:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:39:06 INFO - mozversion platform_version: 47.0a2 09:39:06 INFO - using testdate: 1461170346 09:39:06 INFO - actual date: 1461170346 09:39:06 INFO - starting webserver on 'localhost:49656' 09:39:06 INFO - SUITE-START | Running 2 tests 09:39:06 INFO - TEST-START | dromaeo_css 09:39:06 INFO - operating with platform_type : mac_ 09:39:06 INFO - Initialising browser for dromaeo_css test... 09:39:06 INFO - TEST-INFO | started process 1996 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpZsjX08/profile http://localhost:49656/getInfo.html) 09:39:08 INFO - PROCESS | 1996 | __metrics Screen width/height:1600/1200 09:39:08 INFO - PROCESS | 1996 | colorDepth:24 09:39:08 INFO - PROCESS | 1996 | Browser inner width/height: 1024/689 09:39:08 INFO - PROCESS | 1996 | __metrics 09:39:08 INFO - PROCESS | 1996 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:39:08 INFO - PROCESS | 1996 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:09 INFO - TEST-INFO | 1996: exit 0 09:39:09 INFO - Browser initialized. 09:39:09 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:09 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpZsjX08/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:21 INFO - PROCESS | 1998 | RSS: Main: 207446016 09:39:21 INFO - PROCESS | 1998 | 09:40:12 INFO - PROCESS | 1998 | 1461170412368 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:42:22 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49656/tests/dromaeo/cssquery-ext.html) 09:42:23 INFO - PROCESS | 1998 | RSS: Main: 322560000 09:42:23 INFO - PROCESS | 1998 | 09:45:26 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-ext.html (next: http://localhost:49656/tests/dromaeo/cssquery-jquery.html) 09:45:26 INFO - PROCESS | 1998 | RSS: Main: 343875584 09:45:26 INFO - PROCESS | 1998 | 09:48:28 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49656/tests/dromaeo/cssquery-mootools.html) 09:48:29 INFO - PROCESS | 1998 | RSS: Main: 346300416 09:48:29 INFO - PROCESS | 1998 | 09:51:31 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49656/tests/dromaeo/cssquery-prototype.html) 09:51:31 INFO - PROCESS | 1998 | RSS: Main: 307802112 09:51:31 INFO - PROCESS | 1998 | 09:54:34 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49656/tests/dromaeo/cssquery-yui.html) 09:54:35 INFO - PROCESS | 1998 | RSS: Main: 307109888 09:54:35 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-yui.html (next: http://localhost:49656/tests/dromaeo/cssquery-dojo.html) 09:57:38 INFO - PROCESS | 1998 | RSS: Main: 351408128 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | __start_tp_report 09:57:38 INFO - PROCESS | 1998 | _x_x_mozilla_page_load 09:57:38 INFO - PROCESS | 1998 | _x_x_mozilla_page_load_details 09:57:38 INFO - PROCESS | 1998 | |i|pagename|runs| 09:57:38 INFO - PROCESS | 1998 | |0;dojo.html;3144.7012533809293;3460.6366331535187;3474.0099071764535;3493.94759078614;3529.699975502084;5683.119116536944;5685.090385538315;5689.516391106756;5704.201411802346;5704.686242256675;5666;5693.060644993581;5724.942750572493;5736.053551164053;5744.339400968879;5072.568831649314;5103.157978933472;5114.590832733391;5165.096108181072;5167.48325167484;5192.324997750287;5203.959208158364;5206.401263854648;5206.531412172903;5211.843644690665;5136.845894623168;5147.794088236466;5154.355705536808;5172.896542069157;5185.533302002819;3988.202359528106;5102.36220472441;5172.069027575035;5189.948100519003;5218.321618189649;4958;5125.641205115643;5145.562627176694;5170.819021334273;5176.0683077046115;5568.16477528374;5597.104463285855;5604.523615492688;5639.605227634026;5655.434456554352;6109.677964501043;6257.342978987232;6260.812175634738;6266.592671476339;6267.655278959655;6141.754329826802;6157.261128664544;6166.07508873668;6185.319614842364;6191.473724733403;5818.294493060705;5837.6205546640185;5869.295684517842;5872.530197584183;5878.617889837147;6155.507559395238;6164.753409863645;6211.192544969125;6216.906746398808;6223.813285601439;6166.907496387557;6170.5372097092895;6184.845378865474;6191;6192;5139.794408223629;6221.906671399933;6223.159873417031;6223.626582405071;6234.875302494015;5375.3012108425655;5883.852903677443;6089.086637004485;6242.563020588606;6251.4061164189325;6179.104029915637;6233.688315584293;6234.438900498886;6264.404881536247;6268.153799237136;4856.441509226484;4859.95140048595;4862.489438608965;4879.243717223836;4898.191798353232;3843.673287770571;4322.978385108055;4425.468943726773;4438.60052595268;4440.489343725513;5589.35722391922;5623.0159722048475;5647.491725744702;5657.4908258256955;5678;6208.4722798562625;6211.720472578745;6216.129741836146;6231.22109736283;6250.624962502265;5430.56555475561;5568.7215639217875;5639.887202255932;5658.462446067617;5662.150677398341;5299.5230429261555;5329.25390445338;5337.653052551572;5348.8662783429945;5358.651687640291;6209.627422354673;6249.937500625027;6258.311585725566;6285.748570057147;6289.27673317556;6153.015517517355;6155.076738489262;6205.627662340274;6215.627062376272;6226.25284965807;6234.064890266496;6239.438450539292;6252.062190671436;6258;6274.686265686606;6190.1952746142715;6194.783182588588;6201.658908759881;6208.9689551551955;6212.502999760099;5594.720263986866;6088.543359248163;6142.775043102229;6168.629882207082;6193.969030154821;5581.944180558305;6041.758329666764;6056.636601803906;6057.242844644419;6074.7266373012435;5982.102684597171;6079.726412311545;6082.148499210202;6088.573799833969;6099.481544068626;5850.0932355484965;5916.467517923407;5930.199423077829;5954.047352423591;5999.91000135007;6074.817755467343;6077.149199112039;6084.39156084388;6098.75604975796;6113.5414843886;5837.445442682938;5921.2006379138265;5953.987822070172;5994.55040871945;6007 09:57:38 INFO - PROCESS | 1998 | |1;ext.html;506.83781190019164;589.8407429993902;598.0520874414053;669.0833558025506;669.5112567825489;26134.346641333977;39872;40095;40278.798606007;40293.19413611726;42643.14713705732;43224.91937701559;43276;43300.7009789706;43308.133837323236;9657.179139773129;9867.407955522656;9869.161121304662;9887.11016008559;9889.20886329092;9696.757581060494;9836.262280328969;9838.754031149207;9840.409575425498;9849;9811.95094024529;9862.753431164243;9889.10998010179;9889.703308900744;9891;9787.755306117333;9815.852762208573;9855.310128291023;9857.162141218005;9858.556364963593;9659.565319560637;9823.852642210373;9833.065858743408;9840.557174927073;9862.50687465631;41440.58559414397;41974.79012604917;41988;41993.79003104995;41996.79001604972;3278.2951665392056;3406.931861362784;3441.776284541522;3573.9278216534935;3806.0484878780303;28738.281542961384;29348.413031739245;29699.257518561993;29850.70149298501;29913.85043074771;2304.3547806614174;2700.7832171999985;2763.1434255380696;2804.8457334846576;2838.5316422790215;470672;472205;472219;472962;473023.6348818268;63556.04665930015;65331;65352.67323663351;65488.67255663691;65536;69256.65371673109;76389;77456.61271693718;77665;77980.61009694915;15130.697386052218;17829.286828526714;18030.36893708714;18059.006754628357;18069.096545172688;13657.658558535917;13760.518381856587;13774.449022039005;13821.308934553232;13832.930835345758;22144.88927555352;22454.326370208593;22478.438039049153;22490.425670203254;22494.21270255533;8084.797880052928;9333.73994510746;9336.859947100798;9522;9583.712488625353;860.9195459698038;942.5428667096453;955.4171955107379;1063.8510608514814;1119.4234968990977;29079.56380654292;30159.547606785476;30278.394432111236;30430.54354184689;30894.918677846166;1101.6694991502518;1136.7669627726295;1209.7096696792707;1211.127987848765;1248.5193200617646;12423;15785.842141578438;16002.679946401007;16099.436519721754;16442.506724798277;549103;552735;556599;556878;557110;61923.38076619266;62522.0621690675;62609.68695156586;62720;62875.685621571596;1426.9500567480088;1556.5719427157621;1558.3065535836445;1586.4606033948512;1606.044403579868;28889.42221155523;30007.849960750056;30030.399392012474;30177.396452071276;30688.84655576708;48072;48427.75786121047;48941.26588101253;48960.75519622379;49068.75465622649;353976;355529.44588047906;356027;356369;358052;29461.558076629295;34190.14524636904;34555.48166777551;35029.824850875586;35074.47388289127;42121.578784211764;42556.7232983011;42657.78671106625;44386.334204987594;44400.333994990746;33773.64905403855;34695.571310008054;35489.46765798567;35587;36409;31769.682303177597;35769.78071973535;36418;36679.81660091683;37575;36762.63237367592;36959;37110.44334335041;37384;37486;31919.840400797846;32870;32898.83550582232;32918.50622240716;33092.669073308956;21212.681809773174;22425;24345.756542435058;24723.87638061798;25196.370090747878 09:57:38 INFO - PROCESS | 1998 | |2;jquery.html;383.28517315207137;384.4483166655849;385.21224096722216;390.64646494922107;392.26841939782315;19608.70586941195;20866;21222.363329100113;21533.784662153394;21547.569048619018;12124.211926224789;12482.25106493611;20851.478713032182;21695.34913952584;21771.23800666977;5311.893762124755;5459.890802183954;5499.010178167928;5502.477264659851;5504.036793561131;4139.6895232857505;4330;5857.794977175799;6090.299615544225;6092.756289748405;6037.426444487766;6084.604500707462;6098.207233059718;6098.725557349908;6106.847328816771;5937.703114844262;6066.059760737093;6067.939320606782;6124.816255512342;6129.478994285447;4020.517537895442;5557.694326812024;5807.912881306784;5811.128330750379;5820.35976042635;13787;13790.448382064706;13881.236531990737;23353.76646233533;27858;2444.2056331692092;2661.42779302449;2667.6932152802483;2853.4863724529578;3154.3848949454864;15733.685326293524;16068.276927538289;16556.917215413967;17120.57198570046;17169.399071032454;1720.0711615727491;1910.8853468791917;1933.951651208717;2014.0835919656522;2018.1322031526438;75822;76378.85431718528;76485.61757191234;111089.88910110877;127680;17685.911570442066;18707.129287071457;19857;20530.79469205289;20609.89695051515;25269;31015.534766978515;31581.842090789858;31591.84204079011;31755.52366714501;11053.778924421467;11527.481263343001;11530.538778448768;11530.59642912494;11656.825147622792;7959.721409750631;9654.75863103414;9710.368826026286;9761.121499064977;9767.853482197772;8299.83400332002;8489.702860399857;8924.196822286025;9013.278937684969;9161;3024.485837407602;3040.3311271520674;3581.928361432757;3582.731295152874;3697.5193224880595;952;1030.3972176276961;1051.8429727299908;1058.8623478947688;1110.5335758981248;17340.91329543344;19303.710444343345;19767.90116049439;20265.594688106154;20978.47553811167;914.3234006835028;954.5179684259405;1008.6671398438498;1037.3361048928691;1037.968860934173;11107.666769996913;11331.830022549668;11344.943275283571;11355.318680879009;11397.430128493543;266074.6696266545;266303;278948;635684;687106;70246.64876675584;72203.63898180476;115545.33409994285;115651;121639.78360216462;1037.7198156497702;1070.8661417322835;1075.9731006724894;1076.7792602516465;1091.0725882999386;18631.068446577345;18684.7197292038;19038.952857592965;19061.90469047646;19319.227230910605;50620;51287.230691540404;51747;52362.42912712625;53264;74249.6287518559;75393;77073;77154;78390;8216.917830821616;8343;8345.33237340999;8500.234978851933;8621.439606425562;12486.063545234325;12802.359882005676;13473.865261347653;13656.385462654005;13807.930960345135;11334.8665133486;11472.648107138184;11655.067594592581;11659.008984236434;11671.59149429766;10700.357978521313;11171.273867198606;11252.549898003988;11525.11872031992;11635.709107272385;11487.310761354345;11784.175107742376;11930.284182949052;12087.912087912131;12150.696232594255;8390.412671112963;8462.619182136941;8593.140685931607;8599.39804213699;8839.646414143363;11516;11669.474873630877;11845.703857403634;11902.345371004678;11912.702182445508 09:57:38 INFO - PROCESS | 1998 | |3;mootools.html;1436.784482327651;1456.213644631899;1501.4969985055006;1584.294988730015;1777.964440711185;8178.509289442638;8838;9035.322350823682;9036.322275829307;9087.045860184684;8700.956495217531;8927.866082008759;8931.50876701781;8935.329850261223;8974.685885993975;2183.5414562941723;2273.54529094181;2503.5869081601513;2549.8725063746833;2561.321249868777;2396.7962723168553;2572.6398304237423;2581.6643836301255;2583.806214533908;2585.5346037713202;2167.729033870766;2307.5500277445904;2564.6794150731157;2565.9101931432406;2566.948661026778;2505.3360859372283;2525.1035882261717;2534.1637259704253;2542.6694529711203;2552.604346326313;2491.1281051631786;2503.7245902950663;2519.395345117177;2563.231030690804;2567.191334729573;9022.684206052756;9107.043760405195;9111.954440227757;9151.405158664666;9181.724548263563;5039.77321020555;5562.110062390038;5756.1653560233535;5764.106563482667;5779.248697669318;3665.7337703478483;3674.9448758268645;3764.2847858906994;3797.8480860765812;3809.29528037314;1054.451685123739;1064.8562444070028;1069.176733914889;1083.090204228452;1116.8548088748491;10822.67531974042;11911.868372504598;12299.938500307531;12317.630471085882;12378.442970066279;4953.356063711694;4959.801607935714;5041.143005689041;5045.344105266292;5069.188929771219;10408.427536485415;10418.791624167474;10421.843672344921;10436.165106791439;10436.530356133992;5655.293088363955;5664.405237450009;5664.915026274609;5667.688277144714;5674.546036317085;4544.318352247124;4710.505396933273;4907.2393778964315;5024.120778863758;5082.2122571001555;4035.97966366835;4088.4276201331836;4104.343305071234;4143.399207114951;4151.294279972412;1349.9527516536873;1358.877701006914;1359.4426285223012;1359.5241665417025;1367;1264.3994102801096;1382.7372799168127;1407.7677183264852;1410.2102822419479;1428.9499867504796;8321.16788321163;8448.915510844936;8456.365772567082;8465.746027619181;8477.576121193915;522.9006488767122;530.2841164427991;530.6779559954712;531.0722078853736;535.2479765928877;6278.874422511524;6293.056041593798;6334.5249106317215;6337.588056776387;6344.429001389804;10637.787244255072;11041.944790275997;11159.609413670483;11166.832497512543;11217.71955701114;10567.577296908192;10913.290636108628;10921.563137474572;10938.343699378063;10997.010269075821;8517.787055323666;8566.571671416405;8578.78553036179;8585.914140858637;8628.870566941501;8013.519188848688;8058.435909486279;8084.676612935652;8353.457025293337;8365.24712775853;7776.611169441619;9730.12428881404;9798.020197980144;9832.852507212541;9959.203263739028;9865.210783137476;10038.247131465023;10181.03280188381;10249.74375640615;10271.024252696281;6805.421539169226;6844.4524438043845;7657.6554055066545;7737.651805668871;7785.338246249133;8480;8495.95752021236;8533.87199192025;8535.701250456452;8539.274161696325;7395.445341599509;7511.286427789351;7578.734744283924;7590.354819840155;7634.50375725576;7363.521371110861;7381.409487241115;7382.114146302478;7515.511491753018;7576.3938884887975;8003.959980200295;8031.9960004999375;8065.556394398364;8074.677012919417;8074.878876816735;7205;7243.130824301117;7287.672054757442;7298.124225093023;7309.561426314438;7356.558606483628;7386.482946193715;7423.888641670272;7432.21961694036;7448.292412220831 09:57:38 INFO - PROCESS | 1998 | |4;prototype.html;331.30591410993975;331.49778086199404;338.7391708384545;340.48246665069104;343.25342380322775;6342.302346741854;6381.7766378176775;6413.743450261995;6431.324710905358;6448.677566121699;5327.813526526563;6147;6362;6362.395572420624;6380.553361264713;4560.201964656172;4570.063137056895;4657.86026419208;4709.434867815867;4754.524547545244;4760.4525479569775;4765.499622539635;4770.594499467549;4779.235322348425;4809.951900480985;4078.286299897521;4522.909541809178;4702.482726900038;4732.692374995631;4742.05158968206;4462.509123996374;4506.391637128979;4688.624910007191;4709.105269998689;4713.552212539803;4064.1465292288653;4074.348104303297;4078.9796051019557;4081.9387709184384;4100.0569868930315;6145.661988590626;6150.2927163376335;6157.0456579230295;6178.567500274982;6202.286737025254;5648.3222013358245;5650.830475085754;5657.462541058593;5663.207150998864;5669.574781891333;5492.258545096441;6840.555363901331;6854.862902741917;6864.622445765481;6874.072000279948;3773.5094437723196;4344.761038142901;4545.886352841172;4549.044700612875;4572.039871626929;5814.709264536757;7141.642917854138;7280.927190728078;7289.198188199294;7294.343509084155;4615.215413379734;4626.560476754703;4627.143978363946;4635.3742244797195;4637.675362724644;4675.485696573374;4691.155591993405;4693.413323334583;4703.95296047042;4705.364775755229;5557.166425036277;5557.499825015768;5561.304836895388;5570.944290557123;5581.10702287632;5412.972935135299;6384.553081284358;6877.140357455318;6936.895946560806;6952.130983627047;1482.3625840888521;1612.31476622435;1624.7969003874516;1624.9431269905497;1626.6421387294777;967.970960871175;997.7854761226371;1019.0624625344701;1021.4280003198232;1046.4192373232777;6231.158793562901;6299.464545513682;6341.365863413622;6350.523710721714;6465;7225.494215404976;7238.638068096574;7245.7826265212125;7252.311030452099;7271.854562908818;6407.807765767035;6423.13287706163;6423.293437721847;6424.550281480345;6440.355964403522;3753.6621704046765;3817.6182381762155;3819.598942111094;3823.2926908522013;3830.942535861964;6409.294977552466;6848.794536163923;7532.887006694904;7578.355839753573;7584.658690358947;7382.741604043833;7444.553326800409;7452.739154129579;7453.180150183475;7474;7518.661660225303;7523.247675232433;7525.134609519756;7530.698772049057;7534.171241163577;6982;7233.1681856587165;7234.02340684022;7245.166805817187;7313.963430182815;6251.281102673251;6776.728930842711;7082.256363081802;7092.078029856086;7107.644617769194;6306.716197771019;7284.562926224444;7407.333339999211;7506.774796756106;7535;4632.884177895445;4650.906981860277;4652.627789776878;4662.93005604923;4666.486686464554;6233.690924905821;6997.335253150943;7208.423326133792;7223.819404514929;7253;6692.83267918306;6884.173899132136;6952.965235173791;6958.373746362851;6985.615791131537;6892.793216203522;6932.0988271524375;6933.2026816916705;6938.8612227756175;6956;6731.32686731323;6766.391024807791;6775;6777.762778302736;6785.796426107225;6652.73389064432;6938.757143499954;6943;6952.721891124298;6955.930440695528;5636.85907852307;6558.442532384801;6562.179727534059;6762.49281303914;6842.7605033823575 09:57:38 INFO - PROCESS | 1998 | |5;yui.html;678.3386198456506;685.9862802743945;745.0835472368987;764.7514557768719;766.097941914948;15430.614234644125;15673.45142919998;15798.763018554702;15806.920965395171;15815.762763558556;14551.199684017374;16898.986060836327;17140.914295428538;17153.31386744532;17167.9699218047;804.5494523067072;816.1267443835098;972.7519482531944;990.1435258501416;995.6564985080136;990.2078337330107;994.8557459168417;995.4873240281261;997.2670087485692;1001.4542074569341;1048.0829274384914;1049.8477720730489;1050.4327663061977;1051.337657275919;1054.7943151085542;842.1788755962948;859.7850537365658;863.0980625246632;1031.705963800321;1054.910332621728;779.664744160011;780.7189411811743;782.8629989751771;783.3811289081618;789.6564994227472;15963.281652325673;16076;16082.954607950564;16088.356465741357;16096;13362.665933351647;13465.39405726735;13548.119372240872;13631.386587603582;13679.384427700777;10215.591376344937;10248.897511024943;10442.79114417715;10522.684319470429;10699.465026748709;3551.7158627310014;3562.964370356315;3614.9277014459562;3687.059799751073;3704.907377315562;83495.16504834934;83782;83946;86320.56839715825;87084;9822.803543929082;9917.603295868228;10029.247806414549;10504.684859454228;10856.294340867818;24075.638865417033;24088.638670419958;24096.15663451771;24097.397565061015;24107.879460602584;7750.379969602531;7834.451588388872;7904.802379940547;8063.354931605458;8113.797155071171;4288.785560721952;4292.678049146327;4298.720583162147;4333.43665323506;4351.673624478113;3825.9234815304094;3883.10688541637;3883.2427676602792;3884;3896.0259935016247;674.1169068520254;676.6853413162903;680.5882441123049;694.8019814352886;709.1313141401741;488.25297295138495;543.4836904940322;566.3062748133503;602.7920367473215;633.1231244726088;8162.6734930602115;8593.484390936439;8687.435316704394;9011.549422528979;9029.593668284942;608.9100510086984;615.8521954730833;618.9164462797464;625.5339771869895;633.7401665317198;3808.6000969898314;4025.7383270087353;4029.0531725044593;4048.8380464781667;4207.705460617789;90099.19801604062;90853.54573227091;90992.54503727572;92431.07568924359;103932;80902;81413;81740;82476;82854.17145828585;70579;76015;76566.61716691381;76569.617151915;77267.84098238531;8656.697015604424;8703.651853925772;8866.202041816268;9007.504587247502;9055.637774488947;51469;51680;51904.740476297375;53865.461345387615;55355;70712.29287707058;71213.28786712067;72643.91034134387;73699;73833.26166738478;10990.17573681993;11168.441577921234;11393.316401015965;11463.598774043203;11647.941760291144;11899.940500297444;11958.92473011433;12069.517219311128;12209.267443953391;12701.682457938625;9642.903570964201;10215.387076775418;11324;11391.544338226378;11777.914165531338;11726.355050472306;11758.647240582795;11969.042476602026;12042.157049006486;12097.516099356279;11231.71920701989;11429.914158154961;11577;11641.243319184226;11809.468573914022;9748.561314740711;9920;10028.348157369748;10168.389896606228;10244.897551024693;9833.11501964798;10073.899261007296;10098.495075246356;10143.949280253551;10231.846522302023 09:57:38 INFO - PROCESS | 1998 | __end_tp_report 09:57:38 INFO - PROCESS | 1998 | __start_cc_report 09:57:38 INFO - PROCESS | 1998 | _x_x_mozilla_cycle_collect,999 09:57:38 INFO - PROCESS | 1998 | __end_cc_report 09:57:38 INFO - PROCESS | 1998 | __startTimestamp1461171458444__endTimestamp 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | ------- Summary: start ------- 09:57:38 INFO - PROCESS | 1998 | Number of tests: 6 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | [#0] dojo.html Cycles:165 Average:5688.16 Median:5916.47 stddev:652.03 (11.0%) stddev-sans-first:622.75 09:57:38 INFO - PROCESS | 1998 | Values: 3144.7 3460.6 3474.0 3493.9 3529.7 5683.1 5685.1 5689.5 5704.2 5704.7 5666.0 5693.1 5724.9 5736.1 5744.3 5072.6 5103.2 5114.6 5165.1 5167.5 5192.3 5204.0 5206.4 5206.5 5211.8 5136.8 5147.8 5154.4 5172.9 5185.5 3988.2 5102.4 5172.1 5189.9 5218.3 4958.0 5125.6 5145.6 5170.8 5176.1 5568.2 5597.1 5604.5 5639.6 5655.4 6109.7 6257.3 6260.8 6266.6 6267.7 6141.8 6157.3 6166.1 6185.3 6191.5 5818.3 5837.6 5869.3 5872.5 5878.6 6155.5 6164.8 6211.2 6216.9 6223.8 6166.9 6170.5 6184.8 6191.0 6192.0 5139.8 6221.9 6223.2 6223.6 6234.9 5375.3 5883.9 6089.1 6242.6 6251.4 6179.1 6233.7 6234.4 6264.4 6268.2 4856.4 4860.0 4862.5 4879.2 4898.2 3843.7 4323.0 4425.5 4438.6 4440.5 5589.4 5623.0 5647.5 5657.5 5678.0 6208.5 6211.7 6216.1 6231.2 6250.6 5430.6 5568.7 5639.9 5658.5 5662.2 5299.5 5329.3 5337.7 5348.9 5358.7 6209.6 6249.9 6258.3 6285.7 6289.3 6153.0 6155.1 6205.6 6215.6 6226.3 6234.1 6239.4 6252.1 6258.0 6274.7 6190.2 6194.8 6201.7 6209.0 6212.5 5594.7 6088.5 6142.8 6168.6 6194.0 5581.9 6041.8 6056.6 6057.2 6074.7 5982.1 6079.7 6082.1 6088.6 6099.5 5850.1 5916.5 5930.2 5954.0 5999.9 6074.8 6077.1 6084.4 6098.8 6113.5 5837.4 5921.2 5954.0 5994.6 6007.0 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | [#1] ext.html Cycles:180 Average:61824.76 Median:37530.50 stddev:124389.18 (331.4%) stddev-sans-first:124652.93 09:57:38 INFO - PROCESS | 1998 | Values: 506.8 589.8 598.1 669.1 669.5 26134.3 39872.0 40095.0 40278.8 40293.2 42643.1 43224.9 43276.0 43300.7 43308.1 9657.2 9867.4 9869.2 9887.1 9889.2 9696.8 9836.3 9838.8 9840.4 9849.0 9812.0 9862.8 9889.1 9889.7 9891.0 9787.8 9815.9 9855.3 9857.2 9858.6 9659.6 9823.9 9833.1 9840.6 9862.5 41440.6 41974.8 41988.0 41993.8 41996.8 3278.3 3406.9 3441.8 3573.9 3806.0 28738.3 29348.4 29699.3 29850.7 29913.9 2304.4 2700.8 2763.1 2804.8 2838.5 470672.0 472205.0 472219.0 472962.0 473023.6 63556.0 65331.0 65352.7 65488.7 65536.0 69256.7 76389.0 77456.6 77665.0 77980.6 15130.7 17829.3 18030.4 18059.0 18069.1 13657.7 13760.5 13774.4 13821.3 13832.9 22144.9 22454.3 22478.4 22490.4 22494.2 8084.8 9333.7 9336.9 9522.0 9583.7 860.9 942.5 955.4 1063.9 1119.4 29079.6 30159.5 30278.4 30430.5 30894.9 1101.7 1136.8 1209.7 1211.1 1248.5 12423.0 15785.8 16002.7 16099.4 16442.5 549103.0 552735.0 556599.0 556878.0 557110.0 61923.4 62522.1 62609.7 62720.0 62875.7 1427.0 1556.6 1558.3 1586.5 1606.0 28889.4 30007.8 30030.4 30177.4 30688.8 48072.0 48427.8 48941.3 48960.8 49068.8 353976.0 355529.4 356027.0 356369.0 358052.0 29461.6 34190.1 34555.5 35029.8 35074.5 42121.6 42556.7 42657.8 44386.3 44400.3 33773.6 34695.6 35489.5 35587.0 36409.0 31769.7 35769.8 36418.0 36679.8 37575.0 36762.6 36959.0 37110.4 37384.0 37486.0 31919.8 32870.0 32898.8 32918.5 33092.7 21212.7 22425.0 24345.8 24723.9 25196.4 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | [#2] jquery.html Cycles:180 Average:29643.37 Median:12898.99 stddev:78488.54 (608.5%) stddev-sans-first:78677.98 09:57:38 INFO - PROCESS | 1998 | Values: 383.3 384.4 385.2 390.6 392.3 19608.7 20866.0 21222.4 21533.8 21547.6 12124.2 12482.3 20851.5 21695.3 21771.2 5311.9 5459.9 5499.0 5502.5 5504.0 4139.7 4330.0 5857.8 6090.3 6092.8 6037.4 6084.6 6098.2 6098.7 6106.8 5937.7 6066.1 6067.9 6124.8 6129.5 4020.5 5557.7 5807.9 5811.1 5820.4 13787.0 13790.4 13881.2 23353.8 27858.0 2444.2 2661.4 2667.7 2853.5 3154.4 15733.7 16068.3 16556.9 17120.6 17169.4 1720.1 1910.9 1934.0 2014.1 2018.1 75822.0 76378.9 76485.6 111089.9 127680.0 17685.9 18707.1 19857.0 20530.8 20609.9 25269.0 31015.5 31581.8 31591.8 31755.5 11053.8 11527.5 11530.5 11530.6 11656.8 7959.7 9654.8 9710.4 9761.1 9767.9 8299.8 8489.7 8924.2 9013.3 9161.0 3024.5 3040.3 3581.9 3582.7 3697.5 952.0 1030.4 1051.8 1058.9 1110.5 17340.9 19303.7 19767.9 20265.6 20978.5 914.3 954.5 1008.7 1037.3 1038.0 11107.7 11331.8 11344.9 11355.3 11397.4 266074.7 266303.0 278948.0 635684.0 687106.0 70246.6 72203.6 115545.3 115651.0 121639.8 1037.7 1070.9 1076.0 1076.8 1091.1 18631.1 18684.7 19039.0 19061.9 19319.2 50620.0 51287.2 51747.0 52362.4 53264.0 74249.6 75393.0 77073.0 77154.0 78390.0 8216.9 8343.0 8345.3 8500.2 8621.4 12486.1 12802.4 13473.9 13656.4 13807.9 11334.9 11472.6 11655.1 11659.0 11671.6 10700.4 11171.3 11252.5 11525.1 11635.7 11487.3 11784.2 11930.3 12087.9 12150.7 8390.4 8462.6 8593.1 8599.4 8839.6 11516.0 11669.5 11845.7 11902.3 11912.7 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | [#3] mootools.html Cycles:180 Average:6196.61 Median:526.59 stddev:3286.61 (624.1%) stddev-sans-first:3276.35 09:57:38 INFO - PROCESS | 1998 | Values: 1436.8 1456.2 1501.5 1584.3 1778.0 8178.5 8838.0 9035.3 9036.3 9087.0 8701.0 8927.9 8931.5 8935.3 8974.7 2183.5 2273.5 2503.6 2549.9 2561.3 2396.8 2572.6 2581.7 2583.8 2585.5 2167.7 2307.6 2564.7 2565.9 2566.9 2505.3 2525.1 2534.2 2542.7 2552.6 2491.1 2503.7 2519.4 2563.2 2567.2 9022.7 9107.0 9112.0 9151.4 9181.7 5039.8 5562.1 5756.2 5764.1 5779.2 3665.7 3674.9 3764.3 3797.8 3809.3 1054.5 1064.9 1069.2 1083.1 1116.9 10822.7 11911.9 12299.9 12317.6 12378.4 4953.4 4959.8 5041.1 5045.3 5069.2 10408.4 10418.8 10421.8 10436.2 10436.5 5655.3 5664.4 5664.9 5667.7 5674.5 4544.3 4710.5 4907.2 5024.1 5082.2 4036.0 4088.4 4104.3 4143.4 4151.3 1350.0 1358.9 1359.4 1359.5 1367.0 1264.4 1382.7 1407.8 1410.2 1428.9 8321.2 8448.9 8456.4 8465.7 8477.6 522.9 530.3 530.7 531.1 535.2 6278.9 6293.1 6334.5 6337.6 6344.4 10637.8 11041.9 11159.6 11166.8 11217.7 10567.6 10913.3 10921.6 10938.3 10997.0 8517.8 8566.6 8578.8 8585.9 8628.9 8013.5 8058.4 8084.7 8353.5 8365.2 7776.6 9730.1 9798.0 9832.9 9959.2 9865.2 10038.2 10181.0 10249.7 10271.0 6805.4 6844.5 7657.7 7737.7 7785.3 8480.0 8496.0 8533.9 8535.7 8539.3 7395.4 7511.3 7578.7 7590.4 7634.5 7363.5 7381.4 7382.1 7515.5 7576.4 8004.0 8032.0 8065.6 8074.7 8074.9 7205.0 7243.1 7287.7 7298.1 7309.6 7356.6 7386.5 7423.9 7432.2 7448.3 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | [#4] prototype.html Cycles:180 Average:5614.17 Median:6346.41 stddev:1790.53 (28.2%) stddev-sans-first:1751.09 09:57:38 INFO - PROCESS | 1998 | Values: 331.3 331.5 338.7 340.5 343.3 6342.3 6381.8 6413.7 6431.3 6448.7 5327.8 6147.0 6362.0 6362.4 6380.6 4560.2 4570.1 4657.9 4709.4 4754.5 4760.5 4765.5 4770.6 4779.2 4810.0 4078.3 4522.9 4702.5 4732.7 4742.1 4462.5 4506.4 4688.6 4709.1 4713.6 4064.1 4074.3 4079.0 4081.9 4100.1 6145.7 6150.3 6157.0 6178.6 6202.3 5648.3 5650.8 5657.5 5663.2 5669.6 5492.3 6840.6 6854.9 6864.6 6874.1 3773.5 4344.8 4545.9 4549.0 4572.0 5814.7 7141.6 7280.9 7289.2 7294.3 4615.2 4626.6 4627.1 4635.4 4637.7 4675.5 4691.2 4693.4 4704.0 4705.4 5557.2 5557.5 5561.3 5570.9 5581.1 5413.0 6384.6 6877.1 6936.9 6952.1 1482.4 1612.3 1624.8 1624.9 1626.6 968.0 997.8 1019.1 1021.4 1046.4 6231.2 6299.5 6341.4 6350.5 6465.0 7225.5 7238.6 7245.8 7252.3 7271.9 6407.8 6423.1 6423.3 6424.6 6440.4 3753.7 3817.6 3819.6 3823.3 3830.9 6409.3 6848.8 7532.9 7578.4 7584.7 7382.7 7444.6 7452.7 7453.2 7474.0 7518.7 7523.2 7525.1 7530.7 7534.2 6982.0 7233.2 7234.0 7245.2 7314.0 6251.3 6776.7 7082.3 7092.1 7107.6 6306.7 7284.6 7407.3 7506.8 7535.0 4632.9 4650.9 4652.6 4662.9 4666.5 6233.7 6997.3 7208.4 7223.8 7253.0 6692.8 6884.2 6953.0 6958.4 6985.6 6892.8 6932.1 6933.2 6938.9 6956.0 6731.3 6766.4 6775.0 6777.8 6785.8 6652.7 6938.8 6943.0 6952.7 6955.9 5636.9 6558.4 6562.2 6762.5 6842.8 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - PROCESS | 1998 | [#5] yui.html Cycles:180 Average:19286.09 Median:4316.08 stddev:26937.85 (624.1%) stddev-sans-first:26977.18 09:57:38 INFO - PROCESS | 1998 | Values: 678.3 686.0 745.1 764.8 766.1 15430.6 15673.5 15798.8 15806.9 15815.8 14551.2 16899.0 17140.9 17153.3 17168.0 804.5 816.1 972.8 990.1 995.7 990.2 994.9 995.5 997.3 1001.5 1048.1 1049.8 1050.4 1051.3 1054.8 842.2 859.8 863.1 1031.7 1054.9 779.7 780.7 782.9 783.4 789.7 15963.3 16076.0 16083.0 16088.4 16096.0 13362.7 13465.4 13548.1 13631.4 13679.4 10215.6 10248.9 10442.8 10522.7 10699.5 3551.7 3563.0 3614.9 3687.1 3704.9 83495.2 83782.0 83946.0 86320.6 87084.0 9822.8 9917.6 10029.2 10504.7 10856.3 24075.6 24088.6 24096.2 24097.4 24107.9 7750.4 7834.5 7904.8 8063.4 8113.8 4288.8 4292.7 4298.7 4333.4 4351.7 3825.9 3883.1 3883.2 3884.0 3896.0 674.1 676.7 680.6 694.8 709.1 488.3 543.5 566.3 602.8 633.1 8162.7 8593.5 8687.4 9011.5 9029.6 608.9 615.9 618.9 625.5 633.7 3808.6 4025.7 4029.1 4048.8 4207.7 90099.2 90853.5 90992.5 92431.1 103932.0 80902.0 81413.0 81740.0 82476.0 82854.2 70579.0 76015.0 76566.6 76569.6 77267.8 8656.7 8703.7 8866.2 9007.5 9055.6 51469.0 51680.0 51904.7 53865.5 55355.0 70712.3 71213.3 72643.9 73699.0 73833.3 10990.2 11168.4 11393.3 11463.6 11647.9 11899.9 11958.9 12069.5 12209.3 12701.7 9642.9 10215.4 11324.0 11391.5 11777.9 11726.4 11758.6 11969.0 12042.2 12097.5 11231.7 11429.9 11577.0 11641.2 11809.5 9748.6 9920.0 10028.3 10168.4 10244.9 9833.1 10073.9 10098.5 10143.9 10231.8 09:57:38 INFO - PROCESS | 1998 | -------- Summary: end -------- 09:57:38 INFO - PROCESS | 1998 | 09:57:38 INFO - TEST-INFO | 1998: exit 0 09:57:38 INFO - TEST-OK | dromaeo_css | took 1112308ms 09:57:38 INFO - TEST-START | kraken 09:57:38 INFO - operating with platform_type : mac_ 09:57:38 INFO - Initialising browser for kraken test... 09:57:39 INFO - TEST-INFO | started process 2001 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpvSxQja/profile http://localhost:49656/getInfo.html) 09:57:41 INFO - PROCESS | 2001 | __metrics Screen width/height:1600/1200 09:57:41 INFO - PROCESS | 2001 | colorDepth:24 09:57:41 INFO - PROCESS | 2001 | Browser inner width/height: 1024/689 09:57:41 INFO - PROCESS | 2001 | __metrics 09:57:41 INFO - PROCESS | 2001 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:57:41 INFO - PROCESS | 2001 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:41 INFO - TEST-INFO | 2001: exit 0 09:57:41 INFO - Browser initialized. 09:57:41 INFO - Running cycle 1/1 for kraken test... 09:57:41 INFO - TEST-INFO | started process 2003 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpvSxQja/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:53 INFO - PROCESS | 2003 | RSS: Main: 207843328 09:57:53 INFO - PROCESS | 2003 | 09:57:56 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:56 INFO - PROCESS | 2003 | RSS: Main: 262877184 09:57:56 INFO - PROCESS | 2003 | 09:57:59 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-dft) 09:58:00 INFO - PROCESS | 2003 | RSS: Main: 273186816 09:58:00 INFO - PROCESS | 2003 | 09:58:03 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-fft) 09:58:03 INFO - PROCESS | 2003 | RSS: Main: 262402048 09:58:03 INFO - PROCESS | 2003 | 09:58:05 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-oscillator) 09:58:06 INFO - PROCESS | 2003 | RSS: Main: 263725056 09:58:06 INFO - PROCESS | 2003 | 09:58:08 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49656/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:09 INFO - PROCESS | 2003 | RSS: Main: 264552448 09:58:09 INFO - PROCESS | 2003 | 09:58:12 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49656/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:12 INFO - PROCESS | 2003 | RSS: Main: 264507392 09:58:12 INFO - PROCESS | 2003 | 09:58:15 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49656/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:16 INFO - PROCESS | 2003 | RSS: Main: 264564736 09:58:16 INFO - PROCESS | 2003 | 09:58:18 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49656/tests/kraken/driver.html?testName=json-parse-financial) 09:58:19 INFO - PROCESS | 2003 | RSS: Main: 264912896 09:58:19 INFO - PROCESS | 2003 | 09:58:20 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49656/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:21 INFO - PROCESS | 2003 | RSS: Main: 298729472 09:58:21 INFO - PROCESS | 2003 | 09:58:23 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:23 INFO - PROCESS | 2003 | RSS: Main: 299556864 09:58:23 INFO - PROCESS | 2003 | 09:58:26 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:26 INFO - PROCESS | 2003 | RSS: Main: 305704960 09:58:26 INFO - PROCESS | 2003 | 09:58:29 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:29 INFO - PROCESS | 2003 | RSS: Main: 307306496 09:58:29 INFO - PROCESS | 2003 | 09:58:32 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:32 INFO - PROCESS | 2003 | RSS: Main: 307593216 09:58:32 INFO - PROCESS | 2003 | 09:58:34 INFO - PROCESS | 2003 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49656/tests/kraken/driver.html?testName=ai-astar) 09:58:34 INFO - PROCESS | 2003 | RSS: Main: 369582080 09:58:34 INFO - PROCESS | 2003 | 09:58:34 INFO - PROCESS | 2003 | __start_tp_report 09:58:34 INFO - PROCESS | 2003 | _x_x_mozilla_page_load 09:58:34 INFO - PROCESS | 2003 | _x_x_mozilla_page_load_details 09:58:34 INFO - PROCESS | 2003 | |i|pagename|runs| 09:58:34 INFO - PROCESS | 2003 | |0;ai-astar;113;110;112;112;112;112;119;113;110;110 09:58:34 INFO - PROCESS | 2003 | |1;audio-beat-detection;149;119;154;163;144;170;143;148;143;114 09:58:34 INFO - PROCESS | 2003 | |2;audio-dft;183;185;185;185;185;185;184;184;183;183 09:58:34 INFO - PROCESS | 2003 | |3;audio-fft;70;70;80;119;56;57;57;60;119;56 09:58:34 INFO - PROCESS | 2003 | |4;audio-oscillator;92;97;130;79;103;115;115;76;106;164 09:58:34 INFO - PROCESS | 2003 | |5;imaging-gaussian-blur;92;88;91;86;92;89;91;91;91;91 09:58:34 INFO - PROCESS | 2003 | |6;imaging-darkroom;95;95;95;95;95;94;94;96;95;94 09:58:34 INFO - PROCESS | 2003 | |7;imaging-desaturate;87;88;86;85;91;133;86;86;85;86 09:58:34 INFO - PROCESS | 2003 | |8;json-parse-financial;56;59;58;58;57;57;57;68;54;52 09:58:34 INFO - PROCESS | 2003 | |9;json-stringify-tinderbox;61;64;74;67;72;66;65;67;64;65 09:58:34 INFO - PROCESS | 2003 | |10;stanford-crypto-aes;81;80;92;105;103;80;80;79;80;84 09:58:34 INFO - PROCESS | 2003 | |11;stanford-crypto-ccm;132;129;103;126;131;126;127;115;120;130 09:58:34 INFO - PROCESS | 2003 | |12;stanford-crypto-pbkdf2;156;170;135;135;134;125;134;133;132;131 09:58:34 INFO - PROCESS | 2003 | |13;stanford-crypto-sha256-iterative;57;82;93;62;56;90;92;94;91;57 09:58:34 INFO - PROCESS | 2003 | __end_tp_report 09:58:34 INFO - PROCESS | 2003 | __start_cc_report 09:58:34 INFO - PROCESS | 2003 | _x_x_mozilla_cycle_collect,1268 09:58:34 INFO - PROCESS | 2003 | __end_cc_report 09:58:34 INFO - PROCESS | 2003 | __startTimestamp1461171514991__endTimestamp 09:58:34 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | ------- Summary: start ------- 09:58:35 INFO - PROCESS | 2003 | Number of tests: 14 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.00 stddev:2.63 (2.3%) stddev-sans-first:2.77 09:58:35 INFO - PROCESS | 2003 | Values: 113.0 110.0 112.0 112.0 112.0 112.0 119.0 113.0 110.0 110.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:148.50 stddev:17.32 (11.7%) stddev-sans-first:18.30 09:58:35 INFO - PROCESS | 2003 | Values: 149.0 119.0 154.0 163.0 144.0 170.0 143.0 148.0 143.0 114.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:0.92 (0.5%) stddev-sans-first:0.87 09:58:35 INFO - PROCESS | 2003 | Values: 183.0 185.0 185.0 185.0 185.0 185.0 184.0 184.0 183.0 183.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#3] audio-fft Cycles:10 Average:74.40 Median:58.50 stddev:24.81 (42.4%) stddev-sans-first:26.26 09:58:35 INFO - PROCESS | 2003 | Values: 70.0 70.0 80.0 119.0 56.0 57.0 57.0 60.0 119.0 56.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#4] audio-oscillator Cycles:10 Average:107.70 Median:120.00 stddev:25.79 (21.5%) stddev-sans-first:26.73 09:58:35 INFO - PROCESS | 2003 | Values: 92.0 97.0 130.0 79.0 103.0 115.0 115.0 76.0 106.0 164.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:91.00 stddev:1.93 (2.1%) stddev-sans-first:1.94 09:58:35 INFO - PROCESS | 2003 | Values: 92.0 88.0 91.0 86.0 92.0 89.0 91.0 91.0 91.0 91.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:58:35 INFO - PROCESS | 2003 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 96.0 95.0 94.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:86.00 stddev:14.76 (17.2%) stddev-sans-first:15.57 09:58:35 INFO - PROCESS | 2003 | Values: 87.0 88.0 86.0 85.0 91.0 133.0 86.0 86.0 85.0 86.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:4.20 (7.3%) stddev-sans-first:4.41 09:58:35 INFO - PROCESS | 2003 | Values: 56.0 59.0 58.0 58.0 57.0 57.0 57.0 68.0 54.0 52.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:66.50 stddev:3.87 (5.8%) stddev-sans-first:3.55 09:58:35 INFO - PROCESS | 2003 | Values: 61.0 64.0 74.0 67.0 72.0 66.0 65.0 67.0 64.0 65.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:80.00 stddev:10.04 (12.5%) stddev-sans-first:10.45 09:58:35 INFO - PROCESS | 2003 | Values: 81.0 80.0 92.0 105.0 103.0 80.0 80.0 79.0 80.0 84.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:128.00 stddev:9.00 (7.0%) stddev-sans-first:9.06 09:58:35 INFO - PROCESS | 2003 | Values: 132.0 129.0 103.0 126.0 131.0 126.0 127.0 115.0 120.0 130.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.50 Median:134.50 stddev:13.64 (10.1%) stddev-sans-first:12.91 09:58:35 INFO - PROCESS | 2003 | Values: 156.0 170.0 135.0 135.0 134.0 125.0 134.0 133.0 132.0 131.0 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - PROCESS | 2003 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:90.50 stddev:17.08 (18.9%) stddev-sans-first:16.44 09:58:35 INFO - PROCESS | 2003 | Values: 57.0 82.0 93.0 62.0 56.0 90.0 92.0 94.0 91.0 57.0 09:58:35 INFO - PROCESS | 2003 | -------- Summary: end -------- 09:58:35 INFO - PROCESS | 2003 | 09:58:35 INFO - TEST-INFO | 2003: exit 0 09:58:35 INFO - TEST-OK | kraken | took 56851ms 09:58:35 INFO - SUITE-END | took 1169s 09:58:36 INFO - Completed test suite (00:19:29) 09:58:36 INFO - javascript benchmark 09:58:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3144.7012533809293, 3460.6366331535187, 3474.0099071764535, 3493.94759078614, 3529.699975502084, 5683.119116536944, 5685.090385538315, 5689.516391106756, 5704.201411802346, 5704.686242256675, 5666.0, 5693.060644993581, 5724.942750572493, 5736.053551164053, 5744.339400968879, 5072.568831649314, 5103.157978933472, 5114.590832733391, 5165.096108181072, 5167.48325167484, 5192.324997750287, 5203.959208158364, 5206.401263854648, 5206.531412172903, 5211.843644690665, 5136.845894623168, 5147.794088236466, 5154.355705536808, 5172.896542069157, 5185.533302002819, 3988.202359528106, 5102.36220472441, 5172.069027575035, 5189.948100519003, 5218.321618189649, 4958.0, 5125.641205115643, 5145.562627176694, 5170.819021334273, 5176.0683077046115, 5568.16477528374, 5597.104463285855, 5604.523615492688, 5639.605227634026, 5655.434456554352, 6109.677964501043, 6257.342978987232, 6260.812175634738, 6266.592671476339, 6267.655278959655, 6141.754329826802, 6157.261128664544, 6166.07508873668, 6185.319614842364, 6191.473724733403, 5818.294493060705, 5837.6205546640185, 5869.295684517842, 5872.530197584183, 5878.617889837147, 6155.507559395238, 6164.753409863645, 6211.192544969125, 6216.906746398808, 6223.813285601439, 6166.907496387557, 6170.5372097092895, 6184.845378865474, 6191.0, 6192.0, 5139.794408223629, 6221.906671399933, 6223.159873417031, 6223.626582405071, 6234.875302494015, 5375.3012108425655, 5883.852903677443, 6089.086637004485, 6242.563020588606, 6251.4061164189325, 6179.104029915637, 6233.688315584293, 6234.438900498886, 6264.404881536247, 6268.153799237136, 4856.441509226484, 4859.95140048595, 4862.489438608965, 4879.243717223836, 4898.191798353232, 3843.673287770571, 4322.978385108055, 4425.468943726773, 4438.60052595268, 4440.489343725513, 5589.35722391922, 5623.0159722048475, 5647.491725744702, 5657.4908258256955, 5678.0, 6208.4722798562625, 6211.720472578745, 6216.129741836146, 6231.22109736283, 6250.624962502265, 5430.56555475561, 5568.7215639217875, 5639.887202255932, 5658.462446067617, 5662.150677398341, 5299.5230429261555, 5329.25390445338, 5337.653052551572, 5348.8662783429945, 5358.651687640291, 6209.627422354673, 6249.937500625027, 6258.311585725566, 6285.748570057147, 6289.27673317556, 6153.015517517355, 6155.076738489262, 6205.627662340274, 6215.627062376272, 6226.25284965807, 6234.064890266496, 6239.438450539292, 6252.062190671436, 6258.0, 6274.686265686606, 6190.1952746142715, 6194.783182588588, 6201.658908759881, 6208.9689551551955, 6212.502999760099, 5594.720263986866, 6088.543359248163, 6142.775043102229, 6168.629882207082, 6193.969030154821, 5581.944180558305, 6041.758329666764, 6056.636601803906, 6057.242844644419, 6074.7266373012435, 5982.102684597171, 6079.726412311545, 6082.148499210202, 6088.573799833969, 6099.481544068626, 5850.0932355484965, 5916.467517923407, 5930.199423077829, 5954.047352423591, 5999.91000135007, 6074.817755467343, 6077.149199112039, 6084.39156084388, 6098.75604975796, 6113.5414843886, 5837.445442682938, 5921.2006379138265, 5953.987822070172, 5994.55040871945, 6007.0], "unit": "score", "name": "dojo.html", "value": 5646.734011518693}, {"lowerIsBetter": false, "replicates": [506.83781190019164, 589.8407429993902, 598.0520874414053, 669.0833558025506, 669.5112567825489, 26134.346641333977, 39872.0, 40095.0, 40278.798606007, 40293.19413611726, 42643.14713705732, 43224.91937701559, 43276.0, 43300.7009789706, 43308.133837323236, 9657.179139773129, 9867.407955522656, 9869.161121304662, 9887.11016008559, 9889.20886329092, 9696.757581060494, 9836.262280328969, 9838.754031149207, 9840.409575425498, 9849.0, 9811.95094024529, 9862.753431164243, 9889.10998010179, 9889.703308900744, 9891.0, 9787.755306117333, 9815.852762208573, 9855.310128291023, 9857.162141218005, 9858.556364963593, 9659.565319560637, 9823.852642210373, 9833.065858743408, 9840.557174927073, 9862.50687465631, 41440.58559414397, 41974.79012604917, 41988.0, 41993.79003104995, 41996.79001604972, 3278.2951665392056, 3406.931861362784, 3441.776284541522, 3573.9278216534935, 3806.0484878780303, 28738.281542961384, 29348.413031739245, 29699.257518561993, 29850.70149298501, 29913.85043074771, 2304.3547806614174, 2700.7832171999985, 2763.1434255380696, 2804.8457334846576, 2838.5316422790215, 470672.0, 472205.0, 472219.0, 472962.0, 473023.6348818268, 63556.04665930015, 65331.0, 65352.67323663351, 65488.67255663691, 65536.0, 69256.65371673109, 76389.0, 77456.61271693718, 77665.0, 77980.61009694915, 15130.697386052218, 17829.286828526714, 18030.36893708714, 18059.006754628357, 18069.096545172688, 13657.658558535917, 13760.518381856587, 13774.449022039005, 13821.308934553232, 13832.930835345758, 22144.88927555352, 22454.326370208593, 22478.438039049153, 22490.425670203254, 22494.21270255533, 8084.797880052928, 9333.73994510746, 9336.859947100798, 9522.0, 9583.712488625353, 860.9195459698038, 942.5428667096453, 955.4171955107379, 1063.8510608514814, 1119.4234968990977, 29079.56380654292, 30159.547606785476, 30278.394432111236, 30430.54354184689, 30894.918677846166, 1101.6694991502518, 1136.7669627726295, 1209.7096696792707, 1211.127987848765, 1248.5193200617646, 12423.0, 15785.842141578438, 16002.679946401007, 16099.436519721754, 16442.506724798277, 549103.0, 552735.0, 556599.0, 556878.0, 557110.0, 61923.38076619266, 62522.0621690675, 62609.68695156586, 62720.0, 62875.685621571596, 1426.9500567480088, 1556.5719427157621, 1558.3065535836445, 1586.4606033948512, 1606.044403579868, 28889.42221155523, 30007.849960750056, 30030.399392012474, 30177.396452071276, 30688.84655576708, 48072.0, 48427.75786121047, 48941.26588101253, 48960.75519622379, 49068.75465622649, 353976.0, 355529.44588047906, 356027.0, 356369.0, 358052.0, 29461.558076629295, 34190.14524636904, 34555.48166777551, 35029.824850875586, 35074.47388289127, 42121.578784211764, 42556.7232983011, 42657.78671106625, 44386.334204987594, 44400.333994990746, 33773.64905403855, 34695.571310008054, 35489.46765798567, 35587.0, 36409.0, 31769.682303177597, 35769.78071973535, 36418.0, 36679.81660091683, 37575.0, 36762.63237367592, 36959.0, 37110.44334335041, 37384.0, 37486.0, 31919.840400797846, 32870.0, 32898.83550582232, 32918.50622240716, 33092.669073308956, 21212.681809773174, 22425.0, 24345.756542435058, 24723.87638061798, 25196.370090747878], "unit": "score", "name": "ext.html", "value": 20178.636785319162}, {"lowerIsBetter": false, "replicates": [383.28517315207137, 384.4483166655849, 385.21224096722216, 390.64646494922107, 392.26841939782315, 19608.70586941195, 20866.0, 21222.363329100113, 21533.784662153394, 21547.569048619018, 12124.211926224789, 12482.25106493611, 20851.478713032182, 21695.34913952584, 21771.23800666977, 5311.893762124755, 5459.890802183954, 5499.010178167928, 5502.477264659851, 5504.036793561131, 4139.6895232857505, 4330.0, 5857.794977175799, 6090.299615544225, 6092.756289748405, 6037.426444487766, 6084.604500707462, 6098.207233059718, 6098.725557349908, 6106.847328816771, 5937.703114844262, 6066.059760737093, 6067.939320606782, 6124.816255512342, 6129.478994285447, 4020.517537895442, 5557.694326812024, 5807.912881306784, 5811.128330750379, 5820.35976042635, 13787.0, 13790.448382064706, 13881.236531990737, 23353.76646233533, 27858.0, 2444.2056331692092, 2661.42779302449, 2667.6932152802483, 2853.4863724529578, 3154.3848949454864, 15733.685326293524, 16068.276927538289, 16556.917215413967, 17120.57198570046, 17169.399071032454, 1720.0711615727491, 1910.8853468791917, 1933.951651208717, 2014.0835919656522, 2018.1322031526438, 75822.0, 76378.85431718528, 76485.61757191234, 111089.88910110877, 127680.0, 17685.911570442066, 18707.129287071457, 19857.0, 20530.79469205289, 20609.89695051515, 25269.0, 31015.534766978515, 31581.842090789858, 31591.84204079011, 31755.52366714501, 11053.778924421467, 11527.481263343001, 11530.538778448768, 11530.59642912494, 11656.825147622792, 7959.721409750631, 9654.75863103414, 9710.368826026286, 9761.121499064977, 9767.853482197772, 8299.83400332002, 8489.702860399857, 8924.196822286025, 9013.278937684969, 9161.0, 3024.485837407602, 3040.3311271520674, 3581.928361432757, 3582.731295152874, 3697.5193224880595, 952.0, 1030.3972176276961, 1051.8429727299908, 1058.8623478947688, 1110.5335758981248, 17340.91329543344, 19303.710444343345, 19767.90116049439, 20265.594688106154, 20978.47553811167, 914.3234006835028, 954.5179684259405, 1008.6671398438498, 1037.3361048928691, 1037.968860934173, 11107.666769996913, 11331.830022549668, 11344.943275283571, 11355.318680879009, 11397.430128493543, 266074.6696266545, 266303.0, 278948.0, 635684.0, 687106.0, 70246.64876675584, 72203.63898180476, 115545.33409994285, 115651.0, 121639.78360216462, 1037.7198156497702, 1070.8661417322835, 1075.9731006724894, 1076.7792602516465, 1091.0725882999386, 18631.068446577345, 18684.7197292038, 19038.952857592965, 19061.90469047646, 19319.227230910605, 50620.0, 51287.230691540404, 51747.0, 52362.42912712625, 53264.0, 74249.6287518559, 75393.0, 77073.0, 77154.0, 78390.0, 8216.917830821616, 8343.0, 8345.33237340999, 8500.234978851933, 8621.439606425562, 12486.063545234325, 12802.359882005676, 13473.865261347653, 13656.385462654005, 13807.930960345135, 11334.8665133486, 11472.648107138184, 11655.067594592581, 11659.008984236434, 11671.59149429766, 10700.357978521313, 11171.273867198606, 11252.549898003988, 11525.11872031992, 11635.709107272385, 11487.310761354345, 11784.175107742376, 11930.284182949052, 12087.912087912131, 12150.696232594255, 8390.412671112963, 8462.619182136941, 8593.140685931607, 8599.39804213699, 8839.646414143363, 11516.0, 11669.474873630877, 11845.703857403634, 11902.345371004678, 11912.702182445508], "unit": "score", "name": "jquery.html", "value": 10329.112351896361}, {"lowerIsBetter": false, "replicates": [1436.784482327651, 1456.213644631899, 1501.4969985055006, 1584.294988730015, 1777.964440711185, 8178.509289442638, 8838.0, 9035.322350823682, 9036.322275829307, 9087.045860184684, 8700.956495217531, 8927.866082008759, 8931.50876701781, 8935.329850261223, 8974.685885993975, 2183.5414562941723, 2273.54529094181, 2503.5869081601513, 2549.8725063746833, 2561.321249868777, 2396.7962723168553, 2572.6398304237423, 2581.6643836301255, 2583.806214533908, 2585.5346037713202, 2167.729033870766, 2307.5500277445904, 2564.6794150731157, 2565.9101931432406, 2566.948661026778, 2505.3360859372283, 2525.1035882261717, 2534.1637259704253, 2542.6694529711203, 2552.604346326313, 2491.1281051631786, 2503.7245902950663, 2519.395345117177, 2563.231030690804, 2567.191334729573, 9022.684206052756, 9107.043760405195, 9111.954440227757, 9151.405158664666, 9181.724548263563, 5039.77321020555, 5562.110062390038, 5756.1653560233535, 5764.106563482667, 5779.248697669318, 3665.7337703478483, 3674.9448758268645, 3764.2847858906994, 3797.8480860765812, 3809.29528037314, 1054.451685123739, 1064.8562444070028, 1069.176733914889, 1083.090204228452, 1116.8548088748491, 10822.67531974042, 11911.868372504598, 12299.938500307531, 12317.630471085882, 12378.442970066279, 4953.356063711694, 4959.801607935714, 5041.143005689041, 5045.344105266292, 5069.188929771219, 10408.427536485415, 10418.791624167474, 10421.843672344921, 10436.165106791439, 10436.530356133992, 5655.293088363955, 5664.405237450009, 5664.915026274609, 5667.688277144714, 5674.546036317085, 4544.318352247124, 4710.505396933273, 4907.2393778964315, 5024.120778863758, 5082.2122571001555, 4035.97966366835, 4088.4276201331836, 4104.343305071234, 4143.399207114951, 4151.294279972412, 1349.9527516536873, 1358.877701006914, 1359.4426285223012, 1359.5241665417025, 1367.0, 1264.3994102801096, 1382.7372799168127, 1407.7677183264852, 1410.2102822419479, 1428.9499867504796, 8321.16788321163, 8448.915510844936, 8456.365772567082, 8465.746027619181, 8477.576121193915, 522.9006488767122, 530.2841164427991, 530.6779559954712, 531.0722078853736, 535.2479765928877, 6278.874422511524, 6293.056041593798, 6334.5249106317215, 6337.588056776387, 6344.429001389804, 10637.787244255072, 11041.944790275997, 11159.609413670483, 11166.832497512543, 11217.71955701114, 10567.577296908192, 10913.290636108628, 10921.563137474572, 10938.343699378063, 10997.010269075821, 8517.787055323666, 8566.571671416405, 8578.78553036179, 8585.914140858637, 8628.870566941501, 8013.519188848688, 8058.435909486279, 8084.676612935652, 8353.457025293337, 8365.24712775853, 7776.611169441619, 9730.12428881404, 9798.020197980144, 9832.852507212541, 9959.203263739028, 9865.210783137476, 10038.247131465023, 10181.03280188381, 10249.74375640615, 10271.024252696281, 6805.421539169226, 6844.4524438043845, 7657.6554055066545, 7737.651805668871, 7785.338246249133, 8480.0, 8495.95752021236, 8533.87199192025, 8535.701250456452, 8539.274161696325, 7395.445341599509, 7511.286427789351, 7578.734744283924, 7590.354819840155, 7634.50375725576, 7363.521371110861, 7381.409487241115, 7382.114146302478, 7515.511491753018, 7576.3938884887975, 8003.959980200295, 8031.9960004999375, 8065.556394398364, 8074.677012919417, 8074.878876816735, 7205.0, 7243.130824301117, 7287.672054757442, 7298.124225093023, 7309.561426314438, 7356.558606483628, 7386.482946193715, 7423.888641670272, 7432.21961694036, 7448.292412220831], "unit": "score", "name": "mootools.html", "value": 4960.980174977535}, {"lowerIsBetter": false, "replicates": [331.30591410993975, 331.49778086199404, 338.7391708384545, 340.48246665069104, 343.25342380322775, 6342.302346741854, 6381.7766378176775, 6413.743450261995, 6431.324710905358, 6448.677566121699, 5327.813526526563, 6147.0, 6362.0, 6362.395572420624, 6380.553361264713, 4560.201964656172, 4570.063137056895, 4657.86026419208, 4709.434867815867, 4754.524547545244, 4760.4525479569775, 4765.499622539635, 4770.594499467549, 4779.235322348425, 4809.951900480985, 4078.286299897521, 4522.909541809178, 4702.482726900038, 4732.692374995631, 4742.05158968206, 4462.509123996374, 4506.391637128979, 4688.624910007191, 4709.105269998689, 4713.552212539803, 4064.1465292288653, 4074.348104303297, 4078.9796051019557, 4081.9387709184384, 4100.0569868930315, 6145.661988590626, 6150.2927163376335, 6157.0456579230295, 6178.567500274982, 6202.286737025254, 5648.3222013358245, 5650.830475085754, 5657.462541058593, 5663.207150998864, 5669.574781891333, 5492.258545096441, 6840.555363901331, 6854.862902741917, 6864.622445765481, 6874.072000279948, 3773.5094437723196, 4344.761038142901, 4545.886352841172, 4549.044700612875, 4572.039871626929, 5814.709264536757, 7141.642917854138, 7280.927190728078, 7289.198188199294, 7294.343509084155, 4615.215413379734, 4626.560476754703, 4627.143978363946, 4635.3742244797195, 4637.675362724644, 4675.485696573374, 4691.155591993405, 4693.413323334583, 4703.95296047042, 4705.364775755229, 5557.166425036277, 5557.499825015768, 5561.304836895388, 5570.944290557123, 5581.10702287632, 5412.972935135299, 6384.553081284358, 6877.140357455318, 6936.895946560806, 6952.130983627047, 1482.3625840888521, 1612.31476622435, 1624.7969003874516, 1624.9431269905497, 1626.6421387294777, 967.970960871175, 997.7854761226371, 1019.0624625344701, 1021.4280003198232, 1046.4192373232777, 6231.158793562901, 6299.464545513682, 6341.365863413622, 6350.523710721714, 6465.0, 7225.494215404976, 7238.638068096574, 7245.7826265212125, 7252.311030452099, 7271.854562908818, 6407.807765767035, 6423.13287706163, 6423.293437721847, 6424.550281480345, 6440.355964403522, 3753.6621704046765, 3817.6182381762155, 3819.598942111094, 3823.2926908522013, 3830.942535861964, 6409.294977552466, 6848.794536163923, 7532.887006694904, 7578.355839753573, 7584.658690358947, 7382.741604043833, 7444.553326800409, 7452.739154129579, 7453.180150183475, 7474.0, 7518.661660225303, 7523.247675232433, 7525.134609519756, 7530.698772049057, 7534.171241163577, 6982.0, 7233.1681856587165, 7234.02340684022, 7245.166805817187, 7313.963430182815, 6251.281102673251, 6776.728930842711, 7082.256363081802, 7092.078029856086, 7107.644617769194, 6306.716197771019, 7284.562926224444, 7407.333339999211, 7506.774796756106, 7535.0, 4632.884177895445, 4650.906981860277, 4652.627789776878, 4662.93005604923, 4666.486686464554, 6233.690924905821, 6997.335253150943, 7208.423326133792, 7223.819404514929, 7253.0, 6692.83267918306, 6884.173899132136, 6952.965235173791, 6958.373746362851, 6985.615791131537, 6892.793216203522, 6932.0988271524375, 6933.2026816916705, 6938.8612227756175, 6956.0, 6731.32686731323, 6766.391024807791, 6775.0, 6777.762778302736, 6785.796426107225, 6652.73389064432, 6938.757143499954, 6943.0, 6952.721891124298, 6955.930440695528, 5636.85907852307, 6558.442532384801, 6562.179727534059, 6762.49281303914, 6842.7605033823575], "unit": "score", "name": "prototype.html", "value": 5020.490513925986}, {"lowerIsBetter": false, "replicates": [678.3386198456506, 685.9862802743945, 745.0835472368987, 764.7514557768719, 766.097941914948, 15430.614234644125, 15673.45142919998, 15798.763018554702, 15806.920965395171, 15815.762763558556, 14551.199684017374, 16898.986060836327, 17140.914295428538, 17153.31386744532, 17167.9699218047, 804.5494523067072, 816.1267443835098, 972.7519482531944, 990.1435258501416, 995.6564985080136, 990.2078337330107, 994.8557459168417, 995.4873240281261, 997.2670087485692, 1001.4542074569341, 1048.0829274384914, 1049.8477720730489, 1050.4327663061977, 1051.337657275919, 1054.7943151085542, 842.1788755962948, 859.7850537365658, 863.0980625246632, 1031.705963800321, 1054.910332621728, 779.664744160011, 780.7189411811743, 782.8629989751771, 783.3811289081618, 789.6564994227472, 15963.281652325673, 16076.0, 16082.954607950564, 16088.356465741357, 16096.0, 13362.665933351647, 13465.39405726735, 13548.119372240872, 13631.386587603582, 13679.384427700777, 10215.591376344937, 10248.897511024943, 10442.79114417715, 10522.684319470429, 10699.465026748709, 3551.7158627310014, 3562.964370356315, 3614.9277014459562, 3687.059799751073, 3704.907377315562, 83495.16504834934, 83782.0, 83946.0, 86320.56839715825, 87084.0, 9822.803543929082, 9917.603295868228, 10029.247806414549, 10504.684859454228, 10856.294340867818, 24075.638865417033, 24088.638670419958, 24096.15663451771, 24097.397565061015, 24107.879460602584, 7750.379969602531, 7834.451588388872, 7904.802379940547, 8063.354931605458, 8113.797155071171, 4288.785560721952, 4292.678049146327, 4298.720583162147, 4333.43665323506, 4351.673624478113, 3825.9234815304094, 3883.10688541637, 3883.2427676602792, 3884.0, 3896.0259935016247, 674.1169068520254, 676.6853413162903, 680.5882441123049, 694.8019814352886, 709.1313141401741, 488.25297295138495, 543.4836904940322, 566.3062748133503, 602.7920367473215, 633.1231244726088, 8162.6734930602115, 8593.484390936439, 8687.435316704394, 9011.549422528979, 9029.593668284942, 608.9100510086984, 615.8521954730833, 618.9164462797464, 625.5339771869895, 633.7401665317198, 3808.6000969898314, 4025.7383270087353, 4029.0531725044593, 4048.8380464781667, 4207.705460617789, 90099.19801604062, 90853.54573227091, 90992.54503727572, 92431.07568924359, 103932.0, 80902.0, 81413.0, 81740.0, 82476.0, 82854.17145828585, 70579.0, 76015.0, 76566.61716691381, 76569.617151915, 77267.84098238531, 8656.697015604424, 8703.651853925772, 8866.202041816268, 9007.504587247502, 9055.637774488947, 51469.0, 51680.0, 51904.740476297375, 53865.461345387615, 55355.0, 70712.29287707058, 71213.28786712067, 72643.91034134387, 73699.0, 73833.26166738478, 10990.17573681993, 11168.441577921234, 11393.316401015965, 11463.598774043203, 11647.941760291144, 11899.940500297444, 11958.92473011433, 12069.517219311128, 12209.267443953391, 12701.682457938625, 9642.903570964201, 10215.387076775418, 11324.0, 11391.544338226378, 11777.914165531338, 11726.355050472306, 11758.647240582795, 11969.042476602026, 12042.157049006486, 12097.516099356279, 11231.71920701989, 11429.914158154961, 11577.0, 11641.243319184226, 11809.468573914022, 9748.561314740711, 9920.0, 10028.348157369748, 10168.389896606228, 10244.897551024693, 9833.11501964798, 10073.899261007296, 10098.495075246356, 10143.949280253551, 10231.846522302023], "unit": "score", "name": "yui.html", "value": 7273.031058184956}], "name": "dromaeo_css", "value": 7729.232825347807}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 110.0, 112.0, 112.0, 112.0, 112.0, 119.0, 113.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 112.3}, {"lowerIsBetter": true, "replicates": [149.0, 119.0, 154.0, 163.0, 144.0, 170.0, 143.0, 148.0, 143.0, 114.0], "unit": "score", "name": "audio-beat-detection", "value": 144.7}, {"lowerIsBetter": true, "replicates": [183.0, 185.0, 185.0, 185.0, 185.0, 185.0, 184.0, 184.0, 183.0, 183.0], "unit": "score", "name": "audio-dft", "value": 184.2}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 80.0, 119.0, 56.0, 57.0, 57.0, 60.0, 119.0, 56.0], "unit": "score", "name": "audio-fft", "value": 74.4}, {"lowerIsBetter": true, "replicates": [92.0, 97.0, 130.0, 79.0, 103.0, 115.0, 115.0, 76.0, 106.0, 164.0], "unit": "score", "name": "audio-oscillator", "value": 107.7}, {"lowerIsBetter": true, "replicates": [92.0, 88.0, 91.0, 86.0, 92.0, 89.0, 91.0, 91.0, 91.0, 91.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 90.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 96.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 94.8}, {"lowerIsBetter": true, "replicates": [87.0, 88.0, 86.0, 85.0, 91.0, 133.0, 86.0, 86.0, 85.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 91.3}, {"lowerIsBetter": true, "replicates": [56.0, 59.0, 58.0, 58.0, 57.0, 57.0, 57.0, 68.0, 54.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [61.0, 64.0, 74.0, 67.0, 72.0, 66.0, 65.0, 67.0, 64.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.5}, {"lowerIsBetter": true, "replicates": [81.0, 80.0, 92.0, 105.0, 103.0, 80.0, 80.0, 79.0, 80.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.4}, {"lowerIsBetter": true, "replicates": [132.0, 129.0, 103.0, 126.0, 131.0, 126.0, 127.0, 115.0, 120.0, 130.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [156.0, 170.0, 135.0, 135.0, 134.0, 125.0, 134.0, 133.0, 132.0, 131.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.5}, {"lowerIsBetter": true, "replicates": [57.0, 82.0, 93.0, 62.0, 56.0, 90.0, 92.0, 94.0, 91.0, 57.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.4}], "name": "kraken", "value": 1449.9000000000003}]} 09:58:36 INFO - Return code: 0 09:58:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:58:36 INFO - # TBPL SUCCESS # 09:58:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:36 INFO - 2016-04-20 09:58:36.326 screenresolution[2007:16679] starting screenresolution argv=screenresolution get 09:58:36 INFO - 2016-04-20 09:58:36.329 screenresolution[2007:16679] Display 0: 1600x1200x32@60 09:58:36 INFO - 2016-04-20 09:58:36.334 screenresolution[2008:16682] starting screenresolution argv=screenresolution list 09:58:36 INFO - Available Modes on Display 0 09:58:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:36 INFO - Intel Iris: 09:58:36 INFO - Chipset Model: Intel Iris 09:58:36 INFO - Type: GPU 09:58:36 INFO - Bus: Built-In 09:58:36 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:36 INFO - Vendor: Intel (0x8086) 09:58:36 INFO - Device ID: 0x0a2e 09:58:36 INFO - Revision ID: 0x0009 09:58:36 INFO - Displays: 09:58:36 INFO - Display: 09:58:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:36 INFO - Display Serial Number: 1600x1200 60 09:58:36 INFO - Main Display: Yes 09:58:36 INFO - Mirror: Off 09:58:36 INFO - Online: Yes 09:58:36 INFO - Rotation: Supported 09:58:36 INFO - Return code: 0 09:58:36 INFO - Running post-action listener: _resource_record_post_action 09:58:36 INFO - Running post-run listener: _resource_record_post_run 09:58:37 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 720896; Write bytes: 661587968; Read time: 6; Write time: 2120 09:58:37 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 361904640; Write bytes: 364827136; Read time: 29091; Write time: 778 09:58:37 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 720896; Write bytes: 295417344; Read time: 6; Write time: 1338 09:58:37 INFO - Running post-run listener: _upload_blobber_files 09:58:37 INFO - Blob upload gear active. 09:58:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:58:37 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'] 09:58:37 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 09:58:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:39 INFO - (blobuploader) - INFO - Done attempting. 09:58:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:40 INFO - (blobuploader) - INFO - Done attempting. 09:58:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:40 INFO - Return code: 0 09:58:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d486d89448e64fcd5e1962c9add1412c70862fac289b51c24bef1e8cafd2633d09db2b7d8a3ec898dd735bc4d8e9b85669cbd801f283dc5ef2076fb27c0ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4054a45dcbe7556ce0f1f4e86d436ced432bbbab3161e8422c521cf46a70eb431a4b861701ed5e58523c9ab1cc7d397cfb4797e285ac93a7f0b09d32038c74b8"} 09:58:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:40 INFO - Contents: 09:58:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d486d89448e64fcd5e1962c9add1412c70862fac289b51c24bef1e8cafd2633d09db2b7d8a3ec898dd735bc4d8e9b85669cbd801f283dc5ef2076fb27c0ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4054a45dcbe7556ce0f1f4e86d436ced432bbbab3161e8422c521cf46a70eb431a4b861701ed5e58523c9ab1cc7d397cfb4797e285ac93a7f0b09d32038c74b8"} 09:58:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:40 INFO - Copying logs to upload dir... 09:58:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.432873 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-04-20 09:58:40.263660) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 09:58:40.267078) ========= 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.usOVBtfBod/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.AWeqh2WYlh/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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/5d486d89448e64fcd5e1962c9add1412c70862fac289b51c24bef1e8cafd2633d09db2b7d8a3ec898dd735bc4d8e9b85669cbd801f283dc5ef2076fb27c0ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4054a45dcbe7556ce0f1f4e86d436ced432bbbab3161e8422c521cf46a70eb431a4b861701ed5e58523c9ab1cc7d397cfb4797e285ac93a7f0b09d32038c74b8"} build_url:https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/GlSFgEI4Q424sP24_hTYlg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d486d89448e64fcd5e1962c9add1412c70862fac289b51c24bef1e8cafd2633d09db2b7d8a3ec898dd735bc4d8e9b85669cbd801f283dc5ef2076fb27c0ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4054a45dcbe7556ce0f1f4e86d436ced432bbbab3161e8422c521cf46a70eb431a4b861701ed5e58523c9ab1cc7d397cfb4797e285ac93a7f0b09d32038c74b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 09:58:40.294793) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 09:58:40.295125) ========= 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.usOVBtfBod/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.AWeqh2WYlh/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005015 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 09:58:40.343525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 09:58:40.343842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 09:58:40.344234) ========= ========= Total master_lag: 0.16 =========