builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0070 starttime: 1462798444.66 results: success (0) buildid: 20160509005049 builduid: 41dbd660830c4270b4520649f25740b3 revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.655861) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.656641) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.657140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.675996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.676386) ========= 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.AACM9ZZppF/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.YyryaB56WX/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-09 05:54:04-- 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% 159M=0s 2016-05-09 05:54:04 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.069429 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.764813) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.765649) ========= 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.AACM9ZZppF/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.YyryaB56WX/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.020320 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:04.815056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 05:54:04.815410) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 307b5eca8726f6a72a1d69ba76630a6dbf402477 --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 307b5eca8726f6a72a1d69ba76630a6dbf402477 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AACM9ZZppF/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.YyryaB56WX/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-09 05:54:04,891 truncating revision to first 12 chars 2016-05-09 05:54:04,891 Setting DEBUG logging. 2016-05-09 05:54:04,891 attempt 1/10 2016-05-09 05:54:04,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/307b5eca8726?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 05:54:06,200 unpacking tar archive at: mozilla-aurora-307b5eca8726/testing/mozharness/ program finished with exit code 0 elapsedTime=1.561034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 05:54:06.394853) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:06.395452) ========= script_repo_revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:06.396134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:06.396635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:06.420448) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-09 05:54:06.420863) ========= /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.AACM9ZZppF/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.YyryaB56WX/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:54:06 INFO - MultiFileLogger online at 20160509 05:54:06 in /builds/slave/test 05:54:06 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 05:54:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:54:06 INFO - {'append_to_log': False, 05:54:06 INFO - 'base_work_dir': '/builds/slave/test', 05:54:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:54:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:54:06 INFO - 'branch': 'Mozilla-Aurora', 05:54:06 INFO - 'buildbot_json_path': 'buildprops.json', 05:54:06 INFO - 'config_files': ('talos/mac_config.py',), 05:54:06 INFO - 'default_actions': ('clobber', 05:54:06 INFO - 'read-buildbot-config', 05:54:06 INFO - 'download-and-extract', 05:54:06 INFO - 'populate-webroot', 05:54:06 INFO - 'create-virtualenv', 05:54:06 INFO - 'install', 05:54:06 INFO - 'run-tests'), 05:54:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:54:06 INFO - 'download_minidump_stackwalk': True, 05:54:06 INFO - 'download_symbols': 'ondemand', 05:54:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:54:06 INFO - 'tooltool.py': '/tools/tooltool.py', 05:54:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:54:06 INFO - '/tools/misc-python/virtualenv.py')}, 05:54:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:54:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:54:06 INFO - 'installer_path': 'installer.exe', 05:54:06 INFO - 'log_level': 'info', 05:54:06 INFO - 'log_name': 'talos', 05:54:06 INFO - 'log_to_console': True, 05:54:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:54:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:54:06 INFO - 'opt_config_files': (), 05:54:06 INFO - 'pip_index': False, 05:54:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:06 INFO - 'cmd': ('bash', 05:54:06 INFO - '-c', 05:54:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:06 INFO - 'enabled': True, 05:54:06 INFO - 'halt_on_failure': False, 05:54:06 INFO - 'name': 'check_screen_resolution'},), 05:54:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:06 INFO - 'cmd': ('bash', 05:54:06 INFO - '-c', 05:54:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:06 INFO - 'enabled': True, 05:54:06 INFO - 'halt_on_failure': False, 05:54:06 INFO - 'name': 'check_screen_resolution'},), 05:54:06 INFO - 'run_cmd_checks_enabled': True, 05:54:06 INFO - 'sps_profile': False, 05:54:06 INFO - 'sps_profile_interval': 0, 05:54:06 INFO - 'suite': 'dromaeojs', 05:54:06 INFO - 'system_bits': '32', 05:54:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:54:06 INFO - 'title': 't-yosemite-r7-0070', 05:54:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:54:06 INFO - 'use_talos_json': True, 05:54:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:54:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:54:06 INFO - 'work_dir': 'build'} 05:54:06 INFO - ##### 05:54:06 INFO - ##### Running clobber step. 05:54:06 INFO - ##### 05:54:06 INFO - Running pre-action listener: _resource_record_pre_action 05:54:06 INFO - Running main action method: clobber 05:54:06 INFO - rmtree: /builds/slave/test/build 05:54:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:54:07 INFO - Running post-action listener: _resource_record_post_action 05:54:07 INFO - ##### 05:54:07 INFO - ##### Running read-buildbot-config step. 05:54:07 INFO - ##### 05:54:07 INFO - Running pre-action listener: _resource_record_pre_action 05:54:07 INFO - Running main action method: read_buildbot_config 05:54:07 INFO - Using buildbot properties: 05:54:07 INFO - { 05:54:07 INFO - "project": "", 05:54:07 INFO - "product": "firefox", 05:54:07 INFO - "script_repo_revision": "production", 05:54:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:54:07 INFO - "repository": "", 05:54:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:54:07 INFO - "buildid": "20160509005049", 05:54:07 INFO - "pgo_build": "False", 05:54:07 INFO - "basedir": "/builds/slave/test", 05:54:07 INFO - "buildnumber": 6, 05:54:07 INFO - "slavename": "t-yosemite-r7-0070", 05:54:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:54:07 INFO - "platform": "yosemite_r7", 05:54:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:54:07 INFO - "branch": "mozilla-aurora", 05:54:07 INFO - "repo_path": "releases/mozilla-aurora", 05:54:07 INFO - "revision": "307b5eca8726f6a72a1d69ba76630a6dbf402477", 05:54:07 INFO - "stage_platform": "macosx64", 05:54:07 INFO - "builduid": "41dbd660830c4270b4520649f25740b3", 05:54:07 INFO - "slavebuilddir": "test" 05:54:07 INFO - } 05:54:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:54:07 INFO - Running post-action listener: _resource_record_post_action 05:54:07 INFO - ##### 05:54:07 INFO - ##### Running download-and-extract step. 05:54:07 INFO - ##### 05:54:07 INFO - Running pre-action listener: _resource_record_pre_action 05:54:07 INFO - Running main action method: download_and_extract 05:54:07 INFO - mkdir: /builds/slave/test/build/tests 05:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:07 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:54:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 05:54:10 INFO - Downloaded 1199 bytes. 05:54:10 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:10 INFO - Using the following test package requirements: 05:54:10 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:54:10 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:54:10 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:54:10 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'jsshell-mac.zip'], 05:54:10 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:54:10 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:54:10 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:54:10 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:54:10 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:54:10 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:10 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:54:10 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:10 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:54:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:54:13 INFO - Downloaded 18088795 bytes. 05:54:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:54:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:54:16 INFO - Return code: 0 05:54:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:16 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:54:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:54:17 INFO - Downloaded 18088795 bytes. 05:54:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:54:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:54:21 INFO - Return code: 0 05:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:21 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:54:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:54:22 INFO - Downloaded 10840145 bytes. 05:54:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:54:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:54:22 INFO - Return code: 0 05:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:22 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:54:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:54:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 05:54:25 INFO - Downloaded 114085322 bytes. 05:54:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:25 INFO - mkdir: /builds/slave/test/properties 05:54:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:25 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:25 INFO - Contents: 05:54:25 INFO - build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:26 INFO - Running post-action listener: _resource_record_post_action 05:54:26 INFO - Running post-action listener: set_extra_try_arguments 05:54:26 INFO - ##### 05:54:26 INFO - ##### Running populate-webroot step. 05:54:26 INFO - ##### 05:54:26 INFO - Running pre-action listener: _resource_record_pre_action 05:54:26 INFO - Running main action method: populate_webroot 05:54:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:26 INFO - '--tppagecycles', 05:54:26 INFO - '7'], 05:54:26 INFO - 'tests': ['tsvgm']}}, 05:54:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['damp', 'tps']}, 05:54:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'tests': ['damp', 'tps']}, 05:54:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['dromaeo_dom']}, 05:54:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['a11yr', 05:54:26 INFO - 'ts_paint', 05:54:26 INFO - 'tpaint', 05:54:26 INFO - 'sessionrestore', 05:54:26 INFO - 'sessionrestore_no_auto_restore', 05:54:26 INFO - 'tabpaint']}, 05:54:26 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:26 INFO - 'ts_paint', 05:54:26 INFO - 'tpaint', 05:54:26 INFO - 'sessionrestore', 05:54:26 INFO - 'sessionrestore_no_auto_restore', 05:54:26 INFO - 'tabpaint']}, 05:54:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:26 INFO - 'ts_paint', 05:54:26 INFO - 'tpaint', 05:54:26 INFO - 'sessionrestore', 05:54:26 INFO - 'sessionrestore_no_auto_restore', 05:54:26 INFO - 'tabpaint']}, 05:54:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:26 INFO - 'ts_paint', 05:54:26 INFO - 'tpaint', 05:54:26 INFO - 'sessionrestore', 05:54:26 INFO - 'sessionrestore_no_auto_restore', 05:54:26 INFO - 'tabpaint']}, 05:54:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['a11yr', 05:54:26 INFO - 'ts_paint', 05:54:26 INFO - 'tpaint', 05:54:26 INFO - 'sessionrestore', 05:54:26 INFO - 'sessionrestore_no_auto_restore', 05:54:26 INFO - 'tabpaint']}, 05:54:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['a11yr', 05:54:26 INFO - 'ts_paint', 05:54:26 INFO - 'tpaint', 05:54:26 INFO - 'sessionrestore', 05:54:26 INFO - 'sessionrestore_no_auto_restore', 05:54:26 INFO - 'tabpaint']}, 05:54:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['tsvgx', 05:54:26 INFO - 'tsvgr_opacity', 05:54:26 INFO - 'tart', 05:54:26 INFO - 'tscrollx', 05:54:26 INFO - 'cart']}, 05:54:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:26 INFO - 'tsvgr_opacity', 05:54:26 INFO - 'tart', 05:54:26 INFO - 'tscrollx', 05:54:26 INFO - 'cart']}, 05:54:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'talos_options': ['--disable-e10s'], 05:54:26 INFO - 'tests': ['tp5o']}, 05:54:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'tests': ['tp5o']}, 05:54:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'talos_options': ['--disable-e10s', 05:54:26 INFO - '--xperf_path', 05:54:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:26 INFO - 'tests': ['tp5n']}, 05:54:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:26 INFO - 'talos_options': ['--xperf_path', 05:54:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:26 INFO - 'tests': ['tp5n']}}, 05:54:26 INFO - 'talos.zip': {'path': '', 05:54:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:26 INFO - Running post-action listener: _resource_record_post_action 05:54:26 INFO - ##### 05:54:26 INFO - ##### Running create-virtualenv step. 05:54:26 INFO - ##### 05:54:26 INFO - Running pre-action listener: _resource_record_pre_action 05:54:26 INFO - Running main action method: create_virtualenv 05:54:26 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:54:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:26 INFO - Using real prefix '/tools/python27' 05:54:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:54:30 INFO - Installing pip.................done. 05:54:30 INFO - Return code: 0 05:54:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:30 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:30 INFO - 'HOME': '/Users/cltbld', 05:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:30 INFO - 'LOGNAME': 'cltbld', 05:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:30 INFO - 'MOZ_NO_REMOTE': '1', 05:54:30 INFO - 'NO_EM_RESTART': '1', 05:54:30 INFO - 'PAGER': '/bin/cat', 05:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:30 INFO - 'PWD': '/builds/slave/test', 05:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:30 INFO - 'SHELL': '/bin/bash', 05:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:30 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:30 INFO - 'USER': 'cltbld', 05:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:30 INFO - 'XPC_FLAGS': '0x0', 05:54:30 INFO - 'XPC_SERVICE_NAME': '0', 05:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:54:30 INFO - Cleaning up... 05:54:30 INFO - Return code: 0 05:54:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:30 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:30 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 05:54:30 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 05:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:30 INFO - 'HOME': '/Users/cltbld', 05:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:30 INFO - 'LOGNAME': 'cltbld', 05:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:30 INFO - 'MOZ_NO_REMOTE': '1', 05:54:30 INFO - 'NO_EM_RESTART': '1', 05:54:30 INFO - 'PAGER': '/bin/cat', 05:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:30 INFO - 'PWD': '/builds/slave/test', 05:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:30 INFO - 'SHELL': '/bin/bash', 05:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:30 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:30 INFO - 'USER': 'cltbld', 05:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:30 INFO - 'XPC_FLAGS': '0x0', 05:54:30 INFO - 'XPC_SERVICE_NAME': '0', 05:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:30 INFO - Downloading/unpacking psutil>=0.7.1 05:54: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 05:54: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 05:54: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 05:54: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 05:54: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 05:54: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 05:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:54:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:33 INFO - Installing collected packages: psutil 05:54:33 INFO - Running setup.py install for psutil 05:54:33 INFO - building 'psutil._psutil_osx' extension 05:54:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:54:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:54:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:54:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:54:33 INFO - building 'psutil._psutil_posix' extension 05:54:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:54:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:54:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:54:33 INFO - ^ 05:54:33 INFO - 1 warning generated. 05:54:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:54:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:34 INFO - Successfully installed psutil 05:54:34 INFO - Cleaning up... 05:54:34 INFO - Return code: 0 05:54:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:34 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 05:54:34 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 05:54:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:34 INFO - 'HOME': '/Users/cltbld', 05:54:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:34 INFO - 'LOGNAME': 'cltbld', 05:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:34 INFO - 'MOZ_NO_REMOTE': '1', 05:54:34 INFO - 'NO_EM_RESTART': '1', 05:54:34 INFO - 'PAGER': '/bin/cat', 05:54:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:34 INFO - 'PWD': '/builds/slave/test', 05:54:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:34 INFO - 'SHELL': '/bin/bash', 05:54:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:34 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:34 INFO - 'USER': 'cltbld', 05:54:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:34 INFO - 'XPC_FLAGS': '0x0', 05:54:34 INFO - 'XPC_SERVICE_NAME': '0', 05:54:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:54:36 INFO - Installing collected packages: mozsystemmonitor 05:54:36 INFO - Running setup.py install for mozsystemmonitor 05:54:37 INFO - Successfully installed mozsystemmonitor 05:54:37 INFO - Cleaning up... 05:54:37 INFO - Return code: 0 05:54:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:37 INFO - 'HOME': '/Users/cltbld', 05:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:37 INFO - 'LOGNAME': 'cltbld', 05:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:37 INFO - 'MOZ_NO_REMOTE': '1', 05:54:37 INFO - 'NO_EM_RESTART': '1', 05:54:37 INFO - 'PAGER': '/bin/cat', 05:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:37 INFO - 'PWD': '/builds/slave/test', 05:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:37 INFO - 'SHELL': '/bin/bash', 05:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:37 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:37 INFO - 'USER': 'cltbld', 05:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:37 INFO - 'XPC_FLAGS': '0x0', 05:54:37 INFO - 'XPC_SERVICE_NAME': '0', 05:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:37 INFO - Downloading/unpacking blobuploader==1.2.4 05:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:54:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:54:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:40 INFO - Downloading docopt-0.6.1.tar.gz 05:54:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:54:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:54:41 INFO - Running setup.py install for blobuploader 05:54:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:54:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:54:41 INFO - Running setup.py install for requests 05:54:41 INFO - Running setup.py install for docopt 05:54:41 INFO - Successfully installed blobuploader requests docopt 05:54:41 INFO - Cleaning up... 05:54:41 INFO - Return code: 0 05:54:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54: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')]} 05:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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')]} 05:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:41 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:41 INFO - 'HOME': '/Users/cltbld', 05:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:41 INFO - 'LOGNAME': 'cltbld', 05:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:41 INFO - 'MOZ_NO_REMOTE': '1', 05:54:41 INFO - 'NO_EM_RESTART': '1', 05:54:41 INFO - 'PAGER': '/bin/cat', 05:54:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:41 INFO - 'PWD': '/builds/slave/test', 05:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:41 INFO - 'SHELL': '/bin/bash', 05:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:41 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:41 INFO - 'USER': 'cltbld', 05:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:41 INFO - 'XPC_FLAGS': '0x0', 05:54:41 INFO - 'XPC_SERVICE_NAME': '0', 05:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-PUoxej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-XQwk0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-X3ibVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aM3cpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5GKToT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5hoZJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-qwQkbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Ih4Foc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:42 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-UXKo_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-7OCLC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-iXr57i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-kzJHHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ADYja6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-iA0C0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-UgGNUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-OmpaU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:43 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-nUHCcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:54:43 INFO - Running setup.py install for manifestparser 05:54:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:54:44 INFO - Running setup.py install for mozcrash 05:54:44 INFO - Running setup.py install for mozdebug 05:54:44 INFO - Running setup.py install for mozdevice 05:54:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:54:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:54:44 INFO - Running setup.py install for mozfile 05:54:44 INFO - Running setup.py install for mozhttpd 05:54:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:54:44 INFO - Running setup.py install for mozinfo 05:54:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:54:44 INFO - Running setup.py install for mozInstall 05:54:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozleak 05:54:45 INFO - Running setup.py install for mozlog 05:54:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for moznetwork 05:54:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozprocess 05:54:45 INFO - Running setup.py install for mozprofile 05:54:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:54:45 INFO - Running setup.py install for mozrunner 05:54:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:54:46 INFO - Running setup.py install for mozscreenshot 05:54:46 INFO - Running setup.py install for moztest 05:54:46 INFO - Running setup.py install for mozversion 05:54:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:54:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:54:46 INFO - Cleaning up... 05:54:46 INFO - Return code: 0 05:54:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:46 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:46 INFO - 'HOME': '/Users/cltbld', 05:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:46 INFO - 'LOGNAME': 'cltbld', 05:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:46 INFO - 'MOZ_NO_REMOTE': '1', 05:54:46 INFO - 'NO_EM_RESTART': '1', 05:54:46 INFO - 'PAGER': '/bin/cat', 05:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:46 INFO - 'PWD': '/builds/slave/test', 05:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:46 INFO - 'SHELL': '/bin/bash', 05:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:46 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:46 INFO - 'USER': 'cltbld', 05:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:46 INFO - 'XPC_FLAGS': '0x0', 05:54:46 INFO - 'XPC_SERVICE_NAME': '0', 05:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:46 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5s5NLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:46 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-w550zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:46 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-imnmh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aZKsTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-t9vfb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-SGDQ_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-GELH5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-HsSADI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gpBNdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-RxBltb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:47 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)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-tmjhsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-cTkzXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-VqTvL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-mkuZ5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-q6lSas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ooCu7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-jKtddJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:54:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:51 INFO - Downloading blessings-1.6.tar.gz 05:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:51 INFO - Installing collected packages: blessings 05:54:51 INFO - Running setup.py install for blessings 05:54:51 INFO - Successfully installed blessings 05:54:51 INFO - Cleaning up... 05:54:51 INFO - Return code: 0 05:54:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:51 INFO - Reading from file tmpfile_stdout 05:54:51 INFO - Current package versions: 05:54:51 INFO - blessings == 1.6 05:54:51 INFO - blobuploader == 1.2.4 05:54:51 INFO - docopt == 0.6.1 05:54:51 INFO - manifestparser == 1.1 05:54:51 INFO - mozInstall == 1.12 05:54:51 INFO - mozcrash == 0.17 05:54:51 INFO - mozdebug == 0.1 05:54:51 INFO - mozdevice == 0.48 05:54:51 INFO - mozfile == 1.2 05:54:51 INFO - mozhttpd == 0.7 05:54:51 INFO - mozinfo == 0.9 05:54:51 INFO - mozleak == 0.1 05:54:51 INFO - mozlog == 3.1 05:54:51 INFO - moznetwork == 0.27 05:54:51 INFO - mozprocess == 0.22 05:54:51 INFO - mozprofile == 0.28 05:54:51 INFO - mozrunner == 6.11 05:54:51 INFO - mozscreenshot == 0.1 05:54:51 INFO - mozsystemmonitor == 0.0 05:54:51 INFO - moztest == 0.7 05:54:51 INFO - mozversion == 1.4 05:54:51 INFO - psutil == 3.1.1 05:54:51 INFO - requests == 1.2.3 05:54:51 INFO - wsgiref == 0.1.2 05:54:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54: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')]} 05:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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')]} 05:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:51 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:51 INFO - 'HOME': '/Users/cltbld', 05:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:51 INFO - 'LOGNAME': 'cltbld', 05:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:51 INFO - 'MOZ_NO_REMOTE': '1', 05:54:51 INFO - 'NO_EM_RESTART': '1', 05:54:51 INFO - 'PAGER': '/bin/cat', 05:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:51 INFO - 'PWD': '/builds/slave/test', 05:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:51 INFO - 'SHELL': '/bin/bash', 05:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:51 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:51 INFO - 'USER': 'cltbld', 05:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:51 INFO - 'XPC_FLAGS': '0x0', 05:54:51 INFO - 'XPC_SERVICE_NAME': '0', 05:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:54:51 INFO - Cleaning up... 05:54:51 INFO - Return code: 0 05:54:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54: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')]} 05:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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')]} 05:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:51 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 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:51 INFO - 'HOME': '/Users/cltbld', 05:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:51 INFO - 'LOGNAME': 'cltbld', 05:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:51 INFO - 'MOZ_NO_REMOTE': '1', 05:54:51 INFO - 'NO_EM_RESTART': '1', 05:54:51 INFO - 'PAGER': '/bin/cat', 05:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:51 INFO - 'PWD': '/builds/slave/test', 05:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:51 INFO - 'SHELL': '/bin/bash', 05:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:51 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:51 INFO - 'USER': 'cltbld', 05:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:51 INFO - 'XPC_FLAGS': '0x0', 05:54:51 INFO - 'XPC_SERVICE_NAME': '0', 05:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:52 INFO - Downloading/unpacking jsonschema 05:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:54:54 INFO - Downloading/unpacking functools32 (from jsonschema) 05:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:54 INFO - Downloading functools32-3.2.3-2.tar.gz 05:54:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:54:55 INFO - warning: no files found matching '*.txt' 05:54:55 INFO - no previously-included directories found matching 'build' 05:54:55 INFO - no previously-included directories found matching 'dist' 05:54:55 INFO - no previously-included directories found matching '.git*' 05:54:55 INFO - Installing collected packages: jsonschema, functools32 05:54:55 INFO - Running setup.py install for functools32 05:54:55 INFO - warning: no files found matching '*.txt' 05:54:55 INFO - no previously-included directories found matching 'build' 05:54:55 INFO - no previously-included directories found matching 'dist' 05:54:55 INFO - no previously-included directories found matching '.git*' 05:54:55 INFO - Successfully installed jsonschema functools32 05:54:55 INFO - Cleaning up... 05:54:55 INFO - Return code: 0 05:54:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cbeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34ad8bec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, '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.AACM9ZZppF/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.YyryaB56WX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:54:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:54:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:54:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:54:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:54:55 INFO - 'HOME': '/Users/cltbld', 05:54:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:54:55 INFO - 'LOGNAME': 'cltbld', 05:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:55 INFO - 'MOZ_NO_REMOTE': '1', 05:54:55 INFO - 'NO_EM_RESTART': '1', 05:54:55 INFO - 'PAGER': '/bin/cat', 05:54:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:55 INFO - 'PWD': '/builds/slave/test', 05:54:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:54:55 INFO - 'SHELL': '/bin/bash', 05:54:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:54:55 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:54:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:54:55 INFO - 'USER': 'cltbld', 05:54:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:54:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:55 INFO - 'XPC_FLAGS': '0x0', 05:54:55 INFO - 'XPC_SERVICE_NAME': '0', 05:54:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:55 INFO - Downloading/unpacking flake8 05:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:54:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:54:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:54:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:54:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:54:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:54:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:54:59 INFO - no previously-included directories found matching 'docs/_build' 05:54:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:54:59 INFO - Running setup.py install for pep8 05:54:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:54:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:54:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:54:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:54:59 INFO - no previously-included directories found matching 'docs/_build' 05:54:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:54:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:54:59 INFO - Cleaning up... 05:54:59 INFO - Return code: 0 05:54:59 INFO - Running post-action listener: _resource_record_post_action 05:54:59 INFO - Running post-action listener: _start_resource_monitoring 05:54:59 INFO - Starting resource monitoring. 05:54:59 INFO - ##### 05:54:59 INFO - ##### Running install step. 05:54:59 INFO - ##### 05:54:59 INFO - Running pre-action listener: _resource_record_pre_action 05:54:59 INFO - Running main action method: install 05:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:00 INFO - Reading from file tmpfile_stdout 05:55:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:55:00 INFO - Reading from file tmpfile_stdout 05:55:00 INFO - Output received: 05:55:00 INFO - Usage: mozinstall [options] installer 05:55:00 INFO - Options: 05:55:00 INFO - -h, --help show this help message and exit 05:55:00 INFO - -d DEST, --destination=DEST 05:55:00 INFO - Directory to install application into. [default: 05:55:00 INFO - "/builds/slave/test"] 05:55:00 INFO - --app=APP Application being installed. [default: firefox] 05:55:00 INFO - mkdir: /builds/slave/test/build/application 05:55:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:55:31 INFO - Reading from file tmpfile_stdout 05:55:31 INFO - Output received: 05:55:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:55:31 INFO - Running post-action listener: _resource_record_post_action 05:55:31 INFO - ##### 05:55:31 INFO - ##### Running run-tests step. 05:55:31 INFO - ##### 05:55:31 INFO - Running pre-action listener: _resource_record_pre_action 05:55:31 INFO - Running main action method: run_tests 05:55:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:55:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:55:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:55:31 INFO - 2016-05-09 05:55:31.643 screenresolution[1954:12680] starting screenresolution argv=screenresolution get 05:55:31 INFO - 2016-05-09 05:55:31.645 screenresolution[1954:12680] Display 0: 1600x1200x32@60 05:55:31 INFO - 2016-05-09 05:55:31.651 screenresolution[1955:12682] starting screenresolution argv=screenresolution list 05:55:31 INFO - Available Modes on Display 0 05:55:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:55:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:55:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:55:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:55:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:55:31 INFO - Intel Iris: 05:55:31 INFO - Chipset Model: Intel Iris 05:55:31 INFO - Type: GPU 05:55:31 INFO - Bus: Built-In 05:55:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:55:31 INFO - Vendor: Intel (0x8086) 05:55:31 INFO - Device ID: 0x0a2e 05:55:31 INFO - Revision ID: 0x0009 05:55:31 INFO - Displays: 05:55:31 INFO - Display: 05:55:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:55:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:55:31 INFO - Display Serial Number: 1600x1200 60 05:55:31 INFO - Main Display: Yes 05:55:31 INFO - Mirror: Off 05:55:31 INFO - Online: Yes 05:55:31 INFO - Rotation: Supported 05:55:31 INFO - Return code: 0 05:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:55:31 INFO - Python 2.7.3 05:55:31 INFO - Return code: 0 05:55:31 INFO - grabbing minidump binary from tooltool 05:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:31 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 0x10ca89e00>, '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 0x10c8f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:55:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:55:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:55:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:55:31 INFO - Return code: 0 05:55:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:55:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:55:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:55:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:55:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:55:33 INFO - Return code: 0 05:55:33 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/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 05:55:33 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/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 05:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AACM9ZZppF/Render', 05:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:33 INFO - 'HOME': '/Users/cltbld', 05:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:33 INFO - 'LOGNAME': 'cltbld', 05:55:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:55:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:33 INFO - 'MOZ_NO_REMOTE': '1', 05:55:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:55:33 INFO - 'NO_EM_RESTART': '1', 05:55:33 INFO - 'PAGER': '/bin/cat', 05:55:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:33 INFO - 'PWD': '/builds/slave/test', 05:55:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:33 INFO - 'SHELL': '/bin/bash', 05:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YyryaB56WX/Listeners', 05:55:33 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 05:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:33 INFO - 'USER': 'cltbld', 05:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:33 INFO - 'XPC_FLAGS': '0x0', 05:55:33 INFO - 'XPC_SERVICE_NAME': '0', 05:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:33 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/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 05:55:33 INFO - mozversion application_buildid: 20160509005049 05:55:33 INFO - mozversion application_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 05:55:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:55:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:33 INFO - mozversion application_name: Firefox 05:55:33 INFO - mozversion application_remotingname: firefox-dev 05:55:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:55:33 INFO - mozversion application_vendor: Mozilla 05:55:33 INFO - mozversion application_version: 48.0a2 05:55:33 INFO - mozversion platform_buildid: 20160509005049 05:55:33 INFO - mozversion platform_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 05:55:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:55:33 INFO - mozversion platform_version: 48.0a2 05:55:33 INFO - using testdate: 1462798533 05:55:33 INFO - actual date: 1462798533 05:55:33 INFO - starting webserver on 'localhost:49523' 05:55:33 INFO - SUITE-START | Running 2 tests 05:55:33 INFO - TEST-START | dromaeo_css 05:55:33 INFO - operating with platform_type : mac_ 05:55:33 INFO - Initialising browser for dromaeo_css test... 05:55:33 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmppArZmR/profile http://localhost:49523/getInfo.html) 05:55:35 INFO - PROCESS | 1973 | __metrics Screen width/height:1600/1200 05:55:35 INFO - PROCESS | 1973 | colorDepth:24 05:55:35 INFO - PROCESS | 1973 | Browser inner width/height: 1024/689 05:55:35 INFO - PROCESS | 1973 | __metrics 05:55:35 INFO - PROCESS | 1973 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:55:35 ERROR - PROCESS | 1973 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:55:36 INFO - TEST-INFO | 1973: exit 0 05:55:36 INFO - Browser initialized. 05:55:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:55:36 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmppArZmR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:48 INFO - PROCESS | 1975 | RSS: Main: 211083264 05:55:48 INFO - PROCESS | 1975 | 05:56:39 INFO - PROCESS | 1975 | 1462798599954 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:58:50 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49523/tests/dromaeo/cssquery-ext.html) 05:58:52 INFO - PROCESS | 1975 | RSS: Main: 328765440 05:58:52 INFO - PROCESS | 1975 | 06:01:54 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-ext.html (next: http://localhost:49523/tests/dromaeo/cssquery-jquery.html) 06:01:55 INFO - PROCESS | 1975 | RSS: Main: 352694272 06:01:55 INFO - PROCESS | 1975 | 06:04:57 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49523/tests/dromaeo/cssquery-mootools.html) 06:04:58 INFO - PROCESS | 1975 | RSS: Main: 359833600 06:04:58 INFO - PROCESS | 1975 | 06:08:00 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49523/tests/dromaeo/cssquery-prototype.html) 06:08:00 INFO - PROCESS | 1975 | RSS: Main: 359038976 06:08:00 INFO - PROCESS | 1975 | 06:11:04 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49523/tests/dromaeo/cssquery-yui.html) 06:11:04 INFO - PROCESS | 1975 | RSS: Main: 325214208 06:11:04 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-yui.html (next: http://localhost:49523/tests/dromaeo/cssquery-dojo.html) 06:14:07 INFO - PROCESS | 1975 | RSS: Main: 400293888 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | __start_tp_report 06:14:07 INFO - PROCESS | 1975 | _x_x_mozilla_page_load 06:14:07 INFO - PROCESS | 1975 | _x_x_mozilla_page_load_details 06:14:07 INFO - PROCESS | 1975 | |i|pagename|runs| 06:14:07 INFO - PROCESS | 1975 | |0;dojo.html;3379.8479068441916;3481.3559491494066;3484.6166921638614;3513.1919658478564;3543.114221444639;5770.422957704228;5776.018076926922;5792.420757924206;5799.420057994198;5803.477687008174;5768.682722450264;5807.361190269067;5809.186713860063;5815.796447124351;5831.358550559435;5211.114110601188;5239.476052394749;5264.710440925747;5339.279197308372;5366.838994830142;5350.732463376835;5364.946350536503;5365.624406291561;5368.382635996852;5374.865628359284;4720.669553131283;5324.866878328034;5331;5335.5198032177095;5375.865603359908;5291.1798671206025;5324.840254792362;5331.706756128411;5334.146536554172;5371.865703357408;4320.805563749639;5111.08000559899;5175.171972484422;5193.169092945149;5225.4513276105845;5685.061964775807;5712.11462223356;5730.570207234474;5734.856628584277;5745.109508026263;6299.622022678608;6310.684465776692;6333.240011198685;6335.8416039598915;6337.904931426032;5825.79609713658;6240.750369985195;6306.873862522724;6327.4938004959495;6335.3981371769605;5900.468957793819;5903.852403689943;5908.852278693067;5910.408959104098;5935.614185077956;6386.616802991835;6393.817143828406;6397.26431460388;6403;6408.903866442007;6090.869183494339;6177.227846519185;6275.215598050244;6299.464545513682;6310.62136271816;5277.894442111136;6320.399562041599;6339.270983836917;6340.873182536324;6342.904856427157;6243.344448832898;6263;6296.087067375205;6324.71538780756;6346.238451385863;6182;6279.591826531261;6282.842928926722;6293.118963345135;6325;4957.90084198314;4977.552020318189;4978.77595508203;5006.69959802413;5009.824656137018;4499.797509112098;4500.144972455223;4517.683762136685;4526.20791361517;4526.909461810811;5775.422457754191;5790.565707571865;5802.912956305659;5810.360860305363;5810.767569297181;5187.740612969336;6228.439440450381;6243.687815609202;6258.530610204162;6284.622922624657;5752.884942301131;5772.336181339116;5781.017227071408;5812.418758124154;5818.389069147764;5483.259759932438;5491.588130890199;5493.615446918678;5496.835094947158;5502.449755024546;6231.501479881598;6411.0383442484;6425.5502114851515;6430.64631445275;6507.186601674791;6252.343503932022;6288.528360373082;6304.873902522016;6316.842078948063;6345.968270158804;6481.4166724994975;6511.967440162769;6517.608943463408;6525.347465253627;6526.184226971629;6221.9377806221355;6257.655828929271;6280.905786413299;6308.621482711052;6324.3991820776955;6205.782797602063;6216.160818289654;6311.90532142009;6312.05319202105;6322.525810564134;6180.536459765445;6186.1648677427975;6186.907196391968;6190.938090619216;6207.968960155351;6108.840825276481;6157.261128664589;6176.227971503562;6180.598261113013;6206.224221972254;5929.407059294036;6031.035034394476;6050.878982420415;6134.325224225242;6138.572327483616;4925.729084900365;6226.066090086342;6226.688665566794;6230.532710046855;6233.96883015582;5980.9401905980385;6093.9390606093375;6111.388861113853;6114.602550834182;6121.84695382619 06:14:07 INFO - PROCESS | 1975 | |1;ext.html;521.332694151486;536.1261144334734;544.9809256676017;583.6556431705293;613.0987448450776;31726.365472690533;31935.680643193562;32201;39078.804605977;39990.20019599606;45195;45607.77196114015;45821.77089114558;45883.85290367743;46100.077998440094;9577.664781732641;9799.118079372854;9806.460644664576;9811.852822207673;9824.214062874953;7538.020057392531;8565.271951884097;11489.770204595903;11687.941560292229;11713.1215158863;11416.315021098719;11505.769884602303;11510.884891151107;11512.481938312796;11567.305961642329;7300.561966281987;11435.371054591995;11459.598914038052;11537.826932596017;11537.942310288396;11456.65630031092;11588.420578971018;11588.6523404298;11589.710257243552;11615.767684646344;39315.41026884599;39856.40215396742;39869.60130398717;39920;39928.800355998035;3009.7058264946063;3235.5955505561806;3264.6408895021527;3403.6085850127306;3460.757746957714;28125.43749125006;28885.27786805347;29506;29864.8506757467;30058;2762.9622286110716;2771.3348796288947;2787.4007088476073;2789.748922596976;2811.3252819323216;458374.9999999967;461085;462725;463196;464013.6799315982;36620.81689591535;64025.35974640194;64552.35447645463;64672;64674.35325646777;36240.81879590585;36351.091222719115;36369;36424.453633195524;36455.4531682025;15215.31531081104;15516.456924007605;15520.611984700246;15521.611959700873;15521.689566208612;12441.502339906483;12608.873911260953;13175.209487430784;13233.536826211037;13248.470061197637;8162.918370816215;8196.42625016255;8202;8243.793905152299;8279.172082791672;3391.762576619613;3422.418188907892;3424.126847653832;3427.845746941394;3457.2739724658104;968.4044312747609;987.980240395188;1024.9743756406149;1103.8896110388896;1119.652907598647;29741.810327587085;30307.545386819216;30575.624096915693;30727.811271559385;30733.92431264895;1137.6530158301632;1143;1170.988290117105;1177.8468799056068;1179.204037274836;13241.140192688947;15073.849261507245;15322.310496027705;15651.686966260611;15851.048937063813;593674;597811;603568;605097;606050;62688.37311626917;63177.68411157915;63303.68348158322;63370.68314658397;63682.72634547283;1347.9258640774656;1382.5022991722772;1484.7104814561058;1495.8653721165147;1523.1470376589145;29372.559411608414;29846.701532985262;31258.374832503676;31288;31404.685953140175;45516;54456;55459.61350966258;55615.72192139013;55705.442945571645;561846;588429;589193;589574;589645;33486.497702534;36534.72128475491;41415.37876931788;44488.11023779571;45127;44584.55415445804;45325.546744532134;45589.31616025696;46829.2975605373;47151.76424117857;35986.92039238827;38605.2278954425;38880.41679374755;39209.803950980066;39985.80007100062;38165.618343816204;38447;38627;38963.610363896;39137;37839.24321513499;38234.0441488965;38336.233275333776;38425.4236186463;38504.42243366408;25717.871410642827;32015.519767203044;33147.50278745773;33886.322273553895;33926.66073339235;25070;25380.365490862878;25399.492010159323;25911.870440647675;25929 06:14:07 INFO - PROCESS | 1975 | |2;jquery.html;408.8957315884449;409.1061031645854;414.59784009510764;415.17380412978184;423.67588794572157;20384.694229586567;20687.586248275027;21170.047347869346;21184.046717897694;21834;21194.940252987366;21495.355139345844;21653.45866353342;21662.24182153625;21671.56656866862;5144.176931690921;5959.910601340984;6081.847953801146;6086.847828804293;6109.450149486545;6019.488343490809;6116;6133.938660613404;6143.907841382383;6144.477719393857;5765.827025189251;6082.9391706082815;6084.634921904678;6091.116788065728;6091.604045737013;5826.708664566797;5995.940040599582;6078.635281883079;6095.3599872013465;6098.969505152446;4876.317315575822;5059.241113832918;5319.840404787863;5372.516473517363;5375.489328513785;20912.790872091387;25696;26572.069977550695;27174;27193.1842044737;2086.1863873089505;2326.720793504773;2371.845830021043;2404.723456802473;2619.593962935748;10096.646617368431;10633.46832658364;10754.784904301949;12204.511819527208;15713.92143039289;1696.6606678664316;1805.250820909321;1819.226828597854;1855.0910054073543;1859.7489338939206;115087;117403;119720.40139799332;121305;124415.42468890155;11614.477348519336;11677.941610291895;11786.173143162823;11805.137642056086;11855.585054523222;15616.375344986074;15688.294026768823;15695.68608627821;15711.68576628484;15730.764038539432;8908.599113039929;8935.731928042169;9064.728058158265;9099.954500227457;9192.218661413855;8861.73414797557;8986.955065224633;9001;9092.36353455265;9104;4689.413823272091;4769.236922092448;4769.976150119227;4802.975985120122;4841.15279826029;1686.047383228472;1725.2667616263038;1930.5366711989022;1957.8155216094342;1986.2055177928944;752.4695089961564;949.4730424614268;1008.2085562833141;1024.6311327921942;1093.7156339351825;18778.061096945097;19334.80665193358;20244.98775061241;20282.391528254175;20452.795472045385;967.9370840895319;968.8207681578931;976.345848281653;1015.3400289811682;1048.3447845096814;6294.590851594723;10811.513481893333;10857.185711071543;10906.890931090587;10954.507047182737;634036;636026;648009;661253;662101;114524;118897;120218.79781202252;121514.78485215035;124803;1001.7095042437758;1027.173125633872;1048.0933992096934;1049.0506091986613;1049.1082579807103;17644.647107058045;17775.288988440836;18150;18626.44120676382;18860.811391886455;52932;53181;53876.73061634667;54014.72992635012;57001;80004.59997699974;82792;82893.58553207196;84227;84614;8040.87938680932;8149.307308878811;8210.671573137008;8336.541490217855;8376.53929033909;10236.362182050836;11984.940075299568;12248.938755306166;12628.494860205488;12659.177153484994;8959.41763785352;10399.012093851072;10455.634052808115;10822.891771082188;10831.350118992885;9324.720258391988;10136.290459667753;10228.386296822215;10296.279260451696;10560.577576896838;10591.8940810594;10702.571897124028;10939.726506837074;11087.168462365193;11331.263467874562;8083.555404452812;8125.268725814705;8269.09040005587;8305.377096717892;8419.13331285385;8246.3815213858;9939.850902236327;10989.560417583207;11202.607908723156;11217.495212715285 06:14:07 INFO - PROCESS | 1975 | |3;mootools.html;1354.329606844612;1420.5454254638507;1584.1762283612518;1772.9734053989184;1964.4597735622701;7688.846223075535;8668.696595619154;8860.335474839396;8876.689315873946;8895.911040889589;8575.485470871752;8810.603522841471;8817.823643527141;8824.691135810248;8832.381733278673;2176.3688530326185;2250.954980900381;2251.8311126665567;2472.7279999200027;2499.250224932522;2256.4133325335447;2402.495475950044;2479.876006199692;2485.825992180548;2489.3029951613576;2415.7837848607505;2433.5862903306393;2444.022391043588;2449.3876530867283;2449.669294645218;2144.9142034318606;2486.1298545509017;2496.5381404440236;2498.362917456037;2500.4124030852645;2445.510897820443;2516.6979962404444;2522.5585522533484;2524.255344673326;2525.2045605634166;8733.912660873437;8876.245519130882;8889.422187557788;8889.866652000224;8897.73306800797;5387.838364849061;5535.142052981794;5553.278073850373;5583.4974852263085;5594;3417.7607567470286;3511.806850623215;3573.8391772370305;3574.2136729919375;3596.29872174926;996.1532697207389;1007.8891321954579;1012.6810054832778;1033.963811266602;1050.4957620342202;12159;12190.024798016226;12196.756064878742;12201.20692155007;12203.511859525608;4822.180229361017;4828.157102108375;4849.58778503824;4921.237208232729;4975.726335051607;10293.3823970562;10298.845517317246;10299.330543514647;10324.793504130026;10342.37945723259;5526.834194974157;5563.99848027359;5565.471280228372;5568.192612071308;5577.470140336662;4199.223143718422;4377.4528183977;4996.500349964975;4996.575291100297;5070.340855688811;4038.414429907647;4057.2494088593703;4118.670506359484;4118.858193739239;4124;1160.5763896178003;1331.973360532784;1361.4009835672273;1367.384676895401;1367.8153449284418;843.6751850537587;857.4298091769028;888.6756333938196;911.6262332443799;926.1849572376266;4894.706317620954;5895.616784908967;5995;6080.118382834465;6225.595336303126;991.2763682511807;992.9751756206008;994.5474808961905;1003.0420947994647;1035.880873699519;3901.843926242975;3975.1254723960683;3977.125032492847;4010.0977280111742;4018.236535058388;10855.891441085489;11166.497507612177;11177.83233251502;11264.66206013821;11266.324020558794;10961.506732197071;11002.064824490008;11104.167187460973;11125.221234513669;11131.777364452666;8411.831763364698;8549.829003420022;8577.528235947082;8660.047394786568;8669.393142480092;5460.5085542301385;5724.370319264961;5807.216025836458;5853.356130825691;5875.089361149029;9652.855207172039;9808.607655693691;10031.347962382422;10040.849387259052;10042.096211341015;9518.333716639769;10074.143697785477;10108.241881858743;10282.588696452058;10348.275620706478;6414.166158399378;6494.577852439576;6756;6766.18805743314;6872.862542749017;8073.39449541275;8295.626696798538;8312.459690120122;8323.250907418362;8323.916760832315;7323.707051718085;7416.369608583331;7427.8143046424275;7552.8489430212185;7554.6600402983095;7165.140183178052;7251.927480725125;7285.490015698851;7329.157146928172;7356.558606483628;6872.69072891711;7004.4396448283;7009;7011.1586609607175;7013.473989450709;4839.95160048395;4860;4865.099956508057;4965.925511117408;5007.84976450707;4824.734639594715;4858.805647774049;4862.222044473009;4930.531599498042;5098.5411312982005 06:14:07 INFO - PROCESS | 1975 | |4;prototype.html;318.29338867713676;323.97570182236336;328.41869890294174;332.9617094034187;337.10498626147586;6668.299828518008;6685.899711504325;6724.260331363552;6759.56062855914;6763.729450821961;6688.398044176023;6710.798676039714;6719.227288861783;6721.260661327262;6721.731130754764;4756.120117778205;4883.560479556839;4884.658073934826;4884.804607815683;4936.284238785374;4868;4920;4971.850844474671;4975.4527002029745;4995.725235112068;4844.103840789465;4895.510448955111;4899.289603007562;4918.139325618021;4993.051320249141;4977.800887964477;4981.27771473138;4985.999999999982;4992.151334273164;4994.55049045584;4047.736897101679;4138.937915931264;4209.2423363794505;4219.11398606293;4242.957570424287;6400.679966001681;6450.7097180626515;6454.354564543555;6470.288268290485;6472.805815825533;5859.1797148399255;5866.442687944638;5876.118582212703;5893.7053147342895;5896.056630939072;5868.706564671749;7124.5725256484775;7141.857162856766;7145.285471452865;7146.106736657918;4082.4692789937417;4576.1762882681105;4616.6537509686905;4690.9530904690855;4761.119192949315;7411.7035318587195;7440;7474.962625186839;7504.437167212481;7513.511621744569;4876.82931097417;4878.146324393217;4884.242942343943;4890.388701412324;4895.241237608177;4988.7505624718615;4996.675216110941;5012.749362531858;5016.949830501721;5023.8492845214705;5905.616134951214;5912.940870591239;5925.170476133345;5926.436988486087;5927.585069045212;5746.2529871116485;6920.411765000032;7176.210616832144;7206.135263768277;7208.5490933083;1715.6997525432998;1724.741288806664;1739.365131726936;1740.4517576963217;1744.1017875793975;900.5137225898072;1105.264998775805;1127.2785417332914;1128.041165009735;1136.5169802833764;6382.808515744535;6542.607443553402;6617.7683781067435;6635.9336406636285;6651.707404834541;5808.361080281165;5961.552883533753;6414.941534646731;7370.410367170613;7389.408847292204;5447.891042179134;6304.621722696653;6665.466762658975;6672.466202703772;6685.097511835937;3896.103896103912;3922.607739226112;3925.6074392560517;3932.2135572884963;3937.586553411908;7602.581857997752;7867.134615192324;7895;7901.68393264263;7919.445638805229;7661.506006328824;7731.845363092707;7743.148253692089;7750.186230445722;7768.300852923264;7877;7884.763457096296;7911.208879112043;7913.841723165389;7927.722529711547;7415;7433.8141546461775;7438.70245190208;7444.069491313586;7461.776146715607;7064.717411303696;7304.817379565553;7327.120745510573;7333.779986600411;7337.853242935218;7879.960600196962;7916.129225785274;7964.283214510722;7978.002749656293;7978.4415090945395;4687.414073240845;4744.312074749073;4744.312074749211;4745.240761478147;4752.9049419010735;7457.179710231761;7460.142083660231;7460.850782984418;7470.887936680845;7479.513831600929;6958.199807022257;7159.8926016110845;7230.566166030055;7238.674259658223;7270.745523906638;5854.795082172103;7102.360787529147;7156.534825236343;7176.928230717626;7177.856442871218;6370.331115232834;6424.486041116793;7057.752978645723;7068.257832927468;7076.433885289268;6924.58452492852;7177.964110179416;7178.676959536938;7180.676869540779;7203;6744.258131605618;7043.36609705129;7046.146310744157;7050.189228238613;7077.5753454792875 06:14:07 INFO - PROCESS | 1975 | |5;yui.html;667;734;757.9279968403;767.5241350362775;770.5261264322444;15103.471378501754;15221.923890380547;15437.382504699812;16204.918975405106;16212.837871621281;14783.260836958136;14892.032017918855;14909.627259318522;14926.179060151688;14933.178675172861;907.2424525521197;914.4330515080659;922.5156792683828;922.8154369126169;925.4447331601019;735.4484136897327;746.817029827693;914.9862752058724;930.2743859789375;942.1426501883287;979.1921664626676;982.4301904895179;982.459647194044;984.7193549838297;985.2955137076989;975.3513913247717;977.3647129365897;978.4080631218118;978.4765150644407;979.177490907641;578.8697543052822;589.0780927847904;589.2310534752147;590.7459792289313;720.79097061852;15214.923925380413;15282.159481228528;15292.541223763305;15315.770263446057;15320.770188447183;12501.3749312534;12634.11561190717;12796.61610151697;12819.423125959262;13054.412551435113;9790.363626364264;9835.754106147331;9954.452505112216;10168.542415591317;10576.629817956411;3446.3968805459194;3464.9480257796154;3527.841247143859;3566.8394922228563;3611.3860643690637;85167;85747;86144.27711445736;86184.27631447336;86428;9248.26013918885;9565.521723913776;9657.71026869181;9854.802903941882;10414.687559373231;19765.505862353268;19973.700394494095;20006.59986800285;20034.799652003585;20091;6249.9062514062325;6385.872282554323;7179.748708795271;7447.143578488543;7629.198934111839;4067.8576249831112;4068.7558746475306;4095.795210239476;4134.028503301722;4149.232392007489;3484.4424892017664;3519.7360197985254;3562.946555801665;3563.893083207508;3573.535440392732;544.8748334688934;553.3967974907371;644.9709763060673;656.0290769660833;661.9772451562347;552.9668219906819;576.9307683077974;583.7519054401862;590.9763609455574;609.960352577081;7977.3618110551015;8150.2664760171865;8177.877331840027;8378.748637540884;8388.790280243044;600.2287061126432;605.2071785960318;609.7621927448299;611.9602225855394;617.7343742190901;3669.3395188865725;3713.9814300928324;3785.564660064073;3951.504710419377;4015.297322968469;91877;92802.53598731963;93627.0637293632;93810;94363;78516;78889.60555197188;80549.59725201454;81407.18592814115;82173;70734.64632676907;71508.64245678738;71639;71925.64037179781;73149.26850731531;7737.187595302412;7776.416768742254;7796.151368197024;7844.333231675371;7864.5281283123195;48330.5166948326;48914.021719566124;49157;49353.76615584639;50093.49906501034;62923.6853815728;66717.66641166763;67604.98592521214;68168.6591567039;69340.30659693338;9702.320837541305;9809.705708828747;9983.550740216853;10002.399856008662;10335.844962325722;10462.947685261524;10565.366078035342;10708.678739637824;10899.237053406185;11691.532338706356;10147.137493312855;10280.588776448858;10489.265751397328;10554.472276386303;10562.31344962575;9963.701088967342;10137.442440665835;10507.833872171102;10515.94742026285;10578.312409693346;9785.36395134314;10219.28465007472;10306.639267625897;10364.948175259075;10484.895151048391;8800.82090518728;8843.955780221058;9006.909930900607;9155.084491550791;9238.491882946233;8364.790880228044;8873.911260887568;8911.598978045873;8973.237274831712;8975.730728078168 06:14:07 INFO - PROCESS | 1975 | __end_tp_report 06:14:07 INFO - PROCESS | 1975 | __start_cc_report 06:14:07 INFO - PROCESS | 1975 | _x_x_mozilla_cycle_collect,1120 06:14:07 INFO - PROCESS | 1975 | __end_cc_report 06:14:07 INFO - PROCESS | 1975 | __startTimestamp1462799647673__endTimestamp 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | ------- Summary: start ------- 06:14:07 INFO - PROCESS | 1975 | Number of tests: 6 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | [#0] dojo.html Cycles:165 Average:5806.55 Median:6050.88 stddev:653.31 (10.8%) stddev-sans-first:626.97 06:14:07 INFO - PROCESS | 1975 | Values: 3379.8 3481.4 3484.6 3513.2 3543.1 5770.4 5776.0 5792.4 5799.4 5803.5 5768.7 5807.4 5809.2 5815.8 5831.4 5211.1 5239.5 5264.7 5339.3 5366.8 5350.7 5364.9 5365.6 5368.4 5374.9 4720.7 5324.9 5331.0 5335.5 5375.9 5291.2 5324.8 5331.7 5334.1 5371.9 4320.8 5111.1 5175.2 5193.2 5225.5 5685.1 5712.1 5730.6 5734.9 5745.1 6299.6 6310.7 6333.2 6335.8 6337.9 5825.8 6240.8 6306.9 6327.5 6335.4 5900.5 5903.9 5908.9 5910.4 5935.6 6386.6 6393.8 6397.3 6403.0 6408.9 6090.9 6177.2 6275.2 6299.5 6310.6 5277.9 6320.4 6339.3 6340.9 6342.9 6243.3 6263.0 6296.1 6324.7 6346.2 6182.0 6279.6 6282.8 6293.1 6325.0 4957.9 4977.6 4978.8 5006.7 5009.8 4499.8 4500.1 4517.7 4526.2 4526.9 5775.4 5790.6 5802.9 5810.4 5810.8 5187.7 6228.4 6243.7 6258.5 6284.6 5752.9 5772.3 5781.0 5812.4 5818.4 5483.3 5491.6 5493.6 5496.8 5502.4 6231.5 6411.0 6425.6 6430.6 6507.2 6252.3 6288.5 6304.9 6316.8 6346.0 6481.4 6512.0 6517.6 6525.3 6526.2 6221.9 6257.7 6280.9 6308.6 6324.4 6205.8 6216.2 6311.9 6312.1 6322.5 6180.5 6186.2 6186.9 6190.9 6208.0 6108.8 6157.3 6176.2 6180.6 6206.2 5929.4 6031.0 6050.9 6134.3 6138.6 4925.7 6226.1 6226.7 6230.5 6234.0 5980.9 6093.9 6111.4 6114.6 6121.8 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | [#1] ext.html Cycles:180 Average:67984.26 Median:36295.96 stddev:147599.47 (406.7%) stddev-sans-first:147926.61 06:14:07 INFO - PROCESS | 1975 | Values: 521.3 536.1 545.0 583.7 613.1 31726.4 31935.7 32201.0 39078.8 39990.2 45195.0 45607.8 45821.8 45883.9 46100.1 9577.7 9799.1 9806.5 9811.9 9824.2 7538.0 8565.3 11489.8 11687.9 11713.1 11416.3 11505.8 11510.9 11512.5 11567.3 7300.6 11435.4 11459.6 11537.8 11537.9 11456.7 11588.4 11588.7 11589.7 11615.8 39315.4 39856.4 39869.6 39920.0 39928.8 3009.7 3235.6 3264.6 3403.6 3460.8 28125.4 28885.3 29506.0 29864.9 30058.0 2763.0 2771.3 2787.4 2789.7 2811.3 458375.0 461085.0 462725.0 463196.0 464013.7 36620.8 64025.4 64552.4 64672.0 64674.4 36240.8 36351.1 36369.0 36424.5 36455.5 15215.3 15516.5 15520.6 15521.6 15521.7 12441.5 12608.9 13175.2 13233.5 13248.5 8162.9 8196.4 8202.0 8243.8 8279.2 3391.8 3422.4 3424.1 3427.8 3457.3 968.4 988.0 1025.0 1103.9 1119.7 29741.8 30307.5 30575.6 30727.8 30733.9 1137.7 1143.0 1171.0 1177.8 1179.2 13241.1 15073.8 15322.3 15651.7 15851.0 593674.0 597811.0 603568.0 605097.0 606050.0 62688.4 63177.7 63303.7 63370.7 63682.7 1347.9 1382.5 1484.7 1495.9 1523.1 29372.6 29846.7 31258.4 31288.0 31404.7 45516.0 54456.0 55459.6 55615.7 55705.4 561846.0 588429.0 589193.0 589574.0 589645.0 33486.5 36534.7 41415.4 44488.1 45127.0 44584.6 45325.5 45589.3 46829.3 47151.8 35986.9 38605.2 38880.4 39209.8 39985.8 38165.6 38447.0 38627.0 38963.6 39137.0 37839.2 38234.0 38336.2 38425.4 38504.4 25717.9 32015.5 33147.5 33886.3 33926.7 25070.0 25380.4 25399.5 25911.9 25929.0 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | [#2] jquery.html Cycles:180 Average:36442.73 Median:21752.78 stddev:107686.52 (495.0%) stddev-sans-first:107954.62 06:14:07 INFO - PROCESS | 1975 | Values: 408.9 409.1 414.6 415.2 423.7 20384.7 20687.6 21170.0 21184.0 21834.0 21194.9 21495.4 21653.5 21662.2 21671.6 5144.2 5959.9 6081.8 6086.8 6109.5 6019.5 6116.0 6133.9 6143.9 6144.5 5765.8 6082.9 6084.6 6091.1 6091.6 5826.7 5995.9 6078.6 6095.4 6099.0 4876.3 5059.2 5319.8 5372.5 5375.5 20912.8 25696.0 26572.1 27174.0 27193.2 2086.2 2326.7 2371.8 2404.7 2619.6 10096.6 10633.5 10754.8 12204.5 15713.9 1696.7 1805.3 1819.2 1855.1 1859.7 115087.0 117403.0 119720.4 121305.0 124415.4 11614.5 11677.9 11786.2 11805.1 11855.6 15616.4 15688.3 15695.7 15711.7 15730.8 8908.6 8935.7 9064.7 9100.0 9192.2 8861.7 8987.0 9001.0 9092.4 9104.0 4689.4 4769.2 4770.0 4803.0 4841.2 1686.0 1725.3 1930.5 1957.8 1986.2 752.5 949.5 1008.2 1024.6 1093.7 18778.1 19334.8 20245.0 20282.4 20452.8 967.9 968.8 976.3 1015.3 1048.3 6294.6 10811.5 10857.2 10906.9 10954.5 634036.0 636026.0 648009.0 661253.0 662101.0 114524.0 118897.0 120218.8 121514.8 124803.0 1001.7 1027.2 1048.1 1049.1 1049.1 17644.6 17775.3 18150.0 18626.4 18860.8 52932.0 53181.0 53876.7 54014.7 57001.0 80004.6 82792.0 82893.6 84227.0 84614.0 8040.9 8149.3 8210.7 8336.5 8376.5 10236.4 11984.9 12248.9 12628.5 12659.2 8959.4 10399.0 10455.6 10822.9 10831.4 9324.7 10136.3 10228.4 10296.3 10560.6 10591.9 10702.6 10939.7 11087.2 11331.3 8083.6 8125.3 8269.1 8305.4 8419.1 8246.4 9939.9 10989.6 11202.6 11217.5 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | [#3] mootools.html Cycles:180 Average:5772.05 Median:4859.40 stddev:3258.11 (67.0%) stddev-sans-first:3250.33 06:14:07 INFO - PROCESS | 1975 | Values: 1354.3 1420.5 1584.2 1773.0 1964.5 7688.8 8668.7 8860.3 8876.7 8895.9 8575.5 8810.6 8817.8 8824.7 8832.4 2176.4 2251.0 2251.8 2472.7 2499.3 2256.4 2402.5 2479.9 2485.8 2489.3 2415.8 2433.6 2444.0 2449.4 2449.7 2144.9 2486.1 2496.5 2498.4 2500.4 2445.5 2516.7 2522.6 2524.3 2525.2 8733.9 8876.2 8889.4 8889.9 8897.7 5387.8 5535.1 5553.3 5583.5 5594.0 3417.8 3511.8 3573.8 3574.2 3596.3 996.2 1007.9 1012.7 1034.0 1050.5 12159.0 12190.0 12196.8 12201.2 12203.5 4822.2 4828.2 4849.6 4921.2 4975.7 10293.4 10298.8 10299.3 10324.8 10342.4 5526.8 5564.0 5565.5 5568.2 5577.5 4199.2 4377.5 4996.5 4996.6 5070.3 4038.4 4057.2 4118.7 4118.9 4124.0 1160.6 1332.0 1361.4 1367.4 1367.8 843.7 857.4 888.7 911.6 926.2 4894.7 5895.6 5995.0 6080.1 6225.6 991.3 993.0 994.5 1003.0 1035.9 3901.8 3975.1 3977.1 4010.1 4018.2 10855.9 11166.5 11177.8 11264.7 11266.3 10961.5 11002.1 11104.2 11125.2 11131.8 8411.8 8549.8 8577.5 8660.0 8669.4 5460.5 5724.4 5807.2 5853.4 5875.1 9652.9 9808.6 10031.3 10040.8 10042.1 9518.3 10074.1 10108.2 10282.6 10348.3 6414.2 6494.6 6756.0 6766.2 6872.9 8073.4 8295.6 8312.5 8323.3 8323.9 7323.7 7416.4 7427.8 7552.8 7554.7 7165.1 7251.9 7285.5 7329.2 7356.6 6872.7 7004.4 7009.0 7011.2 7013.5 4840.0 4860.0 4865.1 4965.9 5007.8 4824.7 4858.8 4862.2 4930.5 5098.5 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | [#4] prototype.html Cycles:180 Average:5863.85 Median:6580.19 stddev:1863.54 (28.3%) stddev-sans-first:1821.69 06:14:07 INFO - PROCESS | 1975 | Values: 318.3 324.0 328.4 333.0 337.1 6668.3 6685.9 6724.3 6759.6 6763.7 6688.4 6710.8 6719.2 6721.3 6721.7 4756.1 4883.6 4884.7 4884.8 4936.3 4868.0 4920.0 4971.9 4975.5 4995.7 4844.1 4895.5 4899.3 4918.1 4993.1 4977.8 4981.3 4986.0 4992.2 4994.6 4047.7 4138.9 4209.2 4219.1 4243.0 6400.7 6450.7 6454.4 6470.3 6472.8 5859.2 5866.4 5876.1 5893.7 5896.1 5868.7 7124.6 7141.9 7145.3 7146.1 4082.5 4576.2 4616.7 4691.0 4761.1 7411.7 7440.0 7475.0 7504.4 7513.5 4876.8 4878.1 4884.2 4890.4 4895.2 4988.8 4996.7 5012.7 5016.9 5023.8 5905.6 5912.9 5925.2 5926.4 5927.6 5746.3 6920.4 7176.2 7206.1 7208.5 1715.7 1724.7 1739.4 1740.5 1744.1 900.5 1105.3 1127.3 1128.0 1136.5 6382.8 6542.6 6617.8 6635.9 6651.7 5808.4 5961.6 6414.9 7370.4 7389.4 5447.9 6304.6 6665.5 6672.5 6685.1 3896.1 3922.6 3925.6 3932.2 3937.6 7602.6 7867.1 7895.0 7901.7 7919.4 7661.5 7731.8 7743.1 7750.2 7768.3 7877.0 7884.8 7911.2 7913.8 7927.7 7415.0 7433.8 7438.7 7444.1 7461.8 7064.7 7304.8 7327.1 7333.8 7337.9 7880.0 7916.1 7964.3 7978.0 7978.4 4687.4 4744.3 4744.3 4745.2 4752.9 7457.2 7460.1 7460.9 7470.9 7479.5 6958.2 7159.9 7230.6 7238.7 7270.7 5854.8 7102.4 7156.5 7176.9 7177.9 6370.3 6424.5 7057.8 7068.3 7076.4 6924.6 7178.0 7178.7 7180.7 7203.0 6744.3 7043.4 7046.1 7050.2 7077.6 06:14:07 INFO - PROCESS | 1975 | 06:14:07 INFO - PROCESS | 1975 | [#5] yui.html Cycles:180 Average:18342.53 Median:34654.78 stddev:26364.07 (76.1%) stddev-sans-first:26404.62 06:14:07 INFO - PROCESS | 1975 | Values: 667.0 734.0 757.9 767.5 770.5 15103.5 15221.9 15437.4 16204.9 16212.8 14783.3 14892.0 14909.6 14926.2 14933.2 907.2 914.4 922.5 922.8 925.4 735.4 746.8 915.0 930.3 942.1 979.2 982.4 982.5 984.7 985.3 975.4 977.4 978.4 978.5 979.2 578.9 589.1 589.2 590.7 720.8 15214.9 15282.2 15292.5 15315.8 15320.8 12501.4 12634.1 12796.6 12819.4 13054.4 9790.4 9835.8 9954.5 10168.5 10576.6 3446.4 3464.9 3527.8 3566.8 3611.4 85167.0 85747.0 86144.3 86184.3 86428.0 9248.3 9565.5 9657.7 9854.8 10414.7 19765.5 19973.7 20006.6 20034.8 20091.0 6249.9 6385.9 7179.7 7447.1 7629.2 4067.9 4068.8 4095.8 4134.0 4149.2 3484.4 3519.7 3562.9 3563.9 3573.5 544.9 553.4 645.0 656.0 662.0 553.0 576.9 583.8 591.0 610.0 7977.4 8150.3 8177.9 8378.7 8388.8 600.2 605.2 609.8 612.0 617.7 3669.3 3714.0 3785.6 3951.5 4015.3 91877.0 92802.5 93627.1 93810.0 94363.0 78516.0 78889.6 80549.6 81407.2 82173.0 70734.6 71508.6 71639.0 71925.6 73149.3 7737.2 7776.4 7796.2 7844.3 7864.5 48330.5 48914.0 49157.0 49353.8 50093.5 62923.7 66717.7 67605.0 68168.7 69340.3 9702.3 9809.7 9983.6 10002.4 10335.8 10462.9 10565.4 10708.7 10899.2 11691.5 10147.1 10280.6 10489.3 10554.5 10562.3 9963.7 10137.4 10507.8 10515.9 10578.3 9785.4 10219.3 10306.6 10364.9 10484.9 8800.8 8844.0 9006.9 9155.1 9238.5 8364.8 8873.9 8911.6 8973.2 8975.7 06:14:07 INFO - PROCESS | 1975 | -------- Summary: end -------- 06:14:07 INFO - PROCESS | 1975 | 06:14:08 INFO - TEST-INFO | 1975: exit 0 06:14:08 INFO - TEST-OK | dromaeo_css | took 1114995ms 06:14:08 INFO - TEST-START | kraken 06:14:08 INFO - operating with platform_type : mac_ 06:14:08 INFO - Initialising browser for kraken test... 06:14:08 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpxGBT8S/profile http://localhost:49523/getInfo.html) 06:14:10 INFO - PROCESS | 1978 | __metrics Screen width/height:1600/1200 06:14:10 INFO - PROCESS | 1978 | colorDepth:24 06:14:10 INFO - PROCESS | 1978 | Browser inner width/height: 1024/689 06:14:10 INFO - PROCESS | 1978 | __metrics 06:14:10 INFO - PROCESS | 1978 | 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" 06:14:10 ERROR - PROCESS | 1978 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:14:11 INFO - TEST-INFO | 1978: exit 0 06:14:11 INFO - Browser initialized. 06:14:11 INFO - Running cycle 1/1 for kraken test... 06:14:11 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpxGBT8S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:23 INFO - PROCESS | 1980 | RSS: Main: 211111936 06:14:23 INFO - PROCESS | 1980 | 06:14:25 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-beat-detection) 06:14:26 INFO - PROCESS | 1980 | RSS: Main: 271327232 06:14:26 INFO - PROCESS | 1980 | 06:14:29 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-dft) 06:14:30 INFO - PROCESS | 1980 | RSS: Main: 285958144 06:14:30 INFO - PROCESS | 1980 | 06:14:33 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-fft) 06:14:33 INFO - PROCESS | 1980 | RSS: Main: 372682752 06:14:33 INFO - PROCESS | 1980 | 06:14:35 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-oscillator) 06:14:36 INFO - PROCESS | 1980 | RSS: Main: 278908928 06:14:36 INFO - PROCESS | 1980 | 06:14:38 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49523/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:14:39 INFO - PROCESS | 1980 | RSS: Main: 280526848 06:14:39 INFO - PROCESS | 1980 | 06:14:41 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49523/tests/kraken/driver.html?testName=imaging-darkroom) 06:14:42 INFO - PROCESS | 1980 | RSS: Main: 318210048 06:14:42 INFO - PROCESS | 1980 | 06:14:45 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49523/tests/kraken/driver.html?testName=imaging-desaturate) 06:14:45 INFO - PROCESS | 1980 | RSS: Main: 319242240 06:14:45 INFO - PROCESS | 1980 | 06:14:48 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49523/tests/kraken/driver.html?testName=json-parse-financial) 06:14:49 INFO - PROCESS | 1980 | RSS: Main: 316850176 06:14:49 INFO - PROCESS | 1980 | 06:14:51 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49523/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:14:51 INFO - PROCESS | 1980 | RSS: Main: 309227520 06:14:51 INFO - PROCESS | 1980 | 06:14:53 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:14:54 INFO - PROCESS | 1980 | RSS: Main: 315953152 06:14:54 INFO - PROCESS | 1980 | 06:14:56 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:57 INFO - PROCESS | 1980 | RSS: Main: 318414848 06:14:57 INFO - PROCESS | 1980 | 06:14:59 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:15:00 INFO - PROCESS | 1980 | RSS: Main: 323678208 06:15:00 INFO - PROCESS | 1980 | 06:15:03 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:03 INFO - PROCESS | 1980 | RSS: Main: 321740800 06:15:03 INFO - PROCESS | 1980 | 06:15:05 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49523/tests/kraken/driver.html?testName=ai-astar) 06:15:06 INFO - PROCESS | 1980 | RSS: Main: 386842624 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | __start_tp_report 06:15:06 INFO - PROCESS | 1980 | _x_x_mozilla_page_load 06:15:06 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details 06:15:06 INFO - PROCESS | 1980 | |i|pagename|runs| 06:15:06 INFO - PROCESS | 1980 | |0;ai-astar;113;121;175;158;112;111;113;114;113;136 06:15:06 INFO - PROCESS | 1980 | |1;audio-beat-detection;160;147;176;159;119;145;118;150;154;110 06:15:06 INFO - PROCESS | 1980 | |2;audio-dft;185;185;185;186;187;184;185;186;185;183 06:15:06 INFO - PROCESS | 1980 | |3;audio-fft;69;78;75;151;57;54;59;61;137;55 06:15:06 INFO - PROCESS | 1980 | |4;audio-oscillator;90;92;142;76;81;83;126;80;76;124 06:15:06 INFO - PROCESS | 1980 | |5;imaging-gaussian-blur;86;84;87;84;86;83;91;130;120;83 06:15:06 INFO - PROCESS | 1980 | |6;imaging-darkroom;102;93;94;93;92;92;93;93;91;92 06:15:06 INFO - PROCESS | 1980 | |7;imaging-desaturate;87;85;84;86;84;86;104;140;103;83 06:15:06 INFO - PROCESS | 1980 | |8;json-parse-financial;61;64;67;64;66;63;63;79;59;59 06:15:06 INFO - PROCESS | 1980 | |9;json-stringify-tinderbox;66;65;73;81;77;73;69;70;70;71 06:15:06 INFO - PROCESS | 1980 | |10;stanford-crypto-aes;81;81;81;81;103;99;103;80;82;85 06:15:06 INFO - PROCESS | 1980 | |11;stanford-crypto-ccm;144;147;143;124;127;123;126;124;166;165 06:15:06 INFO - PROCESS | 1980 | |12;stanford-crypto-pbkdf2;160;156;167;178;166;166;165;165;165;163 06:15:06 INFO - PROCESS | 1980 | |13;stanford-crypto-sha256-iterative;70;79;96;94;93;74;66;67;65;64 06:15:06 INFO - PROCESS | 1980 | __end_tp_report 06:15:06 INFO - PROCESS | 1980 | __start_cc_report 06:15:06 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,1983 06:15:06 INFO - PROCESS | 1980 | __end_cc_report 06:15:06 INFO - PROCESS | 1980 | __startTimestamp1462799706015__endTimestamp 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | ------- Summary: start ------- 06:15:06 INFO - PROCESS | 1980 | Number of tests: 14 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#0] ai-astar Cycles:10 Average:126.60 Median:117.50 stddev:22.65 (19.3%) stddev-sans-first:23.49 06:15:06 INFO - PROCESS | 1980 | Values: 113.0 121.0 175.0 158.0 112.0 111.0 113.0 114.0 113.0 136.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:152.00 stddev:21.36 (14.1%) stddev-sans-first:21.84 06:15:06 INFO - PROCESS | 1980 | Values: 160.0 147.0 176.0 159.0 119.0 145.0 118.0 150.0 154.0 110.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.00 stddev:1.10 (0.6%) stddev-sans-first:1.17 06:15:06 INFO - PROCESS | 1980 | Values: 185.0 185.0 185.0 186.0 187.0 184.0 185.0 186.0 185.0 183.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#3] audio-fft Cycles:10 Average:79.60 Median:60.00 stddev:35.07 (58.5%) stddev-sans-first:36.99 06:15:06 INFO - PROCESS | 1980 | Values: 69.0 78.0 75.0 151.0 57.0 54.0 59.0 61.0 137.0 55.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:80.50 stddev:24.25 (30.1%) stddev-sans-first:25.59 06:15:06 INFO - PROCESS | 1980 | Values: 90.0 92.0 142.0 76.0 81.0 83.0 126.0 80.0 76.0 124.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:85.00 stddev:16.98 (20.0%) stddev-sans-first:17.80 06:15:06 INFO - PROCESS | 1980 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 91.0 130.0 120.0 83.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.00 stddev:3.10 (3.3%) stddev-sans-first:0.88 06:15:06 INFO - PROCESS | 1980 | Values: 102.0 93.0 94.0 93.0 92.0 92.0 93.0 93.0 91.0 92.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:84.50 stddev:17.87 (21.2%) stddev-sans-first:18.77 06:15:06 INFO - PROCESS | 1980 | Values: 87.0 85.0 84.0 86.0 84.0 86.0 104.0 140.0 103.0 83.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:5.74 (9.0%) stddev-sans-first:5.95 06:15:06 INFO - PROCESS | 1980 | Values: 61.0 64.0 67.0 64.0 66.0 63.0 63.0 79.0 59.0 59.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:4.81 (6.7%) stddev-sans-first:4.68 06:15:06 INFO - PROCESS | 1980 | Values: 66.0 65.0 73.0 81.0 77.0 73.0 69.0 70.0 70.0 71.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:81.00 stddev:9.86 (12.2%) stddev-sans-first:10.16 06:15:06 INFO - PROCESS | 1980 | Values: 81.0 81.0 81.0 81.0 103.0 99.0 103.0 80.0 82.0 85.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:143.50 stddev:16.76 (11.7%) stddev-sans-first:17.68 06:15:06 INFO - PROCESS | 1980 | Values: 144.0 147.0 143.0 124.0 127.0 123.0 126.0 124.0 166.0 165.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:165.50 stddev:5.63 (3.4%) stddev-sans-first:5.66 06:15:06 INFO - PROCESS | 1980 | Values: 160.0 156.0 167.0 178.0 166.0 166.0 165.0 165.0 165.0 163.0 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - PROCESS | 1980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:76.50 stddev:12.92 (16.9%) stddev-sans-first:13.46 06:15:06 INFO - PROCESS | 1980 | Values: 70.0 79.0 96.0 94.0 93.0 74.0 66.0 67.0 65.0 64.0 06:15:06 INFO - PROCESS | 1980 | -------- Summary: end -------- 06:15:06 INFO - PROCESS | 1980 | 06:15:06 INFO - TEST-INFO | 1980: exit 0 06:15:06 INFO - TEST-OK | kraken | took 58476ms 06:15:06 INFO - SUITE-END | took 1173s 06:15:07 INFO - Completed test suite (00:19:33) 06:15:07 INFO - javascript benchmark 06:15:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3379.8479068441916, 3481.3559491494066, 3484.6166921638614, 3513.1919658478564, 3543.114221444639, 5770.422957704228, 5776.018076926922, 5792.420757924206, 5799.420057994198, 5803.477687008174, 5768.682722450264, 5807.361190269067, 5809.186713860063, 5815.796447124351, 5831.358550559435, 5211.114110601188, 5239.476052394749, 5264.710440925747, 5339.279197308372, 5366.838994830142, 5350.732463376835, 5364.946350536503, 5365.624406291561, 5368.382635996852, 5374.865628359284, 4720.669553131283, 5324.866878328034, 5331.0, 5335.5198032177095, 5375.865603359908, 5291.1798671206025, 5324.840254792362, 5331.706756128411, 5334.146536554172, 5371.865703357408, 4320.805563749639, 5111.08000559899, 5175.171972484422, 5193.169092945149, 5225.4513276105845, 5685.061964775807, 5712.11462223356, 5730.570207234474, 5734.856628584277, 5745.109508026263, 6299.622022678608, 6310.684465776692, 6333.240011198685, 6335.8416039598915, 6337.904931426032, 5825.79609713658, 6240.750369985195, 6306.873862522724, 6327.4938004959495, 6335.3981371769605, 5900.468957793819, 5903.852403689943, 5908.852278693067, 5910.408959104098, 5935.614185077956, 6386.616802991835, 6393.817143828406, 6397.26431460388, 6403.0, 6408.903866442007, 6090.869183494339, 6177.227846519185, 6275.215598050244, 6299.464545513682, 6310.62136271816, 5277.894442111136, 6320.399562041599, 6339.270983836917, 6340.873182536324, 6342.904856427157, 6243.344448832898, 6263.0, 6296.087067375205, 6324.71538780756, 6346.238451385863, 6182.0, 6279.591826531261, 6282.842928926722, 6293.118963345135, 6325.0, 4957.90084198314, 4977.552020318189, 4978.77595508203, 5006.69959802413, 5009.824656137018, 4499.797509112098, 4500.144972455223, 4517.683762136685, 4526.20791361517, 4526.909461810811, 5775.422457754191, 5790.565707571865, 5802.912956305659, 5810.360860305363, 5810.767569297181, 5187.740612969336, 6228.439440450381, 6243.687815609202, 6258.530610204162, 6284.622922624657, 5752.884942301131, 5772.336181339116, 5781.017227071408, 5812.418758124154, 5818.389069147764, 5483.259759932438, 5491.588130890199, 5493.615446918678, 5496.835094947158, 5502.449755024546, 6231.501479881598, 6411.0383442484, 6425.5502114851515, 6430.64631445275, 6507.186601674791, 6252.343503932022, 6288.528360373082, 6304.873902522016, 6316.842078948063, 6345.968270158804, 6481.4166724994975, 6511.967440162769, 6517.608943463408, 6525.347465253627, 6526.184226971629, 6221.9377806221355, 6257.655828929271, 6280.905786413299, 6308.621482711052, 6324.3991820776955, 6205.782797602063, 6216.160818289654, 6311.90532142009, 6312.05319202105, 6322.525810564134, 6180.536459765445, 6186.1648677427975, 6186.907196391968, 6190.938090619216, 6207.968960155351, 6108.840825276481, 6157.261128664589, 6176.227971503562, 6180.598261113013, 6206.224221972254, 5929.407059294036, 6031.035034394476, 6050.878982420415, 6134.325224225242, 6138.572327483616, 4925.729084900365, 6226.066090086342, 6226.688665566794, 6230.532710046855, 6233.96883015582, 5980.9401905980385, 6093.9390606093375, 6111.388861113853, 6114.602550834182, 6121.84695382619], "value": 5766.23143554893, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.332694151486, 536.1261144334734, 544.9809256676017, 583.6556431705293, 613.0987448450776, 31726.365472690533, 31935.680643193562, 32201.0, 39078.804605977, 39990.20019599606, 45195.0, 45607.77196114015, 45821.77089114558, 45883.85290367743, 46100.077998440094, 9577.664781732641, 9799.118079372854, 9806.460644664576, 9811.852822207673, 9824.214062874953, 7538.020057392531, 8565.271951884097, 11489.770204595903, 11687.941560292229, 11713.1215158863, 11416.315021098719, 11505.769884602303, 11510.884891151107, 11512.481938312796, 11567.305961642329, 7300.561966281987, 11435.371054591995, 11459.598914038052, 11537.826932596017, 11537.942310288396, 11456.65630031092, 11588.420578971018, 11588.6523404298, 11589.710257243552, 11615.767684646344, 39315.41026884599, 39856.40215396742, 39869.60130398717, 39920.0, 39928.800355998035, 3009.7058264946063, 3235.5955505561806, 3264.6408895021527, 3403.6085850127306, 3460.757746957714, 28125.43749125006, 28885.27786805347, 29506.0, 29864.8506757467, 30058.0, 2762.9622286110716, 2771.3348796288947, 2787.4007088476073, 2789.748922596976, 2811.3252819323216, 458374.9999999967, 461085.0, 462725.0, 463196.0, 464013.6799315982, 36620.81689591535, 64025.35974640194, 64552.35447645463, 64672.0, 64674.35325646777, 36240.81879590585, 36351.091222719115, 36369.0, 36424.453633195524, 36455.4531682025, 15215.31531081104, 15516.456924007605, 15520.611984700246, 15521.611959700873, 15521.689566208612, 12441.502339906483, 12608.873911260953, 13175.209487430784, 13233.536826211037, 13248.470061197637, 8162.918370816215, 8196.42625016255, 8202.0, 8243.793905152299, 8279.172082791672, 3391.762576619613, 3422.418188907892, 3424.126847653832, 3427.845746941394, 3457.2739724658104, 968.4044312747609, 987.980240395188, 1024.9743756406149, 1103.8896110388896, 1119.652907598647, 29741.810327587085, 30307.545386819216, 30575.624096915693, 30727.811271559385, 30733.92431264895, 1137.6530158301632, 1143.0, 1170.988290117105, 1177.8468799056068, 1179.204037274836, 13241.140192688947, 15073.849261507245, 15322.310496027705, 15651.686966260611, 15851.048937063813, 593674.0, 597811.0, 603568.0, 605097.0, 606050.0, 62688.37311626917, 63177.68411157915, 63303.68348158322, 63370.68314658397, 63682.72634547283, 1347.9258640774656, 1382.5022991722772, 1484.7104814561058, 1495.8653721165147, 1523.1470376589145, 29372.559411608414, 29846.701532985262, 31258.374832503676, 31288.0, 31404.685953140175, 45516.0, 54456.0, 55459.61350966258, 55615.72192139013, 55705.442945571645, 561846.0, 588429.0, 589193.0, 589574.0, 589645.0, 33486.497702534, 36534.72128475491, 41415.37876931788, 44488.11023779571, 45127.0, 44584.55415445804, 45325.546744532134, 45589.31616025696, 46829.2975605373, 47151.76424117857, 35986.92039238827, 38605.2278954425, 38880.41679374755, 39209.803950980066, 39985.80007100062, 38165.618343816204, 38447.0, 38627.0, 38963.610363896, 39137.0, 37839.24321513499, 38234.0441488965, 38336.233275333776, 38425.4236186463, 38504.42243366408, 25717.871410642827, 32015.519767203044, 33147.50278745773, 33886.322273553895, 33926.66073339235, 25070.0, 25380.365490862878, 25399.492010159323, 25911.870440647675, 25929.0], "value": 19287.0795085488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.8957315884449, 409.1061031645854, 414.59784009510764, 415.17380412978184, 423.67588794572157, 20384.694229586567, 20687.586248275027, 21170.047347869346, 21184.046717897694, 21834.0, 21194.940252987366, 21495.355139345844, 21653.45866353342, 21662.24182153625, 21671.56656866862, 5144.176931690921, 5959.910601340984, 6081.847953801146, 6086.847828804293, 6109.450149486545, 6019.488343490809, 6116.0, 6133.938660613404, 6143.907841382383, 6144.477719393857, 5765.827025189251, 6082.9391706082815, 6084.634921904678, 6091.116788065728, 6091.604045737013, 5826.708664566797, 5995.940040599582, 6078.635281883079, 6095.3599872013465, 6098.969505152446, 4876.317315575822, 5059.241113832918, 5319.840404787863, 5372.516473517363, 5375.489328513785, 20912.790872091387, 25696.0, 26572.069977550695, 27174.0, 27193.1842044737, 2086.1863873089505, 2326.720793504773, 2371.845830021043, 2404.723456802473, 2619.593962935748, 10096.646617368431, 10633.46832658364, 10754.784904301949, 12204.511819527208, 15713.92143039289, 1696.6606678664316, 1805.250820909321, 1819.226828597854, 1855.0910054073543, 1859.7489338939206, 115087.0, 117403.0, 119720.40139799332, 121305.0, 124415.42468890155, 11614.477348519336, 11677.941610291895, 11786.173143162823, 11805.137642056086, 11855.585054523222, 15616.375344986074, 15688.294026768823, 15695.68608627821, 15711.68576628484, 15730.764038539432, 8908.599113039929, 8935.731928042169, 9064.728058158265, 9099.954500227457, 9192.218661413855, 8861.73414797557, 8986.955065224633, 9001.0, 9092.36353455265, 9104.0, 4689.413823272091, 4769.236922092448, 4769.976150119227, 4802.975985120122, 4841.15279826029, 1686.047383228472, 1725.2667616263038, 1930.5366711989022, 1957.8155216094342, 1986.2055177928944, 752.4695089961564, 949.4730424614268, 1008.2085562833141, 1024.6311327921942, 1093.7156339351825, 18778.061096945097, 19334.80665193358, 20244.98775061241, 20282.391528254175, 20452.795472045385, 967.9370840895319, 968.8207681578931, 976.345848281653, 1015.3400289811682, 1048.3447845096814, 6294.590851594723, 10811.513481893333, 10857.185711071543, 10906.890931090587, 10954.507047182737, 634036.0, 636026.0, 648009.0, 661253.0, 662101.0, 114524.0, 118897.0, 120218.79781202252, 121514.78485215035, 124803.0, 1001.7095042437758, 1027.173125633872, 1048.0933992096934, 1049.0506091986613, 1049.1082579807103, 17644.647107058045, 17775.288988440836, 18150.0, 18626.44120676382, 18860.811391886455, 52932.0, 53181.0, 53876.73061634667, 54014.72992635012, 57001.0, 80004.59997699974, 82792.0, 82893.58553207196, 84227.0, 84614.0, 8040.87938680932, 8149.307308878811, 8210.671573137008, 8336.541490217855, 8376.53929033909, 10236.362182050836, 11984.940075299568, 12248.938755306166, 12628.494860205488, 12659.177153484994, 8959.41763785352, 10399.012093851072, 10455.634052808115, 10822.891771082188, 10831.350118992885, 9324.720258391988, 10136.290459667753, 10228.386296822215, 10296.279260451696, 10560.577576896838, 10591.8940810594, 10702.571897124028, 10939.726506837074, 11087.168462365193, 11331.263467874562, 8083.555404452812, 8125.268725814705, 8269.09040005587, 8305.377096717892, 8419.13331285385, 8246.3815213858, 9939.850902236327, 10989.560417583207, 11202.607908723156, 11217.495212715285], "value": 9682.652838909495, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.329606844612, 1420.5454254638507, 1584.1762283612518, 1772.9734053989184, 1964.4597735622701, 7688.846223075535, 8668.696595619154, 8860.335474839396, 8876.689315873946, 8895.911040889589, 8575.485470871752, 8810.603522841471, 8817.823643527141, 8824.691135810248, 8832.381733278673, 2176.3688530326185, 2250.954980900381, 2251.8311126665567, 2472.7279999200027, 2499.250224932522, 2256.4133325335447, 2402.495475950044, 2479.876006199692, 2485.825992180548, 2489.3029951613576, 2415.7837848607505, 2433.5862903306393, 2444.022391043588, 2449.3876530867283, 2449.669294645218, 2144.9142034318606, 2486.1298545509017, 2496.5381404440236, 2498.362917456037, 2500.4124030852645, 2445.510897820443, 2516.6979962404444, 2522.5585522533484, 2524.255344673326, 2525.2045605634166, 8733.912660873437, 8876.245519130882, 8889.422187557788, 8889.866652000224, 8897.73306800797, 5387.838364849061, 5535.142052981794, 5553.278073850373, 5583.4974852263085, 5594.0, 3417.7607567470286, 3511.806850623215, 3573.8391772370305, 3574.2136729919375, 3596.29872174926, 996.1532697207389, 1007.8891321954579, 1012.6810054832778, 1033.963811266602, 1050.4957620342202, 12159.0, 12190.024798016226, 12196.756064878742, 12201.20692155007, 12203.511859525608, 4822.180229361017, 4828.157102108375, 4849.58778503824, 4921.237208232729, 4975.726335051607, 10293.3823970562, 10298.845517317246, 10299.330543514647, 10324.793504130026, 10342.37945723259, 5526.834194974157, 5563.99848027359, 5565.471280228372, 5568.192612071308, 5577.470140336662, 4199.223143718422, 4377.4528183977, 4996.500349964975, 4996.575291100297, 5070.340855688811, 4038.414429907647, 4057.2494088593703, 4118.670506359484, 4118.858193739239, 4124.0, 1160.5763896178003, 1331.973360532784, 1361.4009835672273, 1367.384676895401, 1367.8153449284418, 843.6751850537587, 857.4298091769028, 888.6756333938196, 911.6262332443799, 926.1849572376266, 4894.706317620954, 5895.616784908967, 5995.0, 6080.118382834465, 6225.595336303126, 991.2763682511807, 992.9751756206008, 994.5474808961905, 1003.0420947994647, 1035.880873699519, 3901.843926242975, 3975.1254723960683, 3977.125032492847, 4010.0977280111742, 4018.236535058388, 10855.891441085489, 11166.497507612177, 11177.83233251502, 11264.66206013821, 11266.324020558794, 10961.506732197071, 11002.064824490008, 11104.167187460973, 11125.221234513669, 11131.777364452666, 8411.831763364698, 8549.829003420022, 8577.528235947082, 8660.047394786568, 8669.393142480092, 5460.5085542301385, 5724.370319264961, 5807.216025836458, 5853.356130825691, 5875.089361149029, 9652.855207172039, 9808.607655693691, 10031.347962382422, 10040.849387259052, 10042.096211341015, 9518.333716639769, 10074.143697785477, 10108.241881858743, 10282.588696452058, 10348.275620706478, 6414.166158399378, 6494.577852439576, 6756.0, 6766.18805743314, 6872.862542749017, 8073.39449541275, 8295.626696798538, 8312.459690120122, 8323.250907418362, 8323.916760832315, 7323.707051718085, 7416.369608583331, 7427.8143046424275, 7552.8489430212185, 7554.6600402983095, 7165.140183178052, 7251.927480725125, 7285.490015698851, 7329.157146928172, 7356.558606483628, 6872.69072891711, 7004.4396448283, 7009.0, 7011.1586609607175, 7013.473989450709, 4839.95160048395, 4860.0, 4865.099956508057, 4965.925511117408, 5007.84976450707, 4824.734639594715, 4858.805647774049, 4862.222044473009, 4930.531599498042, 5098.5411312982005], "value": 4639.944204465359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.29338867713676, 323.97570182236336, 328.41869890294174, 332.9617094034187, 337.10498626147586, 6668.299828518008, 6685.899711504325, 6724.260331363552, 6759.56062855914, 6763.729450821961, 6688.398044176023, 6710.798676039714, 6719.227288861783, 6721.260661327262, 6721.731130754764, 4756.120117778205, 4883.560479556839, 4884.658073934826, 4884.804607815683, 4936.284238785374, 4868.0, 4920.0, 4971.850844474671, 4975.4527002029745, 4995.725235112068, 4844.103840789465, 4895.510448955111, 4899.289603007562, 4918.139325618021, 4993.051320249141, 4977.800887964477, 4981.27771473138, 4985.999999999982, 4992.151334273164, 4994.55049045584, 4047.736897101679, 4138.937915931264, 4209.2423363794505, 4219.11398606293, 4242.957570424287, 6400.679966001681, 6450.7097180626515, 6454.354564543555, 6470.288268290485, 6472.805815825533, 5859.1797148399255, 5866.442687944638, 5876.118582212703, 5893.7053147342895, 5896.056630939072, 5868.706564671749, 7124.5725256484775, 7141.857162856766, 7145.285471452865, 7146.106736657918, 4082.4692789937417, 4576.1762882681105, 4616.6537509686905, 4690.9530904690855, 4761.119192949315, 7411.7035318587195, 7440.0, 7474.962625186839, 7504.437167212481, 7513.511621744569, 4876.82931097417, 4878.146324393217, 4884.242942343943, 4890.388701412324, 4895.241237608177, 4988.7505624718615, 4996.675216110941, 5012.749362531858, 5016.949830501721, 5023.8492845214705, 5905.616134951214, 5912.940870591239, 5925.170476133345, 5926.436988486087, 5927.585069045212, 5746.2529871116485, 6920.411765000032, 7176.210616832144, 7206.135263768277, 7208.5490933083, 1715.6997525432998, 1724.741288806664, 1739.365131726936, 1740.4517576963217, 1744.1017875793975, 900.5137225898072, 1105.264998775805, 1127.2785417332914, 1128.041165009735, 1136.5169802833764, 6382.808515744535, 6542.607443553402, 6617.7683781067435, 6635.9336406636285, 6651.707404834541, 5808.361080281165, 5961.552883533753, 6414.941534646731, 7370.410367170613, 7389.408847292204, 5447.891042179134, 6304.621722696653, 6665.466762658975, 6672.466202703772, 6685.097511835937, 3896.103896103912, 3922.607739226112, 3925.6074392560517, 3932.2135572884963, 3937.586553411908, 7602.581857997752, 7867.134615192324, 7895.0, 7901.68393264263, 7919.445638805229, 7661.506006328824, 7731.845363092707, 7743.148253692089, 7750.186230445722, 7768.300852923264, 7877.0, 7884.763457096296, 7911.208879112043, 7913.841723165389, 7927.722529711547, 7415.0, 7433.8141546461775, 7438.70245190208, 7444.069491313586, 7461.776146715607, 7064.717411303696, 7304.817379565553, 7327.120745510573, 7333.779986600411, 7337.853242935218, 7879.960600196962, 7916.129225785274, 7964.283214510722, 7978.002749656293, 7978.4415090945395, 4687.414073240845, 4744.312074749073, 4744.312074749211, 4745.240761478147, 4752.9049419010735, 7457.179710231761, 7460.142083660231, 7460.850782984418, 7470.887936680845, 7479.513831600929, 6958.199807022257, 7159.8926016110845, 7230.566166030055, 7238.674259658223, 7270.745523906638, 5854.795082172103, 7102.360787529147, 7156.534825236343, 7176.928230717626, 7177.856442871218, 6370.331115232834, 6424.486041116793, 7057.752978645723, 7068.257832927468, 7076.433885289268, 6924.58452492852, 7177.964110179416, 7178.676959536938, 7180.676869540779, 7203.0, 6744.258131605618, 7043.36609705129, 7046.146310744157, 7050.189228238613, 7077.5753454792875], "value": 5240.632847321057, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.0, 734.0, 757.9279968403, 767.5241350362775, 770.5261264322444, 15103.471378501754, 15221.923890380547, 15437.382504699812, 16204.918975405106, 16212.837871621281, 14783.260836958136, 14892.032017918855, 14909.627259318522, 14926.179060151688, 14933.178675172861, 907.2424525521197, 914.4330515080659, 922.5156792683828, 922.8154369126169, 925.4447331601019, 735.4484136897327, 746.817029827693, 914.9862752058724, 930.2743859789375, 942.1426501883287, 979.1921664626676, 982.4301904895179, 982.459647194044, 984.7193549838297, 985.2955137076989, 975.3513913247717, 977.3647129365897, 978.4080631218118, 978.4765150644407, 979.177490907641, 578.8697543052822, 589.0780927847904, 589.2310534752147, 590.7459792289313, 720.79097061852, 15214.923925380413, 15282.159481228528, 15292.541223763305, 15315.770263446057, 15320.770188447183, 12501.3749312534, 12634.11561190717, 12796.61610151697, 12819.423125959262, 13054.412551435113, 9790.363626364264, 9835.754106147331, 9954.452505112216, 10168.542415591317, 10576.629817956411, 3446.3968805459194, 3464.9480257796154, 3527.841247143859, 3566.8394922228563, 3611.3860643690637, 85167.0, 85747.0, 86144.27711445736, 86184.27631447336, 86428.0, 9248.26013918885, 9565.521723913776, 9657.71026869181, 9854.802903941882, 10414.687559373231, 19765.505862353268, 19973.700394494095, 20006.59986800285, 20034.799652003585, 20091.0, 6249.9062514062325, 6385.872282554323, 7179.748708795271, 7447.143578488543, 7629.198934111839, 4067.8576249831112, 4068.7558746475306, 4095.795210239476, 4134.028503301722, 4149.232392007489, 3484.4424892017664, 3519.7360197985254, 3562.946555801665, 3563.893083207508, 3573.535440392732, 544.8748334688934, 553.3967974907371, 644.9709763060673, 656.0290769660833, 661.9772451562347, 552.9668219906819, 576.9307683077974, 583.7519054401862, 590.9763609455574, 609.960352577081, 7977.3618110551015, 8150.2664760171865, 8177.877331840027, 8378.748637540884, 8388.790280243044, 600.2287061126432, 605.2071785960318, 609.7621927448299, 611.9602225855394, 617.7343742190901, 3669.3395188865725, 3713.9814300928324, 3785.564660064073, 3951.504710419377, 4015.297322968469, 91877.0, 92802.53598731963, 93627.0637293632, 93810.0, 94363.0, 78516.0, 78889.60555197188, 80549.59725201454, 81407.18592814115, 82173.0, 70734.64632676907, 71508.64245678738, 71639.0, 71925.64037179781, 73149.26850731531, 7737.187595302412, 7776.416768742254, 7796.151368197024, 7844.333231675371, 7864.5281283123195, 48330.5166948326, 48914.021719566124, 49157.0, 49353.76615584639, 50093.49906501034, 62923.6853815728, 66717.66641166763, 67604.98592521214, 68168.6591567039, 69340.30659693338, 9702.320837541305, 9809.705708828747, 9983.550740216853, 10002.399856008662, 10335.844962325722, 10462.947685261524, 10565.366078035342, 10708.678739637824, 10899.237053406185, 11691.532338706356, 10147.137493312855, 10280.588776448858, 10489.265751397328, 10554.472276386303, 10562.31344962575, 9963.701088967342, 10137.442440665835, 10507.833872171102, 10515.94742026285, 10578.312409693346, 9785.36395134314, 10219.28465007472, 10306.639267625897, 10364.948175259075, 10484.895151048391, 8800.82090518728, 8843.955780221058, 9006.909930900607, 9155.084491550791, 9238.491882946233, 8364.790880228044, 8873.911260887568, 8911.598978045873, 8973.237274831712, 8975.730728078168], "value": 6780.929572111487, "unit": "score"}], "name": "dromaeo_css", "value": 7497.141521557017, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 121.0, 175.0, 158.0, 112.0, 111.0, 113.0, 114.0, 113.0, 136.0], "value": 126.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 147.0, 176.0, 159.0, 119.0, 145.0, 118.0, 150.0, 154.0, 110.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 186.0, 187.0, 184.0, 185.0, 186.0, 185.0, 183.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 75.0, 151.0, 57.0, 54.0, 59.0, 61.0, 137.0, 55.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 142.0, 76.0, 81.0, 83.0, 126.0, 80.0, 76.0, 124.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 91.0, 130.0, 120.0, 83.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 93.0, 94.0, 93.0, 92.0, 92.0, 93.0, 93.0, 91.0, 92.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 86.0, 84.0, 86.0, 104.0, 140.0, 103.0, 83.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 67.0, 64.0, 66.0, 63.0, 63.0, 79.0, 59.0, 59.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 73.0, 81.0, 77.0, 73.0, 69.0, 70.0, 70.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 81.0, 103.0, 99.0, 103.0, 80.0, 82.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 143.0, 124.0, 127.0, 123.0, 126.0, 124.0, 166.0, 165.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 156.0, 167.0, 178.0, 166.0, 166.0, 165.0, 165.0, 165.0, 163.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 96.0, 94.0, 93.0, 74.0, 66.0, 67.0, 65.0, 64.0], "value": 76.8, "unit": "score"}], "name": "kraken", "value": 1517.6, "alertThreshold": 2.0}]} 06:15:07 INFO - Return code: 0 06:15:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:15:07 ERROR - # TBPL SUCCESS # 06:15:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:07 INFO - 2016-05-09 06:15:07.361 screenresolution[1984:14585] starting screenresolution argv=screenresolution get 06:15:07 INFO - 2016-05-09 06:15:07.364 screenresolution[1984:14585] Display 0: 1600x1200x32@60 06:15:07 INFO - 2016-05-09 06:15:07.369 screenresolution[1985:14588] starting screenresolution argv=screenresolution list 06:15:07 INFO - Available Modes on Display 0 06:15:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:07 INFO - Intel Iris: 06:15:07 INFO - Chipset Model: Intel Iris 06:15:07 INFO - Type: GPU 06:15:07 INFO - Bus: Built-In 06:15:07 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:07 INFO - Vendor: Intel (0x8086) 06:15:07 INFO - Device ID: 0x0a2e 06:15:07 INFO - Revision ID: 0x0009 06:15:07 INFO - Displays: 06:15:07 INFO - Display: 06:15:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:07 INFO - Display Serial Number: 1600x1200 60 06:15:07 INFO - Main Display: Yes 06:15:07 INFO - Mirror: Off 06:15:07 INFO - Online: Yes 06:15:07 INFO - Rotation: Supported 06:15:07 INFO - Return code: 0 06:15:07 INFO - Running post-action listener: _resource_record_post_action 06:15:07 INFO - Running post-run listener: _resource_record_post_run 06:15:08 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 1171456; Write bytes: 651858432; Read time: 15; Write time: 1990 06:15:08 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345534976; Write bytes: 346834432; Read time: 26641; Write time: 676 06:15:08 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 1171456; Write bytes: 295070720; Read time: 15; Write time: 1280 06:15:08 INFO - Running post-run listener: _upload_blobber_files 06:15:08 INFO - Blob upload gear active. 06:15:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:08 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 06:15:08 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'] 06:15:08 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 06:15:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:15:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:15:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:10 INFO - (blobuploader) - INFO - Done attempting. 06:15:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:15:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:15:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:11 INFO - (blobuploader) - INFO - Done attempting. 06:15:11 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:11 INFO - Return code: 0 06:15:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77fab99e82b76d8aab767f57fa0a801c973e0d0196967ac065d83e2dfeb6e06b45d88cb6821b71a0ffaf363537a3ec6f314e443f2ba376e0de573ff0e5a9b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76822e63a63b4bb00d9a64b48b7a7be838fd979d8c6076ad6f8a0b264653b7a8bc134239e8d90db742461b25400c47aa5f8302145bc511eacd76939f6d13a646"} 06:15:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:11 INFO - Contents: 06:15:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77fab99e82b76d8aab767f57fa0a801c973e0d0196967ac065d83e2dfeb6e06b45d88cb6821b71a0ffaf363537a3ec6f314e443f2ba376e0de573ff0e5a9b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76822e63a63b4bb00d9a64b48b7a7be838fd979d8c6076ad6f8a0b264653b7a8bc134239e8d90db742461b25400c47aa5f8302145bc511eacd76939f6d13a646"} 06:15:11 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:11 INFO - Copying logs to upload dir... 06:15:11 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.858822 ========= master_lag: 1.70 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-09 06:15:12.975853) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:12.980946) ========= 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.AACM9ZZppF/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.YyryaB56WX/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/a77fab99e82b76d8aab767f57fa0a801c973e0d0196967ac065d83e2dfeb6e06b45d88cb6821b71a0ffaf363537a3ec6f314e443f2ba376e0de573ff0e5a9b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76822e63a63b4bb00d9a64b48b7a7be838fd979d8c6076ad6f8a0b264653b7a8bc134239e8d90db742461b25400c47aa5f8302145bc511eacd76939f6d13a646"} build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011373 build_url: 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77fab99e82b76d8aab767f57fa0a801c973e0d0196967ac065d83e2dfeb6e06b45d88cb6821b71a0ffaf363537a3ec6f314e443f2ba376e0de573ff0e5a9b20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76822e63a63b4bb00d9a64b48b7a7be838fd979d8c6076ad6f8a0b264653b7a8bc134239e8d90db742461b25400c47aa5f8302145bc511eacd76939f6d13a646"}' ========= master_lag: 0.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:13.215022) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:13.215408) ========= 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.AACM9ZZppF/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.YyryaB56WX/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005192 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:13.245886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:15:13.246205) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:15:13.246561) ========= ========= Total master_lag: 2.01 =========