builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0277 starttime: 1463565128.74 results: success (0) buildid: 20160518004015 builduid: 7b8ca2ff7e89436d9ce06cc5148fed1c revision: b4e6080db0238862dfb893ea6ab564fcd4375da1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:08.738563) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:08.739077) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:08.739367) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:08.758856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:08.759186) ========= 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.EwO54eVUWn/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.VYUR7bSL09/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-18 02:52:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-05-18 02:52:09 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.432320 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:09.210819) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:09.211185) ========= 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.EwO54eVUWn/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.VYUR7bSL09/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.025727 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:09.260369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:09.260751) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b4e6080db0238862dfb893ea6ab564fcd4375da1 --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 b4e6080db0238862dfb893ea6ab564fcd4375da1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EwO54eVUWn/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.VYUR7bSL09/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-18 02:52:09,344 truncating revision to first 12 chars 2016-05-18 02:52:09,344 Setting DEBUG logging. 2016-05-18 02:52:09,344 attempt 1/10 2016-05-18 02:52:09,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b4e6080db023?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 02:52:09,996 unpacking tar archive at: mozilla-aurora-b4e6080db023/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:10.190307) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:10.190632) ========= script_repo_revision: b4e6080db0238862dfb893ea6ab564fcd4375da1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:10.191060) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:10.191332) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 02:52:10.206628) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-18 02:52:10.207184) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.EwO54eVUWn/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.VYUR7bSL09/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:10 INFO - MultiFileLogger online at 20160518 02:52:10 in /builds/slave/test 02:52:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:52:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:52:10 INFO - {'append_to_log': False, 02:52:10 INFO - 'base_work_dir': '/builds/slave/test', 02:52:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:52:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:52:10 INFO - 'branch': 'Mozilla-Aurora', 02:52:10 INFO - 'buildbot_json_path': 'buildprops.json', 02:52:10 INFO - 'config_files': ('talos/mac_config.py',), 02:52:10 INFO - 'default_actions': ('clobber', 02:52:10 INFO - 'read-buildbot-config', 02:52:10 INFO - 'download-and-extract', 02:52:10 INFO - 'populate-webroot', 02:52:10 INFO - 'create-virtualenv', 02:52:10 INFO - 'install', 02:52:10 INFO - 'run-tests'), 02:52:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:52:10 INFO - 'download_minidump_stackwalk': True, 02:52:10 INFO - 'download_symbols': 'ondemand', 02:52:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:52:10 INFO - 'tooltool.py': '/tools/tooltool.py', 02:52:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:52:10 INFO - '/tools/misc-python/virtualenv.py')}, 02:52:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:52:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:52:10 INFO - 'installer_path': 'installer.exe', 02:52:10 INFO - 'log_level': 'info', 02:52:10 INFO - 'log_name': 'talos', 02:52:10 INFO - 'log_to_console': True, 02:52:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:52:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:52:10 INFO - 'opt_config_files': (), 02:52:10 INFO - 'pip_index': False, 02:52:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:52:10 INFO - 'cmd': ('bash', 02:52:10 INFO - '-c', 02:52:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:52:10 INFO - 'enabled': True, 02:52:10 INFO - 'halt_on_failure': False, 02:52:10 INFO - 'name': 'check_screen_resolution'},), 02:52:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:52:10 INFO - 'cmd': ('bash', 02:52:10 INFO - '-c', 02:52:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:52:10 INFO - 'enabled': True, 02:52:10 INFO - 'halt_on_failure': False, 02:52:10 INFO - 'name': 'check_screen_resolution'},), 02:52:10 INFO - 'run_cmd_checks_enabled': True, 02:52:10 INFO - 'sps_profile': False, 02:52:10 INFO - 'sps_profile_interval': 0, 02:52:10 INFO - 'suite': 'dromaeojs-e10s', 02:52:10 INFO - 'system_bits': '32', 02:52:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:52:10 INFO - 'title': 't-yosemite-r7-0277', 02:52:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:52:10 INFO - 'use_talos_json': True, 02:52:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:52:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:52:10 INFO - 'work_dir': 'build'} 02:52:10 INFO - ##### 02:52:10 INFO - ##### Running clobber step. 02:52:10 INFO - ##### 02:52:10 INFO - Running pre-action listener: _resource_record_pre_action 02:52:10 INFO - Running main action method: clobber 02:52:10 INFO - rmtree: /builds/slave/test/build 02:52:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:52:12 INFO - Running post-action listener: _resource_record_post_action 02:52:12 INFO - ##### 02:52:12 INFO - ##### Running read-buildbot-config step. 02:52:12 INFO - ##### 02:52:12 INFO - Running pre-action listener: _resource_record_pre_action 02:52:12 INFO - Running main action method: read_buildbot_config 02:52:12 INFO - Using buildbot properties: 02:52:12 INFO - { 02:52:12 INFO - "project": "", 02:52:12 INFO - "product": "firefox", 02:52:12 INFO - "script_repo_revision": "production", 02:52:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:52:12 INFO - "repository": "", 02:52:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:52:12 INFO - "buildid": "20160518004015", 02:52:12 INFO - "stage_platform": "macosx64", 02:52:12 INFO - "basedir": "/builds/slave/test", 02:52:12 INFO - "nightly_build": "True", 02:52:12 INFO - "buildnumber": 0, 02:52:12 INFO - "slavename": "t-yosemite-r7-0277", 02:52:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:52:12 INFO - "platform": "yosemite_r7", 02:52:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:52:12 INFO - "branch": "mozilla-aurora", 02:52:12 INFO - "repo_path": "releases/mozilla-aurora", 02:52:12 INFO - "revision": "b4e6080db0238862dfb893ea6ab564fcd4375da1", 02:52:12 INFO - "pgo_build": "False", 02:52:12 INFO - "builduid": "7b8ca2ff7e89436d9ce06cc5148fed1c", 02:52:12 INFO - "slavebuilddir": "test" 02:52:12 INFO - } 02:52:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:52:12 INFO - Running post-action listener: _resource_record_post_action 02:52:12 INFO - ##### 02:52:12 INFO - ##### Running download-and-extract step. 02:52:12 INFO - ##### 02:52:12 INFO - Running pre-action listener: _resource_record_pre_action 02:52:12 INFO - Running main action method: download_and_extract 02:52:12 INFO - mkdir: /builds/slave/test/build/tests 02:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:12 INFO - https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:52:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:14 INFO - Downloaded 1199 bytes. 02:52:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:14 INFO - Using the following test package requirements: 02:52:14 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:52:14 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:52:14 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:52:14 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'jsshell-mac.zip'], 02:52:14 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:52:14 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:52:14 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:52:14 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:52:14 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:52:14 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:14 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:52:14 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:52:14 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')]} 02:52:14 INFO - https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:52:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:16 INFO - Downloaded 18102558 bytes. 02:52:16 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'] 02:52:16 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 02:52:19 INFO - Return code: 0 02:52:19 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 02:52:19 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')]} 02:52:19 INFO - https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:52:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:21 INFO - Downloaded 18102558 bytes. 02:52:21 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'] 02:52:21 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 02:52:24 INFO - Return code: 0 02:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:24 INFO - https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:52:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:52:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:52:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:25 INFO - Downloaded 10840145 bytes. 02:52:25 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'] 02:52:25 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 02:52:26 INFO - Return code: 0 02:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:26 INFO - https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:52:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/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 02:52:29 INFO - Downloaded 114149440 bytes. 02:52:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:29 INFO - mkdir: /builds/slave/test/properties 02:52:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:52:29 INFO - Writing to file /builds/slave/test/properties/build_url 02:52:29 INFO - Contents: 02:52:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:30 INFO - Running post-action listener: _resource_record_post_action 02:52:30 INFO - Running post-action listener: set_extra_try_arguments 02:52:30 INFO - ##### 02:52:30 INFO - ##### Running populate-webroot step. 02:52:30 INFO - ##### 02:52:30 INFO - Running pre-action listener: _resource_record_pre_action 02:52:30 INFO - Running main action method: populate_webroot 02:52:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:52:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:52:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:52:30 INFO - '--tppagecycles', 02:52:30 INFO - '7'], 02:52:30 INFO - 'tests': ['tsvgm']}}, 02:52:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:52:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:52:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:52:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:52:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['damp', 'tps']}, 02:52:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'tests': ['damp', 'tps']}, 02:52:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['dromaeo_dom']}, 02:52:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:52:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['a11yr', 02:52:30 INFO - 'ts_paint', 02:52:30 INFO - 'tpaint', 02:52:30 INFO - 'sessionrestore', 02:52:30 INFO - 'sessionrestore_no_auto_restore', 02:52:30 INFO - 'tabpaint']}, 02:52:30 INFO - 'other-e10s': {'tests': ['a11yr', 02:52:30 INFO - 'ts_paint', 02:52:30 INFO - 'tpaint', 02:52:30 INFO - 'sessionrestore', 02:52:30 INFO - 'sessionrestore_no_auto_restore', 02:52:30 INFO - 'tabpaint']}, 02:52:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:52:30 INFO - 'ts_paint', 02:52:30 INFO - 'tpaint', 02:52:30 INFO - 'sessionrestore', 02:52:30 INFO - 'sessionrestore_no_auto_restore', 02:52:30 INFO - 'tabpaint']}, 02:52:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:52:30 INFO - 'ts_paint', 02:52:30 INFO - 'tpaint', 02:52:30 INFO - 'sessionrestore', 02:52:30 INFO - 'sessionrestore_no_auto_restore', 02:52:30 INFO - 'tabpaint']}, 02:52:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['a11yr', 02:52:30 INFO - 'ts_paint', 02:52:30 INFO - 'tpaint', 02:52:30 INFO - 'sessionrestore', 02:52:30 INFO - 'sessionrestore_no_auto_restore', 02:52:30 INFO - 'tabpaint']}, 02:52:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['a11yr', 02:52:30 INFO - 'ts_paint', 02:52:30 INFO - 'tpaint', 02:52:30 INFO - 'sessionrestore', 02:52:30 INFO - 'sessionrestore_no_auto_restore', 02:52:30 INFO - 'tabpaint']}, 02:52:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['tsvgx', 02:52:30 INFO - 'tsvgr_opacity', 02:52:30 INFO - 'tart', 02:52:30 INFO - 'tscrollx', 02:52:30 INFO - 'cart']}, 02:52:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:52:30 INFO - 'tsvgr_opacity', 02:52:30 INFO - 'tart', 02:52:30 INFO - 'tscrollx', 02:52:30 INFO - 'cart']}, 02:52:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'talos_options': ['--disable-e10s'], 02:52:30 INFO - 'tests': ['tp5o']}, 02:52:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'tests': ['tp5o']}, 02:52:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'talos_options': ['--disable-e10s', 02:52:30 INFO - '--xperf_path', 02:52:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:30 INFO - 'tests': ['tp5n']}, 02:52:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:30 INFO - 'talos_options': ['--xperf_path', 02:52:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:30 INFO - 'tests': ['tp5n']}}, 02:52:30 INFO - 'talos.zip': {'path': '', 02:52:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:52:30 INFO - Running post-action listener: _resource_record_post_action 02:52:30 INFO - ##### 02:52:30 INFO - ##### Running create-virtualenv step. 02:52:30 INFO - ##### 02:52:30 INFO - Running pre-action listener: _resource_record_pre_action 02:52:30 INFO - Running main action method: create_virtualenv 02:52:30 INFO - Creating virtualenv /builds/slave/test/build/venv 02:52:30 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 02:52:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:52:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:52:30 INFO - Using real prefix '/tools/python27' 02:52:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:52:31 INFO - Installing distribute.............................................................................................................................................................................................done. 02:52:34 INFO - Installing pip.................done. 02:52:34 INFO - Return code: 0 02:52:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52: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', 'pip>=1.5'] in /builds/slave/test/build 02:52: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 pip>=1.5 02:52:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:34 INFO - 'HOME': '/Users/cltbld', 02:52:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:34 INFO - 'LOGNAME': 'cltbld', 02:52:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:34 INFO - 'MOZ_NO_REMOTE': '1', 02:52:34 INFO - 'NO_EM_RESTART': '1', 02:52:34 INFO - 'PAGER': '/bin/cat', 02:52:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:34 INFO - 'PWD': '/builds/slave/test', 02:52:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:34 INFO - 'SHELL': '/bin/bash', 02:52:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:34 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:34 INFO - 'USER': 'cltbld', 02:52:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:34 INFO - 'XPC_FLAGS': '0x0', 02:52:34 INFO - 'XPC_SERVICE_NAME': '0', 02:52:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:34 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 02:52:34 INFO - Cleaning up... 02:52:34 INFO - Return code: 0 02:52:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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', '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 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52: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', 'psutil>=0.7.1'] in /builds/slave/test/build 02:52: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 psutil>=0.7.1 02:52:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:34 INFO - 'HOME': '/Users/cltbld', 02:52:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:34 INFO - 'LOGNAME': 'cltbld', 02:52:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:34 INFO - 'MOZ_NO_REMOTE': '1', 02:52:34 INFO - 'NO_EM_RESTART': '1', 02:52:34 INFO - 'PAGER': '/bin/cat', 02:52:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:34 INFO - 'PWD': '/builds/slave/test', 02:52:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:34 INFO - 'SHELL': '/bin/bash', 02:52:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:34 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:34 INFO - 'USER': 'cltbld', 02:52:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:34 INFO - 'XPC_FLAGS': '0x0', 02:52:34 INFO - 'XPC_SERVICE_NAME': '0', 02:52:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:35 INFO - Downloading/unpacking psutil>=0.7.1 02:52:35 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 02:52:35 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 02:52:35 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 02:52:35 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 02:52:35 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 02:52:35 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 02:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:52:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:52:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:52:38 INFO - Installing collected packages: psutil 02:52:38 INFO - Running setup.py install for psutil 02:52:38 INFO - building 'psutil._psutil_osx' extension 02:52:38 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 02:52:38 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 02:52:38 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 02:52:38 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 02:52:38 INFO - building 'psutil._psutil_posix' extension 02:52:38 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 02:52:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:52:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:52:38 INFO - ^ 02:52:38 INFO - 1 warning generated. 02:52:38 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 02:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:52:39 INFO - Successfully installed psutil 02:52:39 INFO - Cleaning up... 02:52:39 INFO - Return code: 0 02:52:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:52:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:39 INFO - 'HOME': '/Users/cltbld', 02:52:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:39 INFO - 'LOGNAME': 'cltbld', 02:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:39 INFO - 'MOZ_NO_REMOTE': '1', 02:52:39 INFO - 'NO_EM_RESTART': '1', 02:52:39 INFO - 'PAGER': '/bin/cat', 02:52:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:39 INFO - 'PWD': '/builds/slave/test', 02:52:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:39 INFO - 'SHELL': '/bin/bash', 02:52:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:39 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:39 INFO - 'USER': 'cltbld', 02:52:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:39 INFO - 'XPC_FLAGS': '0x0', 02:52:39 INFO - 'XPC_SERVICE_NAME': '0', 02:52:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:52:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:52:41 INFO - Installing collected packages: mozsystemmonitor 02:52:41 INFO - Running setup.py install for mozsystemmonitor 02:52:42 INFO - Successfully installed mozsystemmonitor 02:52:42 INFO - Cleaning up... 02:52:42 INFO - Return code: 0 02:52:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:52:42 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')]} 02:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:42 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')]} 02:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:42 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 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:42 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 02:52:42 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 02:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:42 INFO - 'HOME': '/Users/cltbld', 02:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:42 INFO - 'LOGNAME': 'cltbld', 02:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:42 INFO - 'MOZ_NO_REMOTE': '1', 02:52:42 INFO - 'NO_EM_RESTART': '1', 02:52:42 INFO - 'PAGER': '/bin/cat', 02:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:42 INFO - 'PWD': '/builds/slave/test', 02:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:42 INFO - 'SHELL': '/bin/bash', 02:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:42 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:42 INFO - 'USER': 'cltbld', 02:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:42 INFO - 'XPC_FLAGS': '0x0', 02:52:42 INFO - 'XPC_SERVICE_NAME': '0', 02:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:42 INFO - Downloading/unpacking blobuploader==1.2.4 02:52:42 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 02:52:42 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 02:52:42 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 02:52:42 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 02:52:42 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 02:52:42 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 02:52:44 INFO - Downloading blobuploader-1.2.4.tar.gz 02:52:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:52:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:52:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:52:45 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 02:52:45 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 02:52:45 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 02:52:45 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 02:52:45 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 02:52:45 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 02:52:46 INFO - Downloading docopt-0.6.1.tar.gz 02:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:52:46 INFO - Installing collected packages: blobuploader, requests, docopt 02:52:46 INFO - Running setup.py install for blobuploader 02:52:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:52:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:52:46 INFO - Running setup.py install for requests 02:52:46 INFO - Running setup.py install for docopt 02:52:46 INFO - Successfully installed blobuploader requests docopt 02:52:46 INFO - Cleaning up... 02:52:47 INFO - Return code: 0 02:52:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:47 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 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:47 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 02:52:47 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 02:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:47 INFO - 'HOME': '/Users/cltbld', 02:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:47 INFO - 'LOGNAME': 'cltbld', 02:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:47 INFO - 'MOZ_NO_REMOTE': '1', 02:52:47 INFO - 'NO_EM_RESTART': '1', 02:52:47 INFO - 'PAGER': '/bin/cat', 02:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:47 INFO - 'PWD': '/builds/slave/test', 02:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:47 INFO - 'SHELL': '/bin/bash', 02:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:47 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:47 INFO - 'USER': 'cltbld', 02:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:47 INFO - 'XPC_FLAGS': '0x0', 02:52:47 INFO - 'XPC_SERVICE_NAME': '0', 02:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-giRgm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-qMgpFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-3BqbGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ReZ_8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-izLW7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-DjHjn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-OdItD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:47 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-TJWpKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-0_ERyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-_pr744-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-qF_YVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Ep1sEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-i8VkJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-NqoNYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-tP5aL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-JvtQKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:48 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-9Qh3_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:52:49 INFO - Running setup.py install for manifestparser 02:52:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:52:49 INFO - Running setup.py install for mozcrash 02:52:49 INFO - Running setup.py install for mozdebug 02:52:49 INFO - Running setup.py install for mozdevice 02:52:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:52:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:52:49 INFO - Running setup.py install for mozfile 02:52:49 INFO - Running setup.py install for mozhttpd 02:52:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:52:49 INFO - Running setup.py install for mozinfo 02:52:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Running setup.py install for mozInstall 02:52:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Running setup.py install for mozleak 02:52:50 INFO - Running setup.py install for mozlog 02:52:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Running setup.py install for moznetwork 02:52:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Running setup.py install for mozprocess 02:52:50 INFO - Running setup.py install for mozprofile 02:52:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:52:50 INFO - Running setup.py install for mozrunner 02:52:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:52:51 INFO - Running setup.py install for mozscreenshot 02:52:51 INFO - Running setup.py install for moztest 02:52:51 INFO - Running setup.py install for mozversion 02:52:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:52:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:52:51 INFO - Cleaning up... 02:52:51 INFO - Return code: 0 02:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:51 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 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:51 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 02:52:51 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 02:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:51 INFO - 'HOME': '/Users/cltbld', 02:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:51 INFO - 'LOGNAME': 'cltbld', 02:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:51 INFO - 'MOZ_NO_REMOTE': '1', 02:52:51 INFO - 'NO_EM_RESTART': '1', 02:52:51 INFO - 'PAGER': '/bin/cat', 02:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:51 INFO - 'PWD': '/builds/slave/test', 02:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:51 INFO - 'SHELL': '/bin/bash', 02:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:51 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:51 INFO - 'USER': 'cltbld', 02:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:51 INFO - 'XPC_FLAGS': '0x0', 02:52:51 INFO - 'XPC_SERVICE_NAME': '0', 02:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:51 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-BAaEVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:51 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)) 02:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:51 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-D9miBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-DzpQVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-2kca9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-NNkwLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-kKuUPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-vms6v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-dKSNt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-mLGNvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-vXRnxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:52 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-UPz9ZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:52 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)) 02:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:53 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Oqczmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:53 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)) 02:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:53 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ctW7gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:53 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)) 02:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:53 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-kyYcdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:53 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)) 02:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:53 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-DZI16y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:53 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)) 02:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:53 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-0mNVw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:53 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)) 02:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:53 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-W9wvK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:53 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)) 02:52:53 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)) 02:52:53 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)) 02:52:53 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)) 02:52:53 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)) 02:52:53 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)) 02:52:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:57 INFO - Downloading blessings-1.6.tar.gz 02:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:52:57 INFO - Installing collected packages: blessings 02:52:57 INFO - Running setup.py install for blessings 02:52:57 INFO - Successfully installed blessings 02:52:57 INFO - Cleaning up... 02:52:57 INFO - Return code: 0 02:52:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:52:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:57 INFO - Reading from file tmpfile_stdout 02:52:57 INFO - Current package versions: 02:52:57 INFO - blessings == 1.6 02:52:57 INFO - blobuploader == 1.2.4 02:52:57 INFO - docopt == 0.6.1 02:52:57 INFO - manifestparser == 1.1 02:52:57 INFO - mozInstall == 1.12 02:52:57 INFO - mozcrash == 0.17 02:52:57 INFO - mozdebug == 0.1 02:52:57 INFO - mozdevice == 0.48 02:52:57 INFO - mozfile == 1.2 02:52:57 INFO - mozhttpd == 0.7 02:52:57 INFO - mozinfo == 0.9 02:52:57 INFO - mozleak == 0.1 02:52:57 INFO - mozlog == 3.1 02:52:57 INFO - moznetwork == 0.27 02:52:57 INFO - mozprocess == 0.22 02:52:57 INFO - mozprofile == 0.28 02:52:57 INFO - mozrunner == 6.11 02:52:57 INFO - mozscreenshot == 0.1 02:52:57 INFO - mozsystemmonitor == 0.0 02:52:57 INFO - moztest == 0.7 02:52:57 INFO - mozversion == 1.4 02:52:57 INFO - psutil == 3.1.1 02:52:57 INFO - requests == 1.2.3 02:52:57 INFO - wsgiref == 0.1.2 02:52:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 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 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:57 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 02:52:57 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 02:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:57 INFO - 'HOME': '/Users/cltbld', 02:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:57 INFO - 'LOGNAME': 'cltbld', 02:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:57 INFO - 'MOZ_NO_REMOTE': '1', 02:52:57 INFO - 'NO_EM_RESTART': '1', 02:52:57 INFO - 'PAGER': '/bin/cat', 02:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:57 INFO - 'PWD': '/builds/slave/test', 02:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:57 INFO - 'SHELL': '/bin/bash', 02:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:57 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:57 INFO - 'USER': 'cltbld', 02:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:57 INFO - 'XPC_FLAGS': '0x0', 02:52:57 INFO - 'XPC_SERVICE_NAME': '0', 02:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 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)) 02:52:57 INFO - Cleaning up... 02:52:57 INFO - Return code: 0 02:52:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:57 INFO - 'HOME': '/Users/cltbld', 02:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:57 INFO - 'LOGNAME': 'cltbld', 02:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:57 INFO - 'MOZ_NO_REMOTE': '1', 02:52:57 INFO - 'NO_EM_RESTART': '1', 02:52:57 INFO - 'PAGER': '/bin/cat', 02:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:57 INFO - 'PWD': '/builds/slave/test', 02:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:57 INFO - 'SHELL': '/bin/bash', 02:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:52:57 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:57 INFO - 'USER': 'cltbld', 02:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:57 INFO - 'XPC_FLAGS': '0x0', 02:52:57 INFO - 'XPC_SERVICE_NAME': '0', 02:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:58 INFO - Downloading/unpacking jsonschema 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:53:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:53:00 INFO - Downloading/unpacking functools32 (from jsonschema) 02:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:00 INFO - Downloading functools32-3.2.3-2.tar.gz 02:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:53:01 INFO - warning: no files found matching '*.txt' 02:53:01 INFO - no previously-included directories found matching 'build' 02:53:01 INFO - no previously-included directories found matching 'dist' 02:53:01 INFO - no previously-included directories found matching '.git*' 02:53:01 INFO - Installing collected packages: jsonschema, functools32 02:53:01 INFO - Running setup.py install for functools32 02:53:01 INFO - warning: no files found matching '*.txt' 02:53:01 INFO - no previously-included directories found matching 'build' 02:53:01 INFO - no previously-included directories found matching 'dist' 02:53:01 INFO - no previously-included directories found matching '.git*' 02:53:01 INFO - Successfully installed jsonschema functools32 02:53:01 INFO - Cleaning up... 02:53:01 INFO - Return code: 0 02:53:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101327a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101337420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de0c458d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, '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.EwO54eVUWn/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.VYUR7bSL09/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 02:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:01 INFO - 'HOME': '/Users/cltbld', 02:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:01 INFO - 'LOGNAME': 'cltbld', 02:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:01 INFO - 'MOZ_NO_REMOTE': '1', 02:53:01 INFO - 'NO_EM_RESTART': '1', 02:53:01 INFO - 'PAGER': '/bin/cat', 02:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:01 INFO - 'PWD': '/builds/slave/test', 02:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:01 INFO - 'SHELL': '/bin/bash', 02:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:53:01 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:01 INFO - 'USER': 'cltbld', 02:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:01 INFO - 'XPC_FLAGS': '0x0', 02:53:01 INFO - 'XPC_SERVICE_NAME': '0', 02:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:01 INFO - Downloading/unpacking flake8 02:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:53:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:53:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:04 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 02:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:53:05 INFO - no previously-included directories found matching 'docs/_build' 02:53:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:53:05 INFO - Running setup.py install for pep8 02:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:53:05 INFO - no previously-included directories found matching 'docs/_build' 02:53:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:53:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:53:05 INFO - Cleaning up... 02:53:05 INFO - Return code: 0 02:53:05 INFO - Running post-action listener: _resource_record_post_action 02:53:05 INFO - Running post-action listener: _start_resource_monitoring 02:53:05 INFO - Starting resource monitoring. 02:53:05 INFO - ##### 02:53:05 INFO - ##### Running install step. 02:53:05 INFO - ##### 02:53:05 INFO - Running pre-action listener: _resource_record_pre_action 02:53:05 INFO - Running main action method: install 02:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:53:05 INFO - Reading from file tmpfile_stdout 02:53:05 INFO - Detecting whether we're running mozinstall >=1.0... 02:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:53:06 INFO - Reading from file tmpfile_stdout 02:53:06 INFO - Output received: 02:53:06 INFO - Usage: mozinstall [options] installer 02:53:06 INFO - Options: 02:53:06 INFO - -h, --help show this help message and exit 02:53:06 INFO - -d DEST, --destination=DEST 02:53:06 INFO - Directory to install application into. [default: 02:53:06 INFO - "/builds/slave/test"] 02:53:06 INFO - --app=APP Application being installed. [default: firefox] 02:53:06 INFO - mkdir: /builds/slave/test/build/application 02:53:06 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'] 02:53:06 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 02:53:37 INFO - Reading from file tmpfile_stdout 02:53:37 INFO - Output received: 02:53:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:53:37 INFO - Running post-action listener: _resource_record_post_action 02:53:37 INFO - ##### 02:53:37 INFO - ##### Running run-tests step. 02:53:37 INFO - ##### 02:53:37 INFO - Running pre-action listener: _resource_record_pre_action 02:53:37 INFO - Running main action method: run_tests 02:53:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:53:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:53:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:53:37 INFO - 2016-05-18 02:53:37.427 screenresolution[1638:7318] starting screenresolution argv=screenresolution get 02:53:37 INFO - 2016-05-18 02:53:37.430 screenresolution[1638:7318] Display 0: 1600x1200x32@60 02:53:37 INFO - 2016-05-18 02:53:37.435 screenresolution[1639:7321] starting screenresolution argv=screenresolution list 02:53:37 INFO - Available Modes on Display 0 02:53:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:53:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:53:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:53:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:53:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:53:37 INFO - Intel Iris: 02:53:37 INFO - Chipset Model: Intel Iris 02:53:37 INFO - Type: GPU 02:53:37 INFO - Bus: Built-In 02:53:37 INFO - VRAM (Dynamic, Max): 1536 MB 02:53:37 INFO - Vendor: Intel (0x8086) 02:53:37 INFO - Device ID: 0x0a2e 02:53:37 INFO - Revision ID: 0x0009 02:53:37 INFO - Displays: 02:53:37 INFO - Display: 02:53:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:53:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:53:37 INFO - Display Serial Number: 1600x1200 60 02:53:37 INFO - Main Display: Yes 02:53:37 INFO - Mirror: Off 02:53:37 INFO - Online: Yes 02:53:37 INFO - Rotation: Supported 02:53:37 INFO - Return code: 0 02:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:53:37 INFO - Python 2.7.3 02:53:37 INFO - Return code: 0 02:53:37 INFO - grabbing minidump binary from tooltool 02:53: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')]} 02:53:37 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 0x1011c6e00>, '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 0x10102fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1011c1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:53:37 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 02:53:37 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 02:53:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:53:37 INFO - Return code: 0 02:53:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:53:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:53:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:53:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:53:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:53:38 INFO - Return code: 0 02:53:38 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:53:38 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0277 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EwO54eVUWn/Render', 02:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:38 INFO - 'HOME': '/Users/cltbld', 02:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:38 INFO - 'LOGNAME': 'cltbld', 02:53:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:53:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:38 INFO - 'MOZ_NO_REMOTE': '1', 02:53:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:53:38 INFO - 'NO_EM_RESTART': '1', 02:53:38 INFO - 'PAGER': '/bin/cat', 02:53:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:38 INFO - 'PWD': '/builds/slave/test', 02:53:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:38 INFO - 'SHELL': '/bin/bash', 02:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VYUR7bSL09/Listeners', 02:53:38 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 02:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:38 INFO - 'USER': 'cltbld', 02:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:38 INFO - 'XPC_FLAGS': '0x0', 02:53:38 INFO - 'XPC_SERVICE_NAME': '0', 02:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:53:39 INFO - mozversion application_buildid: 20160518004015 02:53:39 INFO - mozversion application_changeset: b4e6080db0238862dfb893ea6ab564fcd4375da1 02:53:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:53:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:53:39 INFO - mozversion application_name: Firefox 02:53:39 INFO - mozversion application_remotingname: firefox-dev 02:53:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:53:39 INFO - mozversion application_vendor: Mozilla 02:53:39 INFO - mozversion application_version: 48.0a2 02:53:39 INFO - mozversion platform_buildid: 20160518004015 02:53:39 INFO - mozversion platform_changeset: b4e6080db0238862dfb893ea6ab564fcd4375da1 02:53:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:53:39 INFO - mozversion platform_version: 48.0a2 02:53:39 INFO - using testdate: 1463565219 02:53:39 INFO - actual date: 1463565219 02:53:39 INFO - starting webserver on 'localhost:49271' 02:53:39 INFO - SUITE-START | Running 2 tests 02:53:39 INFO - TEST-START | dromaeo_css 02:53:39 INFO - operating with platform_type : mac_ 02:53:39 INFO - Initialising browser for dromaeo_css test... 02:53:39 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpeeiTGe/profile http://localhost:49271/getInfo.html) 02:53:42 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 02:53:42 INFO - PROCESS | 1657 | colorDepth:24 02:53:42 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 02:53:42 INFO - PROCESS | 1657 | __metrics 02:53:42 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:53:42 INFO - TEST-INFO | 1657: exit 0 02:53:42 INFO - Browser initialized. 02:53:42 INFO - Running cycle 1/1 for dromaeo_css test... 02:53:42 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpeeiTGe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:53:54 INFO - PROCESS | 1661 | RSS: Main: 221622272 02:53:54 INFO - PROCESS | 1661 | 02:54:44 INFO - PROCESS | 1661 | 1463565284609 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] 02:56:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 02:56:57 INFO - PROCESS | 1661 | RSS: Main: 231677952 02:56:57 INFO - PROCESS | 1661 | 02:59:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 02:59:59 INFO - PROCESS | 1661 | RSS: Main: 243539968 02:59:59 INFO - PROCESS | 1661 | 03:03:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 03:03:02 INFO - PROCESS | 1661 | RSS: Main: 245587968 03:03:02 INFO - PROCESS | 1661 | 03:06:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 03:06:04 INFO - PROCESS | 1661 | RSS: Main: 247283712 03:06:04 INFO - PROCESS | 1661 | 03:09:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:09:07 INFO - PROCESS | 1661 | RSS: Main: 250155008 03:09:07 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:12:10 INFO - PROCESS | 1661 | RSS: Main: 251035648 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | __start_tp_report 03:12:10 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 03:12:10 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 03:12:10 INFO - PROCESS | 1661 | |i|pagename|runs| 03:12:10 INFO - PROCESS | 1661 | |0;dojo.html;2932.2376182192625;3061.6019917410736;3453.723702103832;3503.824808759561;3518.7185025197987;5665.71671416429;5678.205051292812;5679.7160141992945;5716.3426205986325;5743.942560574393;5712.971435142818;5724.8282551523525;5732.0542110551705;5750.6262092963925;5775.162601422791;4288.978555107228;5187.766550505236;5196.92204616931;5270.736463176845;5286.180642000496;5077.517635824591;5193.324867767185;5204.167333226685;5220.712860792655;5228.816991405301;4980.27785971034;5114.795408183668;5190.221466779975;5264.315638966948;5279.445658205891;5193.37679478465;5254.448282930294;5270.5520030797425;5314.893702125936;5352.946470535284;4878.390201224847;5082.440931497532;5172.767225474825;5205.791768329263;5257.631965762398;4592.425946756655;5475.479829416199;5535.723213839292;5541;5569.220309156722;5782.56630752691;6093.025115981422;6200.875982480326;6201.782937597208;6226.501879849601;6141.762184093006;6158;6234.532410069218;6238.750449981995;6279.309275979638;5809.709514524299;5821.330546987066;5825.475707186289;5832.445917637818;5832.591718579701;6247.219097612799;6248.437640612276;6277.027060805583;6278.497720182374;6282.246130464282;6034.668093254825;6100.633961962297;6116.67056464255;6162.661053642093;6164.229471316085;6236.906446403307;6283.08895210192;6302.086197501337;6302.401271879256;6320.715567799368;6199.969000154971;6227.532935029801;6273.0904018917;6286.402791734778;6306.779262725782;5211.687298762086;6105.633661980295;6176.042713379434;6240.843978900564;6252.906206406908;3979.3235150024566;4900.558949694545;4966.205407134841;4970.179920313108;5002.224655178453;4311.245532031882;4471.1728330259;4476.082403107355;4495.415595972503;4542.068875880436;5629.802956896489;5680.147977803363;5686.914696279559;5692.23154874095;5715.571332150105;5145.279660847485;6259.843503912348;6271.122042913996;6282.937170628326;6295.055741638699;5237.450067742909;5636.859078522988;5657.2928383952;5658.519025882763;5677.943220567824;5306.071437498423;5346.91979620306;5366.490183432567;5447.509724124848;5455.699936503531;6214.844628884314;6228.626282422979;6246.625202487865;6251.437370636664;6254.9687251563455;6010.669413182317;6047.123167140652;6054;6076.57463977535;6144.662043587646;6310.211223597051;6315.210598675166;6324.367563243639;6330.556861019684;6359.4276515112015;6186.845328866814;6198.163247961467;6202.34875338083;6222.12890195364;6239.469645080219;5021.246812977936;6067.605605635619;6124.045297608125;6285.151504546828;6296.244450665949;5986.682929755447;6063.6664983426335;6077.787277445268;6080.817575472743;6106.2061931948565;5098.337216161875;6073.331933487401;6077.149199112217;6094.2077529920825;6100.786472473442;5052.671576347526;5817.825465236049;5826.883462330645;5885.941140588539;5936.257967754031;6125.234345706786;6150.04674275488;6160.568760186744;6163.630182189083;6168.228971378578;6022.608530445507;6073.453389194994;6078.3921607838865;6078.787242446493;6094.481969032682 03:12:10 INFO - PROCESS | 1661 | |1;ext.html;481.5040508276474;540.5999560325359;581.0818906128318;585.4876982640192;605.6366180291825;38565;39607.20785584287;39619.80190099049;39644.405333919945;39758.60241397585;41324.760257192254;41792.58207417925;41947.79026104865;41978.58021419785;42047.36928946068;9443.244540436748;9612.086851749073;9665.903340966606;9701.932787393382;9750.414975101517;9573.856392154123;9744.07431294026;9778.755531111708;9805.803883922317;9814.165795907356;9762.90237097627;9816.214702823792;9817.116459518642;9826.50867456628;9851.310408271424;9719.222462203006;9742.9512852436;9796.26528010402;9810.754731131707;9820.50897455128;9662.903370966342;9673.70978870635;9767.072128147815;9778.853317200248;9784.266180036455;39660.00849978745;39885.60114398848;40063.99840003994;40179.3973090401;40183.59816401828;2920.620319358491;3215.951760723591;3294.3081952789703;3400.336934297813;3437.4843773434186;28502.00242991506;28870;29137;29144.854275728696;29948.8502557488;2734.6581677290337;2797.482465743844;2812.2969257685577;2851;2874.1664917173994;330827;337245;340736.2963185193;341746;343007;37120.814395927846;64484.35515644783;65439.67280163569;65595.688086238;65718.67140664266;35597.110072248404;35937.8203108988;36084.27831443356;36314.45528317078;36321.81839090788;15485.922570387076;15636.4527241546;15869.603259918595;15950.68098638044;15950.920245398931;12846.10077294599;13213.603591892259;13250.337483125804;13349.332533373487;13364.732705346034;8256.215659512336;8283.130271321545;8338.082810890797;8394.412391132562;8454.746357609281;3297.3075654112904;3396.4056290149124;3406.1825161961447;3463.41122009259;3487.511748355232;749.4379215588309;919.0120620333169;1005.8893521712606;1047.4439278649097;1073.259450978822;29956.850215748782;30060.69939300579;30360.240993975327;30867.845660772;30912.845435772677;1043.7860238651058;1106.994465027686;1184.3604453594962;1215.3497878634887;1240.7456471423336;13145.934270328587;15228.314725837365;15705.293261803246;15737.763933541006;15803.604909877115;658258;660749;662472;665003;670923;52076.73961630243;60545.394546053976;62475;62488.06267905985;62561;1394.2610416479283;1403.7964495148146;1404.9297535123408;1477.6601381682274;1478.0097334785505;30023.7487206496;30410.695893041673;30486.416300440003;30754.692453075182;30849.845750771103;55410.614734631956;55549.722251388484;56016.87966240734;56061.159082612976;56218;503039;513848;520827;521282;521627;27891.302717432227;28023.159305220877;28248.011319603716;28352;28451.008429409743;47113;47140;48305.75847120742;48347.79130521624;48418.757906210245;38707.61388340516;38957.6104238954;39427.40858887062;39507.40738888976;40184.799076005605;37864.74167927185;38283.61716382801;38420.84737457881;38472.80763596164;39415.01462463461;30553.388932220787;32811.17972050718;38080.619193808816;38251.2349753009;38888.416673750486;31037;33531.66468335383;33568.66431335655;33581.16047098842;33860.66139338575;24742;24979.87510062438;25185.0967279674;25252.368690782143;25258.873705631355 03:12:10 INFO - PROCESS | 1661 | |2;jquery.html;395.8139674353054;398.1598826476135;399.3590287588419;404.8684177642267;410.1121072877221;20154.589178757455;20299.49251268717;20913.37259882202;20950.89524552379;21010.264640737558;20895.059722312493;21194.89402552985;21633.45916352088;21904;21940.780592194074;5767.452092051249;5790.971045144768;6104.725287362079;6120.296165940907;6125.816225513242;5523.28197334348;5643.8024669136585;5908.645481271116;5940.52475801937;5997.040473524237;6212.534059945522;6221.688915554227;6226.750929962796;6234.781782637609;6257.155284036642;5961.850953726148;6193.256809182904;6199.597026193283;6202.317745048041;6224.190855188842;6024.668643224621;6046.18376519173;6079.270487541523;6082.96958515209;6087.2086628738425;13455.66360840977;13647.931760341235;13660.385282662204;13742.862571374359;13834.30828458573;2222.511047569532;2300.18343488061;2318.9420264493356;2421.5520128776056;2766.695663477016;9580.76048098803;10103.545340459697;10168.4915754212;10225.64210252645;10357.274990750575;1718.8109307976113;1759.9824001760076;1780.305680784482;1825.6531259060737;1829.396299221267;106608.40087398695;110370.89629103687;112918;118036;119138.40430797791;11608.76782464346;12008.519659213709;12068.456919438646;12076.154669173073;12077.45651445687;15463.690726185638;15557.533274001798;15583.519565641473;15606.29771660278;15609.453669121753;9097.454152750855;9103.271738260923;9146.2682985361;9161.038091000482;9229.400088994194;7992.680292788224;8919.241864441596;9080.046595107418;9195.540222988824;9249.907500924905;3834.9233015339537;4850.1269771440775;4862.343583616237;4869.220924652039;4924.374956264424;1735.6094878652455;1987.5130593004806;2024.9773864198485;2037.521182522106;2058.6191554562456;820.4420993724325;942.2744486745167;968.8373951258518;976.2385339435252;1038.3406536849004;16851.325946962228;20198.495037623885;20263.39209823678;20496.487587810127;21184.25855095095;905.2034209895251;972.6060945317158;998.9051040151174;1000.3797645459862;1004.4023805835662;10916.672499825017;11233.887661123448;11411.429428528707;11438.542458301741;11455;610517;611282;611833;620785;624316;121613;132436;134912;135894;141530.2923485376;999.0209594597335;1002.6490728245055;1013.5894962540183;1018.7300365403174;1022.4121130350078;18184.636307274046;18610.534736631693;18712;18720.625587488445;18785;51067;51423.48576514336;52640;55057;55707;57628;71461.28538714546;73834.63082684767;76855.61572192327;78691;8142.145074767301;8232.300254478198;8283.95858020706;8508.957455212685;8644.394892357475;10836.349819010884;12485.438155283216;12930.935345323212;12953.740925181255;13067.34663266852;11023.228374013743;11107.111431085656;11117.94379533943;11180.161487888603;11250.66248012561;10856.185786066235;10856.565737370416;10893.673189804318;10894.836577451186;10950.452477376259;11165.106791456827;11197.496112674786;11343.773124537629;11408.429578520874;11564.884351156381;8176.67293308261;8305.418620696493;8428.367872409472;8480.321574274167;8670.479771213748;11116.49975751077;11127.499262533043;11223.55105795759;11349.659510214708;11441 03:12:10 INFO - PROCESS | 1661 | |3;mootools.html;1519.7948276982609;1592.3550961860444;1815.7911840138388;1906.1708156951727;1976.3300011490285;7130.465215108861;8722.127787221274;8726.258268047224;8929.642814287428;8930.017698053218;8686.391952563306;8707.477551346909;8777.60500777465;8784.07767184446;8807.559622018905;2437.914672986446;2460.5324988252173;2463.4210960424375;2464;2475.935347800445;2431.659567660529;2487.875606219691;2488.5769419198778;2488.8880000400027;2497.612870005152;2451.166603354859;2458.4960083182996;2490.5267999080206;2494.301595553248;2502.874856257189;2253.906855175239;2317.895694693743;2409.8433601815827;2418.2140804238607;2437.7445615508027;2437.110454684045;2440.8169387296025;2445.8165637577254;2453.5092981403614;2459.9016039358403;8933.28533717301;8970.775730606721;8982.910170898273;9004.77488062797;9016.053314402025;5357.357117145929;5519.558435325164;5521.668699878021;5530.917036244459;5536.778528858841;3542.769719968194;3600.261946301002;3625.5679006792434;3637.436197389409;3695.427208782643;1058.8782290036668;1083.0360978728938;1084.0352086642895;1084.783043391317;1085.647164671485;10561.947190264076;12093.213941093889;12094;12100.455479503444;12413;4856.150173719585;4868.367112275381;4952.90094198114;4967.3542439482635;4975.726335051607;10179.440130792878;10205.540750666238;10205.74485637865;10214.693559193236;10232.948835255776;5490.752916118784;5498.092814685612;5507.504324610804;5520.392756796749;5532.75102620383;4259.361095835649;4775.617950563947;4884.877878053006;4899.828506002273;4970.453250142481;4029.294873397144;4041.4341992121126;4123.463949686581;4131.958680413158;4163.10493243954;1320.3794216718127;1350.2371160294401;1356.923643200713;1360.5238166641793;1364.4405793624567;717.1752484642599;863.2877875752529;926.545992463688;928.9535523223811;961.9038096190325;5226.634135610547;5852.180694702745;6007.579469437097;6138.140660307471;6151.3541078187045;406.80473372780637;432.5609506351056;437.64550713921824;475.5316013726477;482.2091769498026;3945.11234972129;3960.801959902051;4077.082656402286;4127.587241275848;4129;10399;10783.29908555957;10846.078083363003;10860.45697715111;10866.456677166268;10014.148797352162;10424.478776061167;10451.059404653617;10591;10602.469876506144;8346.916530834735;8401.15988401155;8437.873431898526;8448.197421244971;8466.238038576434;5743.540516758733;6026.7589296429405;6055.000924847527;6064.090386442069;6117.663528505975;8812.250958668585;9652.75868103303;9670.323077384797;9719.805603888024;9887.752806179615;9753.512324383895;10094;10127.29108962395;10165.339252948535;10236.795264094826;5660.971695141498;6843.418309443753;7522.699092036476;7582.54504729718;7639.930409742752;8326.167383261625;8341.45780524264;8363.623636936474;8389.203025712548;8399.370047246359;6376.234851817811;7299.635018249172;7398.408127349907;7529.247075292427;7536.7362142324755;7232.74685386009;7254.310840470147;7315.63421828917;7327.780166595011;7334.596597187206;7075.257098004636;7104.857902842017;7125.216226215216;7134.357907788324;7159.212486626363;5003.199488081889;5005.899882002267;5014.7994080236385;5071.898562028812;5087.618428617794;4394.780260987001;5005.199168133081;5012.083325011607;5140.64015518925;5173.456787037458 03:12:10 INFO - PROCESS | 1661 | |4;prototype.html;288.3900550336039;334.40642858925406;339.4805946901242;340.3550272234118;344.2735827404175;6799.184097908252;6815.352541508555;6819.21579018413;6839.0425340452375;6867.175938887328;6741.292164322749;6755.932440675591;6762.256151823295;6780.084688567042;6791.694373753181;4739.620830333565;4834.274858771178;4918.360613120307;4935.407751069876;4946.826861059863;4350.1734670412525;4543.522930092342;4868.05073010763;4920.146315137212;4938.234573641092;4605.608523275526;4877.0489754497885;4898.191798353268;4955.033768415161;4966.900661986758;4941.827036053757;4943.332650092246;4947.381577302837;4960.181570040939;4972.105021096201;4259.978700106511;4260.467441569804;4263.701540892139;4275.123599662062;4278.764667943262;5684;6250.468710159642;6277.686115694197;6514.348565143495;6543.280239173687;5853.619514731528;5902.6163299385835;5923.614965027259;5930.703464826742;5937.970310148422;7175.210726820045;7197.928020719831;7207.45944054198;7258.528195667264;7275.12698476181;4768.61851051915;4785.377900872864;4792.329073929653;4806.879828004293;4808.230683090724;7585.279398457193;7612.733554325572;7613.885791713129;7628.275313845232;7649.6940122395035;4497.617702495325;4668.50980647034;4672.509386514435;4699.412573428322;4708.246680531167;4730.432348118179;4767.165745994437;4775.617950563947;4784.330193772875;4799.616030717534;5339.11904535755;5522.22688823565;5718.542516598662;5803.680797556175;5812.157237200582;7281.4538909582;7301.415886729049;7304.96347518259;7317.414606831547;7335.1197856257595;1644.4819881737214;1668.1992643531173;1669.4741156535651;1672.2976349933058;1676.1451659653421;1033.178622994721;1059.8092343378112;1060.7666313411037;1065.717584840018;1074.6131392698621;5570.944290557123;6725.159355080615;6748.966255168692;6765;6787.932120678829;7267.927320726725;7521.962390188014;7537.283958023979;7543.207963163789;7566.092068951651;5707.828765137053;5968.4926781224085;6549.279579246279;6775.966120169368;6793.354631310018;3039.0730827097946;3301;3890.727649064595;4001.9599804001587;4011.679065674739;7935.650939340326;7940.920590794133;7942.920570794334;7951.608468518102;7981.48120372174;6457.773977910751;7403.5928023959195;7738.458307918391;7788.026496687914;7816.84366312682;6966.407855332353;7450.552966822008;7874.291313781787;7953.721619743281;7961.323287520626;7543;7552.9244707552225;7560.054993125859;7591.316781489692;7607.505512141693;6111.84720381994;7386.002889610048;7436.702531898663;7437.0331856858265;7481.401487881065;7567;7917.7624671259955;7946.284834364935;7996.520208787491;7999.400044996765;4693.202155633482;4713.717176969393;4724.385829842098;4731.881702957316;4752.738599377068;7499.962500187464;7503.512271702322;7517.210692877388;7526.5107767994905;7563.319301262913;6758.357955994173;7164.961080643173;7256.4194864411775;7269.127704675473;7279.417646588155;5816.76732930678;7040.1551813780625;7282.854342913218;7284.708611655687;7291.489595728461;7026.929730702627;7082.681279342545;7098.183708873546;7102.254263302486;7129;7223.602701851239;7251.383632391306;7263.6005019724425;7280.854382912418;7296.817579560341;7054.929450705633;7096.7516136934955;7105.111861017373;7109.324614161853;7110.4667149965 03:12:10 INFO - PROCESS | 1661 | |5;yui.html;690.716806109495;704.9531445802982;722.8315054835493;743.3421422041491;778.4434129597337;14647.047941883784;14865.108093514396;14951.102933823964;14964.32660530276;15001.774973375395;13527.864721352784;13846.792298115512;13866.930665346685;13886;13916.23460709663;745.679357876113;905.9456432614064;913.4793167894303;913.7898283394824;935.5275585829147;934.7149119518544;949.9477528735916;950.653011650746;956.5934477846887;959.52023988006;979.0992287095889;988.293370240278;992.4243938515643;993.0417147452707;996.4619105682923;957.1529196660936;988.2242439684885;988.663854289545;989.7129832348646;994.005994005994;720.9906131416008;723.5297056913049;739.9963000185019;749.647665597174;749.8762704153808;14836.480723174636;14892.0320179188;14907.329170187368;14929.253537323091;14963.102213867203;12799.74400511994;12820;12954.352282385937;12986.480540778357;13122.934385328108;8380.497170169749;9977.451240181786;10054.195664346908;10122.595096196143;10160.542775575117;3083.6762139975426;3374.578177727784;3519.8768043118366;3568.268504956504;3638.6725194732603;69979;71185.64407177983;71605.92591111138;71693;73198.63400683015;9423.481708506099;9527.237820974306;9558.044195580387;9620.518974051269;9888.752781180528;22029.559408812056;22370.888145559493;22400.775992240197;22432.551348972927;22451.214207502984;7571;7590.051243594551;7592.392608591299;7638.961805190938;7723.575203363869;4069.023434375788;4087.2029954159248;4118.5057793065025;4157.147784704129;4187.371894215892;3680.9447858282147;3723.478712980185;3752.7373083883867;3758.229562939591;3760.172761992357;675.166169780321;685.4310921934782;686.084077756191;692.7263730826396;696.738722978883;554.6062295769967;586.7447660267798;604.4529700620946;606.9757209711651;612.402907165519;8016.398770092266;8285.42002059862;8292.834143317099;8334.791630209293;8487.023992240847;511.2739909328762;564.25800072904;590.3293678888734;628.8522197283635;639.792067578039;3274.8198849063056;3721.5348081489815;3721.925561488755;3830.7893065881085;3968.503937007874;88585.55707221423;89037;89480.6577901332;91683.54158229165;92593;71656.28343716497;72023.63988180025;76257.85613215806;76315.23684763082;76765;63328;68669;68676;69955;70167.544135955;8315.792105197419;8322.26560549622;8444.07115217338;8475.194856488624;8553.272971797467;44739;45886.541134588224;46483.53516464792;46710.532894671975;48446;61652;62265.707414870245;62802.37197627965;64335.71328573309;64469.67765161144;10614.363138211733;10824;10922.563097476012;11028.613998510014;11609.709757255798;11723.648290551297;11892.821607676065;11955.521779128738;11989.280643161439;12214.328211948428;10957.06864916491;11346.319220846775;11517.308961462339;11546.942265288955;11614.187006909773;11130;11207.103431725605;11326.32042077478;11357.943210283896;11482.82775758381;9045.547722613712;10426.947865260929;11178.944105279421;11280.210385272952;11321.943390282995;8568.914310856811;9100.362974591715;9341.252699784136;9427.198688111588;9581.616735330508;9467.137400969197;9701.417914925127;9740.805183896424;9814.558344874355;9845.950770246389 03:12:10 INFO - PROCESS | 1661 | __end_tp_report 03:12:10 INFO - PROCESS | 1661 | __start_cc_report 03:12:10 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,418 03:12:10 INFO - PROCESS | 1661 | __end_cc_report 03:12:10 INFO - PROCESS | 1661 | __startTimestamp1463566330356__endTimestamp 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | ------- Summary: start ------- 03:12:10 INFO - PROCESS | 1661 | Number of tests: 6 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5682.54 Median:5832.59 stddev:673.19 (11.5%) stddev-sans-first:639.75 03:12:10 INFO - PROCESS | 1661 | Values: 2932.2 3061.6 3453.7 3503.8 3518.7 5665.7 5678.2 5679.7 5716.3 5743.9 5713.0 5724.8 5732.1 5750.6 5775.2 4289.0 5187.8 5196.9 5270.7 5286.2 5077.5 5193.3 5204.2 5220.7 5228.8 4980.3 5114.8 5190.2 5264.3 5279.4 5193.4 5254.4 5270.6 5314.9 5352.9 4878.4 5082.4 5172.8 5205.8 5257.6 4592.4 5475.5 5535.7 5541.0 5569.2 5782.6 6093.0 6200.9 6201.8 6226.5 6141.8 6158.0 6234.5 6238.8 6279.3 5809.7 5821.3 5825.5 5832.4 5832.6 6247.2 6248.4 6277.0 6278.5 6282.2 6034.7 6100.6 6116.7 6162.7 6164.2 6236.9 6283.1 6302.1 6302.4 6320.7 6200.0 6227.5 6273.1 6286.4 6306.8 5211.7 6105.6 6176.0 6240.8 6252.9 3979.3 4900.6 4966.2 4970.2 5002.2 4311.2 4471.2 4476.1 4495.4 4542.1 5629.8 5680.1 5686.9 5692.2 5715.6 5145.3 6259.8 6271.1 6282.9 6295.1 5237.5 5636.9 5657.3 5658.5 5677.9 5306.1 5346.9 5366.5 5447.5 5455.7 6214.8 6228.6 6246.6 6251.4 6255.0 6010.7 6047.1 6054.0 6076.6 6144.7 6310.2 6315.2 6324.4 6330.6 6359.4 6186.8 6198.2 6202.3 6222.1 6239.5 5021.2 6067.6 6124.0 6285.2 6296.2 5986.7 6063.7 6077.8 6080.8 6106.2 5098.3 6073.3 6077.1 6094.2 6100.8 5052.7 5817.8 5826.9 5885.9 5936.3 6125.2 6150.0 6160.6 6163.6 6168.2 6022.6 6073.5 6078.4 6078.8 6094.5 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:63994.34 Median:38446.83 stddev:140199.05 (364.7%) stddev-sans-first:140511.24 03:12:10 INFO - PROCESS | 1661 | Values: 481.5 540.6 581.1 585.5 605.6 38565.0 39607.2 39619.8 39644.4 39758.6 41324.8 41792.6 41947.8 41978.6 42047.4 9443.2 9612.1 9665.9 9701.9 9750.4 9573.9 9744.1 9778.8 9805.8 9814.2 9762.9 9816.2 9817.1 9826.5 9851.3 9719.2 9743.0 9796.3 9810.8 9820.5 9662.9 9673.7 9767.1 9778.9 9784.3 39660.0 39885.6 40064.0 40179.4 40183.6 2920.6 3216.0 3294.3 3400.3 3437.5 28502.0 28870.0 29137.0 29144.9 29948.9 2734.7 2797.5 2812.3 2851.0 2874.2 330827.0 337245.0 340736.3 341746.0 343007.0 37120.8 64484.4 65439.7 65595.7 65718.7 35597.1 35937.8 36084.3 36314.5 36321.8 15485.9 15636.5 15869.6 15950.7 15950.9 12846.1 13213.6 13250.3 13349.3 13364.7 8256.2 8283.1 8338.1 8394.4 8454.7 3297.3 3396.4 3406.2 3463.4 3487.5 749.4 919.0 1005.9 1047.4 1073.3 29956.9 30060.7 30360.2 30867.8 30912.8 1043.8 1107.0 1184.4 1215.3 1240.7 13145.9 15228.3 15705.3 15737.8 15803.6 658258.0 660749.0 662472.0 665003.0 670923.0 52076.7 60545.4 62475.0 62488.1 62561.0 1394.3 1403.8 1404.9 1477.7 1478.0 30023.7 30410.7 30486.4 30754.7 30849.8 55410.6 55549.7 56016.9 56061.2 56218.0 503039.0 513848.0 520827.0 521282.0 521627.0 27891.3 28023.2 28248.0 28352.0 28451.0 47113.0 47140.0 48305.8 48347.8 48418.8 38707.6 38957.6 39427.4 39507.4 40184.8 37864.7 38283.6 38420.8 38472.8 39415.0 30553.4 32811.2 38080.6 38251.2 38888.4 31037.0 33531.7 33568.7 33581.2 33860.7 24742.0 24979.9 25185.1 25252.4 25258.9 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35187.44 Median:21097.26 stddev:102609.88 (486.4%) stddev-sans-first:102864.48 03:12:10 INFO - PROCESS | 1661 | Values: 395.8 398.2 399.4 404.9 410.1 20154.6 20299.5 20913.4 20950.9 21010.3 20895.1 21194.9 21633.5 21904.0 21940.8 5767.5 5791.0 6104.7 6120.3 6125.8 5523.3 5643.8 5908.6 5940.5 5997.0 6212.5 6221.7 6226.8 6234.8 6257.2 5961.9 6193.3 6199.6 6202.3 6224.2 6024.7 6046.2 6079.3 6083.0 6087.2 13455.7 13647.9 13660.4 13742.9 13834.3 2222.5 2300.2 2318.9 2421.6 2766.7 9580.8 10103.5 10168.5 10225.6 10357.3 1718.8 1760.0 1780.3 1825.7 1829.4 106608.4 110370.9 112918.0 118036.0 119138.4 11608.8 12008.5 12068.5 12076.2 12077.5 15463.7 15557.5 15583.5 15606.3 15609.5 9097.5 9103.3 9146.3 9161.0 9229.4 7992.7 8919.2 9080.0 9195.5 9249.9 3834.9 4850.1 4862.3 4869.2 4924.4 1735.6 1987.5 2025.0 2037.5 2058.6 820.4 942.3 968.8 976.2 1038.3 16851.3 20198.5 20263.4 20496.5 21184.3 905.2 972.6 998.9 1000.4 1004.4 10916.7 11233.9 11411.4 11438.5 11455.0 610517.0 611282.0 611833.0 620785.0 624316.0 121613.0 132436.0 134912.0 135894.0 141530.3 999.0 1002.6 1013.6 1018.7 1022.4 18184.6 18610.5 18712.0 18720.6 18785.0 51067.0 51423.5 52640.0 55057.0 55707.0 57628.0 71461.3 73834.6 76855.6 78691.0 8142.1 8232.3 8284.0 8509.0 8644.4 10836.3 12485.4 12930.9 12953.7 13067.3 11023.2 11107.1 11117.9 11180.2 11250.7 10856.2 10856.6 10893.7 10894.8 10950.5 11165.1 11197.5 11343.8 11408.4 11564.9 8176.7 8305.4 8428.4 8480.3 8670.5 11116.5 11127.5 11223.6 11349.7 11441.0 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5739.18 Median:4926.36 stddev:3193.10 (64.8%) stddev-sans-first:3186.31 03:12:10 INFO - PROCESS | 1661 | Values: 1519.8 1592.4 1815.8 1906.2 1976.3 7130.5 8722.1 8726.3 8929.6 8930.0 8686.4 8707.5 8777.6 8784.1 8807.6 2437.9 2460.5 2463.4 2464.0 2475.9 2431.7 2487.9 2488.6 2488.9 2497.6 2451.2 2458.5 2490.5 2494.3 2502.9 2253.9 2317.9 2409.8 2418.2 2437.7 2437.1 2440.8 2445.8 2453.5 2459.9 8933.3 8970.8 8982.9 9004.8 9016.1 5357.4 5519.6 5521.7 5530.9 5536.8 3542.8 3600.3 3625.6 3637.4 3695.4 1058.9 1083.0 1084.0 1084.8 1085.6 10561.9 12093.2 12094.0 12100.5 12413.0 4856.2 4868.4 4952.9 4967.4 4975.7 10179.4 10205.5 10205.7 10214.7 10232.9 5490.8 5498.1 5507.5 5520.4 5532.8 4259.4 4775.6 4884.9 4899.8 4970.5 4029.3 4041.4 4123.5 4132.0 4163.1 1320.4 1350.2 1356.9 1360.5 1364.4 717.2 863.3 926.5 929.0 961.9 5226.6 5852.2 6007.6 6138.1 6151.4 406.8 432.6 437.6 475.5 482.2 3945.1 3960.8 4077.1 4127.6 4129.0 10399.0 10783.3 10846.1 10860.5 10866.5 10014.1 10424.5 10451.1 10591.0 10602.5 8346.9 8401.2 8437.9 8448.2 8466.2 5743.5 6026.8 6055.0 6064.1 6117.7 8812.3 9652.8 9670.3 9719.8 9887.8 9753.5 10094.0 10127.3 10165.3 10236.8 5661.0 6843.4 7522.7 7582.5 7639.9 8326.2 8341.5 8363.6 8389.2 8399.4 6376.2 7299.6 7398.4 7529.2 7536.7 7232.7 7254.3 7315.6 7327.8 7334.6 7075.3 7104.9 7125.2 7134.4 7159.2 5003.2 5005.9 5014.8 5071.9 5087.6 4394.8 5005.2 5012.1 5140.6 5173.5 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:5887.45 Median:6757.15 stddev:1915.95 (28.4%) stddev-sans-first:1874.67 03:12:10 INFO - PROCESS | 1661 | Values: 288.4 334.4 339.5 340.4 344.3 6799.2 6815.4 6819.2 6839.0 6867.2 6741.3 6755.9 6762.3 6780.1 6791.7 4739.6 4834.3 4918.4 4935.4 4946.8 4350.2 4543.5 4868.1 4920.1 4938.2 4605.6 4877.0 4898.2 4955.0 4966.9 4941.8 4943.3 4947.4 4960.2 4972.1 4260.0 4260.5 4263.7 4275.1 4278.8 5684.0 6250.5 6277.7 6514.3 6543.3 5853.6 5902.6 5923.6 5930.7 5938.0 7175.2 7197.9 7207.5 7258.5 7275.1 4768.6 4785.4 4792.3 4806.9 4808.2 7585.3 7612.7 7613.9 7628.3 7649.7 4497.6 4668.5 4672.5 4699.4 4708.2 4730.4 4767.2 4775.6 4784.3 4799.6 5339.1 5522.2 5718.5 5803.7 5812.2 7281.5 7301.4 7305.0 7317.4 7335.1 1644.5 1668.2 1669.5 1672.3 1676.1 1033.2 1059.8 1060.8 1065.7 1074.6 5570.9 6725.2 6749.0 6765.0 6787.9 7267.9 7522.0 7537.3 7543.2 7566.1 5707.8 5968.5 6549.3 6776.0 6793.4 3039.1 3301.0 3890.7 4002.0 4011.7 7935.7 7940.9 7942.9 7951.6 7981.5 6457.8 7403.6 7738.5 7788.0 7816.8 6966.4 7450.6 7874.3 7953.7 7961.3 7543.0 7552.9 7560.1 7591.3 7607.5 6111.8 7386.0 7436.7 7437.0 7481.4 7567.0 7917.8 7946.3 7996.5 7999.4 4693.2 4713.7 4724.4 4731.9 4752.7 7500.0 7503.5 7517.2 7526.5 7563.3 6758.4 7165.0 7256.4 7269.1 7279.4 5816.8 7040.2 7282.9 7284.7 7291.5 7026.9 7082.7 7098.2 7102.3 7129.0 7223.6 7251.4 7263.6 7280.9 7296.8 7054.9 7096.8 7105.1 7109.3 7110.5 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17612.04 Median:32487.75 stddev:24238.58 (74.6%) stddev-sans-first:24273.27 03:12:10 INFO - PROCESS | 1661 | Values: 690.7 705.0 722.8 743.3 778.4 14647.0 14865.1 14951.1 14964.3 15001.8 13527.9 13846.8 13866.9 13886.0 13916.2 745.7 905.9 913.5 913.8 935.5 934.7 949.9 950.7 956.6 959.5 979.1 988.3 992.4 993.0 996.5 957.2 988.2 988.7 989.7 994.0 721.0 723.5 740.0 749.6 749.9 14836.5 14892.0 14907.3 14929.3 14963.1 12799.7 12820.0 12954.4 12986.5 13122.9 8380.5 9977.5 10054.2 10122.6 10160.5 3083.7 3374.6 3519.9 3568.3 3638.7 69979.0 71185.6 71605.9 71693.0 73198.6 9423.5 9527.2 9558.0 9620.5 9888.8 22029.6 22370.9 22400.8 22432.6 22451.2 7571.0 7590.1 7592.4 7639.0 7723.6 4069.0 4087.2 4118.5 4157.1 4187.4 3680.9 3723.5 3752.7 3758.2 3760.2 675.2 685.4 686.1 692.7 696.7 554.6 586.7 604.5 607.0 612.4 8016.4 8285.4 8292.8 8334.8 8487.0 511.3 564.3 590.3 628.9 639.8 3274.8 3721.5 3721.9 3830.8 3968.5 88585.6 89037.0 89480.7 91683.5 92593.0 71656.3 72023.6 76257.9 76315.2 76765.0 63328.0 68669.0 68676.0 69955.0 70167.5 8315.8 8322.3 8444.1 8475.2 8553.3 44739.0 45886.5 46483.5 46710.5 48446.0 61652.0 62265.7 62802.4 64335.7 64469.7 10614.4 10824.0 10922.6 11028.6 11609.7 11723.6 11892.8 11955.5 11989.3 12214.3 10957.1 11346.3 11517.3 11546.9 11614.2 11130.0 11207.1 11326.3 11357.9 11482.8 9045.5 10426.9 11178.9 11280.2 11321.9 8568.9 9100.4 9341.3 9427.2 9581.6 9467.1 9701.4 9740.8 9814.6 9846.0 03:12:10 INFO - PROCESS | 1661 | -------- Summary: end -------- 03:12:10 INFO - PROCESS | 1661 | 03:12:10 INFO - TEST-INFO | 1661: exit 0 03:12:10 INFO - TEST-OK | dromaeo_css | took 1111940ms 03:12:11 INFO - TEST-START | kraken 03:12:11 INFO - operating with platform_type : mac_ 03:12:11 INFO - Initialising browser for kraken test... 03:12:11 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp4C8URU/profile http://localhost:49271/getInfo.html) 03:12:13 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 03:12:13 INFO - PROCESS | 1673 | colorDepth:24 03:12:13 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 03:12:13 INFO - PROCESS | 1673 | __metrics 03:12:13 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:12:14 INFO - TEST-INFO | 1673: exit 0 03:12:14 INFO - Browser initialized. 03:12:14 INFO - Running cycle 1/1 for kraken test... 03:12:14 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp4C8URU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:26 INFO - PROCESS | 1677 | RSS: Main: 221122560 03:12:26 INFO - PROCESS | 1677 | 03:12:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:12:30 INFO - PROCESS | 1677 | RSS: Main: 233091072 03:12:30 INFO - PROCESS | 1677 | 03:12:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:12:33 INFO - PROCESS | 1677 | RSS: Main: 242122752 03:12:33 INFO - PROCESS | 1677 | 03:12:36 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:12:37 INFO - PROCESS | 1677 | RSS: Main: 245526528 03:12:37 INFO - PROCESS | 1677 | 03:12:39 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:12:40 INFO - PROCESS | 1677 | RSS: Main: 246484992 03:12:40 INFO - PROCESS | 1677 | 03:12:42 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:12:42 INFO - PROCESS | 1677 | RSS: Main: 246276096 03:12:42 INFO - PROCESS | 1677 | 03:12:45 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:12:46 INFO - PROCESS | 1677 | RSS: Main: 246734848 03:12:46 INFO - PROCESS | 1677 | 03:12:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:12:49 INFO - PROCESS | 1677 | RSS: Main: 246759424 03:12:49 INFO - PROCESS | 1677 | 03:12:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:12:53 INFO - PROCESS | 1677 | RSS: Main: 246910976 03:12:53 INFO - PROCESS | 1677 | 03:12:54 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:12:55 INFO - PROCESS | 1677 | RSS: Main: 247578624 03:12:55 INFO - PROCESS | 1677 | 03:12:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:12:57 INFO - PROCESS | 1677 | RSS: Main: 247685120 03:12:57 INFO - PROCESS | 1677 | 03:12:59 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:13:00 INFO - PROCESS | 1677 | RSS: Main: 247877632 03:13:00 INFO - PROCESS | 1677 | 03:13:03 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:13:04 INFO - PROCESS | 1677 | RSS: Main: 248758272 03:13:04 INFO - PROCESS | 1677 | 03:13:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:13:07 INFO - PROCESS | 1677 | RSS: Main: 248020992 03:13:07 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:13:09 INFO - PROCESS | 1677 | RSS: Main: 249528320 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | __start_tp_report 03:13:09 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 03:13:09 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 03:13:09 INFO - PROCESS | 1677 | |i|pagename|runs| 03:13:09 INFO - PROCESS | 1677 | |0;ai-astar;114;126;157;173;134;118;107;116;116;117 03:13:09 INFO - PROCESS | 1677 | |1;audio-beat-detection;175;157;137;190;144;129;149;190;195;127 03:13:09 INFO - PROCESS | 1677 | |2;audio-dft;199;195;195;198;193;198;196;198;194;193 03:13:09 INFO - PROCESS | 1677 | |3;audio-fft;65;72;68;169;85;82;84;74;127;57 03:13:09 INFO - PROCESS | 1677 | |4;audio-oscillator;77;82;131;76;79;101;118;80;79;120 03:13:09 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;90;87;89;86;90;86;90;86;89;92 03:13:09 INFO - PROCESS | 1677 | |6;imaging-darkroom;154;113;95;96;95;97;95;113;96;97 03:13:09 INFO - PROCESS | 1677 | |7;imaging-desaturate;92;94;90;127;151;90;91;90;90;90 03:13:09 INFO - PROCESS | 1677 | |8;json-parse-financial;59;62;64;64;65;65;65;79;63;58 03:13:09 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;62;68;72;70;72;81;75;71;64;68 03:13:09 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;84;81;84;79;80;83;82;81;84;84 03:13:09 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;139;139;131;124;143;147;154;134;148;155 03:13:09 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;165;181;175;166;165;176;165;170;164;163 03:13:09 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;68;68;67;68;67;68;65;70;69;65 03:13:09 INFO - PROCESS | 1677 | __end_tp_report 03:13:09 INFO - PROCESS | 1677 | __start_cc_report 03:13:09 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1092 03:13:09 INFO - PROCESS | 1677 | __end_cc_report 03:13:09 INFO - PROCESS | 1677 | __startTimestamp1463566389373__endTimestamp 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | ------- Summary: start ------- 03:13:09 INFO - PROCESS | 1677 | Number of tests: 14 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:127.80 Median:122.00 stddev:21.22 (17.4%) stddev-sans-first:21.91 03:13:09 INFO - PROCESS | 1677 | Values: 114.0 126.0 157.0 173.0 134.0 118.0 107.0 116.0 116.0 117.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:159.30 Median:166.00 stddev:26.27 (15.8%) stddev-sans-first:27.24 03:13:09 INFO - PROCESS | 1677 | Values: 175.0 157.0 137.0 190.0 144.0 129.0 149.0 190.0 195.0 127.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:195.90 Median:197.00 stddev:2.23 (1.1%) stddev-sans-first:2.07 03:13:09 INFO - PROCESS | 1677 | Values: 199.0 195.0 195.0 198.0 193.0 198.0 196.0 198.0 194.0 193.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:88.30 Median:73.00 stddev:34.13 (46.8%) stddev-sans-first:35.14 03:13:09 INFO - PROCESS | 1677 | Values: 65.0 72.0 68.0 169.0 85.0 82.0 84.0 74.0 127.0 57.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:78.00 stddev:21.27 (27.3%) stddev-sans-first:21.62 03:13:09 INFO - PROCESS | 1677 | Values: 77.0 82.0 131.0 76.0 79.0 101.0 118.0 80.0 79.0 120.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.50 stddev:2.12 (2.4%) stddev-sans-first:2.18 03:13:09 INFO - PROCESS | 1677 | Values: 90.0 87.0 89.0 86.0 90.0 86.0 90.0 86.0 89.0 92.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:105.10 Median:95.50 stddev:18.62 (19.5%) stddev-sans-first:7.60 03:13:09 INFO - PROCESS | 1677 | Values: 154.0 113.0 95.0 96.0 95.0 97.0 95.0 113.0 96.0 97.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:90.00 stddev:21.10 (23.4%) stddev-sans-first:22.16 03:13:09 INFO - PROCESS | 1677 | Values: 92.0 94.0 90.0 127.0 151.0 90.0 91.0 90.0 90.0 90.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.50 stddev:5.70 (8.8%) stddev-sans-first:5.70 03:13:09 INFO - PROCESS | 1677 | Values: 59.0 62.0 64.0 64.0 65.0 65.0 65.0 79.0 63.0 58.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.50 stddev:5.40 (7.5%) stddev-sans-first:4.82 03:13:09 INFO - PROCESS | 1677 | Values: 62.0 68.0 72.0 70.0 72.0 81.0 75.0 71.0 64.0 68.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:83.50 stddev:1.87 (2.2%) stddev-sans-first:1.87 03:13:09 INFO - PROCESS | 1677 | Values: 84.0 81.0 84.0 79.0 80.0 83.0 82.0 81.0 84.0 84.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:145.00 stddev:9.99 (6.9%) stddev-sans-first:10.56 03:13:09 INFO - PROCESS | 1677 | Values: 139.0 139.0 131.0 124.0 143.0 147.0 154.0 134.0 148.0 155.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:168.00 stddev:6.22 (3.7%) stddev-sans-first:6.42 03:13:09 INFO - PROCESS | 1677 | Values: 165.0 181.0 175.0 166.0 165.0 176.0 165.0 170.0 164.0 163.0 03:13:09 INFO - PROCESS | 1677 | 03:13:09 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.58 (2.3%) stddev-sans-first:1.67 03:13:09 INFO - PROCESS | 1677 | Values: 68.0 68.0 67.0 68.0 67.0 68.0 65.0 70.0 69.0 65.0 03:13:09 INFO - PROCESS | 1677 | -------- Summary: end -------- 03:13:09 INFO - PROCESS | 1677 | 03:13:10 INFO - TEST-INFO | 1677: exit 0 03:13:10 INFO - TEST-OK | kraken | took 59265ms 03:13:10 INFO - SUITE-END | took 1171s 03:13:10 INFO - Completed test suite (00:19:31) 03:13:10 INFO - javascript benchmark 03:13:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2932.2376182192625, 3061.6019917410736, 3453.723702103832, 3503.824808759561, 3518.7185025197987, 5665.71671416429, 5678.205051292812, 5679.7160141992945, 5716.3426205986325, 5743.942560574393, 5712.971435142818, 5724.8282551523525, 5732.0542110551705, 5750.6262092963925, 5775.162601422791, 4288.978555107228, 5187.766550505236, 5196.92204616931, 5270.736463176845, 5286.180642000496, 5077.517635824591, 5193.324867767185, 5204.167333226685, 5220.712860792655, 5228.816991405301, 4980.27785971034, 5114.795408183668, 5190.221466779975, 5264.315638966948, 5279.445658205891, 5193.37679478465, 5254.448282930294, 5270.5520030797425, 5314.893702125936, 5352.946470535284, 4878.390201224847, 5082.440931497532, 5172.767225474825, 5205.791768329263, 5257.631965762398, 4592.425946756655, 5475.479829416199, 5535.723213839292, 5541.0, 5569.220309156722, 5782.56630752691, 6093.025115981422, 6200.875982480326, 6201.782937597208, 6226.501879849601, 6141.762184093006, 6158.0, 6234.532410069218, 6238.750449981995, 6279.309275979638, 5809.709514524299, 5821.330546987066, 5825.475707186289, 5832.445917637818, 5832.591718579701, 6247.219097612799, 6248.437640612276, 6277.027060805583, 6278.497720182374, 6282.246130464282, 6034.668093254825, 6100.633961962297, 6116.67056464255, 6162.661053642093, 6164.229471316085, 6236.906446403307, 6283.08895210192, 6302.086197501337, 6302.401271879256, 6320.715567799368, 6199.969000154971, 6227.532935029801, 6273.0904018917, 6286.402791734778, 6306.779262725782, 5211.687298762086, 6105.633661980295, 6176.042713379434, 6240.843978900564, 6252.906206406908, 3979.3235150024566, 4900.558949694545, 4966.205407134841, 4970.179920313108, 5002.224655178453, 4311.245532031882, 4471.1728330259, 4476.082403107355, 4495.415595972503, 4542.068875880436, 5629.802956896489, 5680.147977803363, 5686.914696279559, 5692.23154874095, 5715.571332150105, 5145.279660847485, 6259.843503912348, 6271.122042913996, 6282.937170628326, 6295.055741638699, 5237.450067742909, 5636.859078522988, 5657.2928383952, 5658.519025882763, 5677.943220567824, 5306.071437498423, 5346.91979620306, 5366.490183432567, 5447.509724124848, 5455.699936503531, 6214.844628884314, 6228.626282422979, 6246.625202487865, 6251.437370636664, 6254.9687251563455, 6010.669413182317, 6047.123167140652, 6054.0, 6076.57463977535, 6144.662043587646, 6310.211223597051, 6315.210598675166, 6324.367563243639, 6330.556861019684, 6359.4276515112015, 6186.845328866814, 6198.163247961467, 6202.34875338083, 6222.12890195364, 6239.469645080219, 5021.246812977936, 6067.605605635619, 6124.045297608125, 6285.151504546828, 6296.244450665949, 5986.682929755447, 6063.6664983426335, 6077.787277445268, 6080.817575472743, 6106.2061931948565, 5098.337216161875, 6073.331933487401, 6077.149199112217, 6094.2077529920825, 6100.786472473442, 5052.671576347526, 5817.825465236049, 5826.883462330645, 5885.941140588539, 5936.257967754031, 6125.234345706786, 6150.04674275488, 6160.568760186744, 6163.630182189083, 6168.228971378578, 6022.608530445507, 6073.453389194994, 6078.3921607838865, 6078.787242446493, 6094.481969032682], "value": 5640.005274584219, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.5040508276474, 540.5999560325359, 581.0818906128318, 585.4876982640192, 605.6366180291825, 38565.0, 39607.20785584287, 39619.80190099049, 39644.405333919945, 39758.60241397585, 41324.760257192254, 41792.58207417925, 41947.79026104865, 41978.58021419785, 42047.36928946068, 9443.244540436748, 9612.086851749073, 9665.903340966606, 9701.932787393382, 9750.414975101517, 9573.856392154123, 9744.07431294026, 9778.755531111708, 9805.803883922317, 9814.165795907356, 9762.90237097627, 9816.214702823792, 9817.116459518642, 9826.50867456628, 9851.310408271424, 9719.222462203006, 9742.9512852436, 9796.26528010402, 9810.754731131707, 9820.50897455128, 9662.903370966342, 9673.70978870635, 9767.072128147815, 9778.853317200248, 9784.266180036455, 39660.00849978745, 39885.60114398848, 40063.99840003994, 40179.3973090401, 40183.59816401828, 2920.620319358491, 3215.951760723591, 3294.3081952789703, 3400.336934297813, 3437.4843773434186, 28502.00242991506, 28870.0, 29137.0, 29144.854275728696, 29948.8502557488, 2734.6581677290337, 2797.482465743844, 2812.2969257685577, 2851.0, 2874.1664917173994, 330827.0, 337245.0, 340736.2963185193, 341746.0, 343007.0, 37120.814395927846, 64484.35515644783, 65439.67280163569, 65595.688086238, 65718.67140664266, 35597.110072248404, 35937.8203108988, 36084.27831443356, 36314.45528317078, 36321.81839090788, 15485.922570387076, 15636.4527241546, 15869.603259918595, 15950.68098638044, 15950.920245398931, 12846.10077294599, 13213.603591892259, 13250.337483125804, 13349.332533373487, 13364.732705346034, 8256.215659512336, 8283.130271321545, 8338.082810890797, 8394.412391132562, 8454.746357609281, 3297.3075654112904, 3396.4056290149124, 3406.1825161961447, 3463.41122009259, 3487.511748355232, 749.4379215588309, 919.0120620333169, 1005.8893521712606, 1047.4439278649097, 1073.259450978822, 29956.850215748782, 30060.69939300579, 30360.240993975327, 30867.845660772, 30912.845435772677, 1043.7860238651058, 1106.994465027686, 1184.3604453594962, 1215.3497878634887, 1240.7456471423336, 13145.934270328587, 15228.314725837365, 15705.293261803246, 15737.763933541006, 15803.604909877115, 658258.0, 660749.0, 662472.0, 665003.0, 670923.0, 52076.73961630243, 60545.394546053976, 62475.0, 62488.06267905985, 62561.0, 1394.2610416479283, 1403.7964495148146, 1404.9297535123408, 1477.6601381682274, 1478.0097334785505, 30023.7487206496, 30410.695893041673, 30486.416300440003, 30754.692453075182, 30849.845750771103, 55410.614734631956, 55549.722251388484, 56016.87966240734, 56061.159082612976, 56218.0, 503039.0, 513848.0, 520827.0, 521282.0, 521627.0, 27891.302717432227, 28023.159305220877, 28248.011319603716, 28352.0, 28451.008429409743, 47113.0, 47140.0, 48305.75847120742, 48347.79130521624, 48418.757906210245, 38707.61388340516, 38957.6104238954, 39427.40858887062, 39507.40738888976, 40184.799076005605, 37864.74167927185, 38283.61716382801, 38420.84737457881, 38472.80763596164, 39415.01462463461, 30553.388932220787, 32811.17972050718, 38080.619193808816, 38251.2349753009, 38888.416673750486, 31037.0, 33531.66468335383, 33568.66431335655, 33581.16047098842, 33860.66139338575, 24742.0, 24979.87510062438, 25185.0967279674, 25252.368690782143, 25258.873705631355], "value": 18674.189945135196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.8139674353054, 398.1598826476135, 399.3590287588419, 404.8684177642267, 410.1121072877221, 20154.589178757455, 20299.49251268717, 20913.37259882202, 20950.89524552379, 21010.264640737558, 20895.059722312493, 21194.89402552985, 21633.45916352088, 21904.0, 21940.780592194074, 5767.452092051249, 5790.971045144768, 6104.725287362079, 6120.296165940907, 6125.816225513242, 5523.28197334348, 5643.8024669136585, 5908.645481271116, 5940.52475801937, 5997.040473524237, 6212.534059945522, 6221.688915554227, 6226.750929962796, 6234.781782637609, 6257.155284036642, 5961.850953726148, 6193.256809182904, 6199.597026193283, 6202.317745048041, 6224.190855188842, 6024.668643224621, 6046.18376519173, 6079.270487541523, 6082.96958515209, 6087.2086628738425, 13455.66360840977, 13647.931760341235, 13660.385282662204, 13742.862571374359, 13834.30828458573, 2222.511047569532, 2300.18343488061, 2318.9420264493356, 2421.5520128776056, 2766.695663477016, 9580.76048098803, 10103.545340459697, 10168.4915754212, 10225.64210252645, 10357.274990750575, 1718.8109307976113, 1759.9824001760076, 1780.305680784482, 1825.6531259060737, 1829.396299221267, 106608.40087398695, 110370.89629103687, 112918.0, 118036.0, 119138.40430797791, 11608.76782464346, 12008.519659213709, 12068.456919438646, 12076.154669173073, 12077.45651445687, 15463.690726185638, 15557.533274001798, 15583.519565641473, 15606.29771660278, 15609.453669121753, 9097.454152750855, 9103.271738260923, 9146.2682985361, 9161.038091000482, 9229.400088994194, 7992.680292788224, 8919.241864441596, 9080.046595107418, 9195.540222988824, 9249.907500924905, 3834.9233015339537, 4850.1269771440775, 4862.343583616237, 4869.220924652039, 4924.374956264424, 1735.6094878652455, 1987.5130593004806, 2024.9773864198485, 2037.521182522106, 2058.6191554562456, 820.4420993724325, 942.2744486745167, 968.8373951258518, 976.2385339435252, 1038.3406536849004, 16851.325946962228, 20198.495037623885, 20263.39209823678, 20496.487587810127, 21184.25855095095, 905.2034209895251, 972.6060945317158, 998.9051040151174, 1000.3797645459862, 1004.4023805835662, 10916.672499825017, 11233.887661123448, 11411.429428528707, 11438.542458301741, 11455.0, 610517.0, 611282.0, 611833.0, 620785.0, 624316.0, 121613.0, 132436.0, 134912.0, 135894.0, 141530.2923485376, 999.0209594597335, 1002.6490728245055, 1013.5894962540183, 1018.7300365403174, 1022.4121130350078, 18184.636307274046, 18610.534736631693, 18712.0, 18720.625587488445, 18785.0, 51067.0, 51423.48576514336, 52640.0, 55057.0, 55707.0, 57628.0, 71461.28538714546, 73834.63082684767, 76855.61572192327, 78691.0, 8142.145074767301, 8232.300254478198, 8283.95858020706, 8508.957455212685, 8644.394892357475, 10836.349819010884, 12485.438155283216, 12930.935345323212, 12953.740925181255, 13067.34663266852, 11023.228374013743, 11107.111431085656, 11117.94379533943, 11180.161487888603, 11250.66248012561, 10856.185786066235, 10856.565737370416, 10893.673189804318, 10894.836577451186, 10950.452477376259, 11165.106791456827, 11197.496112674786, 11343.773124537629, 11408.429578520874, 11564.884351156381, 8176.67293308261, 8305.418620696493, 8428.367872409472, 8480.321574274167, 8670.479771213748, 11116.49975751077, 11127.499262533043, 11223.55105795759, 11349.659510214708, 11441.0], "value": 9592.625529813819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.7948276982609, 1592.3550961860444, 1815.7911840138388, 1906.1708156951727, 1976.3300011490285, 7130.465215108861, 8722.127787221274, 8726.258268047224, 8929.642814287428, 8930.017698053218, 8686.391952563306, 8707.477551346909, 8777.60500777465, 8784.07767184446, 8807.559622018905, 2437.914672986446, 2460.5324988252173, 2463.4210960424375, 2464.0, 2475.935347800445, 2431.659567660529, 2487.875606219691, 2488.5769419198778, 2488.8880000400027, 2497.612870005152, 2451.166603354859, 2458.4960083182996, 2490.5267999080206, 2494.301595553248, 2502.874856257189, 2253.906855175239, 2317.895694693743, 2409.8433601815827, 2418.2140804238607, 2437.7445615508027, 2437.110454684045, 2440.8169387296025, 2445.8165637577254, 2453.5092981403614, 2459.9016039358403, 8933.28533717301, 8970.775730606721, 8982.910170898273, 9004.77488062797, 9016.053314402025, 5357.357117145929, 5519.558435325164, 5521.668699878021, 5530.917036244459, 5536.778528858841, 3542.769719968194, 3600.261946301002, 3625.5679006792434, 3637.436197389409, 3695.427208782643, 1058.8782290036668, 1083.0360978728938, 1084.0352086642895, 1084.783043391317, 1085.647164671485, 10561.947190264076, 12093.213941093889, 12094.0, 12100.455479503444, 12413.0, 4856.150173719585, 4868.367112275381, 4952.90094198114, 4967.3542439482635, 4975.726335051607, 10179.440130792878, 10205.540750666238, 10205.74485637865, 10214.693559193236, 10232.948835255776, 5490.752916118784, 5498.092814685612, 5507.504324610804, 5520.392756796749, 5532.75102620383, 4259.361095835649, 4775.617950563947, 4884.877878053006, 4899.828506002273, 4970.453250142481, 4029.294873397144, 4041.4341992121126, 4123.463949686581, 4131.958680413158, 4163.10493243954, 1320.3794216718127, 1350.2371160294401, 1356.923643200713, 1360.5238166641793, 1364.4405793624567, 717.1752484642599, 863.2877875752529, 926.545992463688, 928.9535523223811, 961.9038096190325, 5226.634135610547, 5852.180694702745, 6007.579469437097, 6138.140660307471, 6151.3541078187045, 406.80473372780637, 432.5609506351056, 437.64550713921824, 475.5316013726477, 482.2091769498026, 3945.11234972129, 3960.801959902051, 4077.082656402286, 4127.587241275848, 4129.0, 10399.0, 10783.29908555957, 10846.078083363003, 10860.45697715111, 10866.456677166268, 10014.148797352162, 10424.478776061167, 10451.059404653617, 10591.0, 10602.469876506144, 8346.916530834735, 8401.15988401155, 8437.873431898526, 8448.197421244971, 8466.238038576434, 5743.540516758733, 6026.7589296429405, 6055.000924847527, 6064.090386442069, 6117.663528505975, 8812.250958668585, 9652.75868103303, 9670.323077384797, 9719.805603888024, 9887.752806179615, 9753.512324383895, 10094.0, 10127.29108962395, 10165.339252948535, 10236.795264094826, 5660.971695141498, 6843.418309443753, 7522.699092036476, 7582.54504729718, 7639.930409742752, 8326.167383261625, 8341.45780524264, 8363.623636936474, 8389.203025712548, 8399.370047246359, 6376.234851817811, 7299.635018249172, 7398.408127349907, 7529.247075292427, 7536.7362142324755, 7232.74685386009, 7254.310840470147, 7315.63421828917, 7327.780166595011, 7334.596597187206, 7075.257098004636, 7104.857902842017, 7125.216226215216, 7134.357907788324, 7159.212486626363, 5003.199488081889, 5005.899882002267, 5014.7994080236385, 5071.898562028812, 5087.618428617794, 4394.780260987001, 5005.199168133081, 5012.083325011607, 5140.64015518925, 5173.456787037458], "value": 4562.414501410737, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.3900550336039, 334.40642858925406, 339.4805946901242, 340.3550272234118, 344.2735827404175, 6799.184097908252, 6815.352541508555, 6819.21579018413, 6839.0425340452375, 6867.175938887328, 6741.292164322749, 6755.932440675591, 6762.256151823295, 6780.084688567042, 6791.694373753181, 4739.620830333565, 4834.274858771178, 4918.360613120307, 4935.407751069876, 4946.826861059863, 4350.1734670412525, 4543.522930092342, 4868.05073010763, 4920.146315137212, 4938.234573641092, 4605.608523275526, 4877.0489754497885, 4898.191798353268, 4955.033768415161, 4966.900661986758, 4941.827036053757, 4943.332650092246, 4947.381577302837, 4960.181570040939, 4972.105021096201, 4259.978700106511, 4260.467441569804, 4263.701540892139, 4275.123599662062, 4278.764667943262, 5684.0, 6250.468710159642, 6277.686115694197, 6514.348565143495, 6543.280239173687, 5853.619514731528, 5902.6163299385835, 5923.614965027259, 5930.703464826742, 5937.970310148422, 7175.210726820045, 7197.928020719831, 7207.45944054198, 7258.528195667264, 7275.12698476181, 4768.61851051915, 4785.377900872864, 4792.329073929653, 4806.879828004293, 4808.230683090724, 7585.279398457193, 7612.733554325572, 7613.885791713129, 7628.275313845232, 7649.6940122395035, 4497.617702495325, 4668.50980647034, 4672.509386514435, 4699.412573428322, 4708.246680531167, 4730.432348118179, 4767.165745994437, 4775.617950563947, 4784.330193772875, 4799.616030717534, 5339.11904535755, 5522.22688823565, 5718.542516598662, 5803.680797556175, 5812.157237200582, 7281.4538909582, 7301.415886729049, 7304.96347518259, 7317.414606831547, 7335.1197856257595, 1644.4819881737214, 1668.1992643531173, 1669.4741156535651, 1672.2976349933058, 1676.1451659653421, 1033.178622994721, 1059.8092343378112, 1060.7666313411037, 1065.717584840018, 1074.6131392698621, 5570.944290557123, 6725.159355080615, 6748.966255168692, 6765.0, 6787.932120678829, 7267.927320726725, 7521.962390188014, 7537.283958023979, 7543.207963163789, 7566.092068951651, 5707.828765137053, 5968.4926781224085, 6549.279579246279, 6775.966120169368, 6793.354631310018, 3039.0730827097946, 3301.0, 3890.727649064595, 4001.9599804001587, 4011.679065674739, 7935.650939340326, 7940.920590794133, 7942.920570794334, 7951.608468518102, 7981.48120372174, 6457.773977910751, 7403.5928023959195, 7738.458307918391, 7788.026496687914, 7816.84366312682, 6966.407855332353, 7450.552966822008, 7874.291313781787, 7953.721619743281, 7961.323287520626, 7543.0, 7552.9244707552225, 7560.054993125859, 7591.316781489692, 7607.505512141693, 6111.84720381994, 7386.002889610048, 7436.702531898663, 7437.0331856858265, 7481.401487881065, 7567.0, 7917.7624671259955, 7946.284834364935, 7996.520208787491, 7999.400044996765, 4693.202155633482, 4713.717176969393, 4724.385829842098, 4731.881702957316, 4752.738599377068, 7499.962500187464, 7503.512271702322, 7517.210692877388, 7526.5107767994905, 7563.319301262913, 6758.357955994173, 7164.961080643173, 7256.4194864411775, 7269.127704675473, 7279.417646588155, 5816.76732930678, 7040.1551813780625, 7282.854342913218, 7284.708611655687, 7291.489595728461, 7026.929730702627, 7082.681279342545, 7098.183708873546, 7102.254263302486, 7129.0, 7223.602701851239, 7251.383632391306, 7263.6005019724425, 7280.854382912418, 7296.817579560341, 7054.929450705633, 7096.7516136934955, 7105.111861017373, 7109.324614161853, 7110.4667149965], "value": 5242.6120000545925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.716806109495, 704.9531445802982, 722.8315054835493, 743.3421422041491, 778.4434129597337, 14647.047941883784, 14865.108093514396, 14951.102933823964, 14964.32660530276, 15001.774973375395, 13527.864721352784, 13846.792298115512, 13866.930665346685, 13886.0, 13916.23460709663, 745.679357876113, 905.9456432614064, 913.4793167894303, 913.7898283394824, 935.5275585829147, 934.7149119518544, 949.9477528735916, 950.653011650746, 956.5934477846887, 959.52023988006, 979.0992287095889, 988.293370240278, 992.4243938515643, 993.0417147452707, 996.4619105682923, 957.1529196660936, 988.2242439684885, 988.663854289545, 989.7129832348646, 994.005994005994, 720.9906131416008, 723.5297056913049, 739.9963000185019, 749.647665597174, 749.8762704153808, 14836.480723174636, 14892.0320179188, 14907.329170187368, 14929.253537323091, 14963.102213867203, 12799.74400511994, 12820.0, 12954.352282385937, 12986.480540778357, 13122.934385328108, 8380.497170169749, 9977.451240181786, 10054.195664346908, 10122.595096196143, 10160.542775575117, 3083.6762139975426, 3374.578177727784, 3519.8768043118366, 3568.268504956504, 3638.6725194732603, 69979.0, 71185.64407177983, 71605.92591111138, 71693.0, 73198.63400683015, 9423.481708506099, 9527.237820974306, 9558.044195580387, 9620.518974051269, 9888.752781180528, 22029.559408812056, 22370.888145559493, 22400.775992240197, 22432.551348972927, 22451.214207502984, 7571.0, 7590.051243594551, 7592.392608591299, 7638.961805190938, 7723.575203363869, 4069.023434375788, 4087.2029954159248, 4118.5057793065025, 4157.147784704129, 4187.371894215892, 3680.9447858282147, 3723.478712980185, 3752.7373083883867, 3758.229562939591, 3760.172761992357, 675.166169780321, 685.4310921934782, 686.084077756191, 692.7263730826396, 696.738722978883, 554.6062295769967, 586.7447660267798, 604.4529700620946, 606.9757209711651, 612.402907165519, 8016.398770092266, 8285.42002059862, 8292.834143317099, 8334.791630209293, 8487.023992240847, 511.2739909328762, 564.25800072904, 590.3293678888734, 628.8522197283635, 639.792067578039, 3274.8198849063056, 3721.5348081489815, 3721.925561488755, 3830.7893065881085, 3968.503937007874, 88585.55707221423, 89037.0, 89480.6577901332, 91683.54158229165, 92593.0, 71656.28343716497, 72023.63988180025, 76257.85613215806, 76315.23684763082, 76765.0, 63328.0, 68669.0, 68676.0, 69955.0, 70167.544135955, 8315.792105197419, 8322.26560549622, 8444.07115217338, 8475.194856488624, 8553.272971797467, 44739.0, 45886.541134588224, 46483.53516464792, 46710.532894671975, 48446.0, 61652.0, 62265.707414870245, 62802.37197627965, 64335.71328573309, 64469.67765161144, 10614.363138211733, 10824.0, 10922.563097476012, 11028.613998510014, 11609.709757255798, 11723.648290551297, 11892.821607676065, 11955.521779128738, 11989.280643161439, 12214.328211948428, 10957.06864916491, 11346.319220846775, 11517.308961462339, 11546.942265288955, 11614.187006909773, 11130.0, 11207.103431725605, 11326.32042077478, 11357.943210283896, 11482.82775758381, 9045.547722613712, 10426.947865260929, 11178.944105279421, 11280.210385272952, 11321.943390282995, 8568.914310856811, 9100.362974591715, 9341.252699784136, 9427.198688111588, 9581.616735330508, 9467.137400969197, 9701.417914925127, 9740.805183896424, 9814.558344874355, 9845.950770246389], "value": 6879.3341525634905, "unit": "score"}], "name": "dromaeo_css", "value": 7415.330048619871, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 126.0, 157.0, 173.0, 134.0, 118.0, 107.0, 116.0, 116.0, 117.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 157.0, 137.0, 190.0, 144.0, 129.0, 149.0, 190.0, 195.0, 127.0], "value": 159.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 195.0, 195.0, 198.0, 193.0, 198.0, 196.0, 198.0, 194.0, 193.0], "value": 195.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 68.0, 169.0, 85.0, 82.0, 84.0, 74.0, 127.0, 57.0], "value": 88.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 131.0, 76.0, 79.0, 101.0, 118.0, 80.0, 79.0, 120.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 90.0, 86.0, 90.0, 86.0, 89.0, 92.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 113.0, 95.0, 96.0, 95.0, 97.0, 95.0, 113.0, 96.0, 97.0], "value": 105.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 90.0, 127.0, 151.0, 90.0, 91.0, 90.0, 90.0, 90.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 64.0, 64.0, 65.0, 65.0, 65.0, 79.0, 63.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 68.0, 72.0, 70.0, 72.0, 81.0, 75.0, 71.0, 64.0, 68.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 84.0, 79.0, 80.0, 83.0, 82.0, 81.0, 84.0, 84.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 131.0, 124.0, 143.0, 147.0, 154.0, 134.0, 148.0, 155.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 181.0, 175.0, 166.0, 165.0, 176.0, 165.0, 170.0, 164.0, 163.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 65.0, 70.0, 69.0, 65.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1554.5, "alertThreshold": 2.0}]} 03:13:10 INFO - Return code: 0 03:13:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:13:10 ERROR - # TBPL SUCCESS # 03:13:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:13:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:13:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:13:10 INFO - 2016-05-18 03:13:10.673 screenresolution[1682:10220] starting screenresolution argv=screenresolution get 03:13:10 INFO - 2016-05-18 03:13:10.675 screenresolution[1682:10220] Display 0: 1600x1200x32@60 03:13:10 INFO - 2016-05-18 03:13:10.682 screenresolution[1683:10223] starting screenresolution argv=screenresolution list 03:13:10 INFO - Available Modes on Display 0 03:13:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:13:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:13:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:13:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:13:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:13:10 INFO - Intel Iris: 03:13:10 INFO - Chipset Model: Intel Iris 03:13:10 INFO - Type: GPU 03:13:10 INFO - Bus: Built-In 03:13:10 INFO - VRAM (Dynamic, Max): 1536 MB 03:13:10 INFO - Vendor: Intel (0x8086) 03:13:10 INFO - Device ID: 0x0a2e 03:13:10 INFO - Revision ID: 0x0009 03:13:10 INFO - Displays: 03:13:10 INFO - Display: 03:13:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:13:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:13:10 INFO - Display Serial Number: 1600x1200 60 03:13:10 INFO - Main Display: Yes 03:13:10 INFO - Mirror: Off 03:13:10 INFO - Online: Yes 03:13:10 INFO - Rotation: Supported 03:13:10 INFO - Return code: 0 03:13:10 INFO - Running post-action listener: _resource_record_post_action 03:13:10 INFO - Running post-run listener: _resource_record_post_run 03:13:11 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 36554240; Write bytes: 653592064; Read time: 242; Write time: 1830 03:13:11 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346673664; Write bytes: 345552384; Read time: 26497; Write time: 679 03:13:11 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 35599872; Write bytes: 298082304; Read time: 230; Write time: 1116 03:13:11 INFO - Running post-run listener: _upload_blobber_files 03:13:11 INFO - Blob upload gear active. 03:13:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:13:11 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 03:13:11 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'] 03:13:11 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 03:13:12 INFO - (blobuploader) - INFO - Open directory for files ... 03:13:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:13:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:13:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:14 INFO - (blobuploader) - INFO - Done attempting. 03:13:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:13:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:13:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:15 INFO - (blobuploader) - INFO - Done attempting. 03:13:15 INFO - (blobuploader) - INFO - Iteration through files over. 03:13:15 INFO - Return code: 0 03:13:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:13:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:13:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80f06970a85968bd3a70b33bc8e554bf7360743793f9f746d17e06c33a639c82f7e1916df2db0ea61d675b8d00ee7c24d11b1b535c583960027ad1189e4d3f78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6bbeb9dba8c3419c913c97f6f10d736f6d98eb5de96c9f52d2b3f6a66bb2b8ba7854145f3a417655c97ee6e013c395e6430e8fc596880f4bcdbcb16f533d066"} 03:13:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:13:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:13:15 INFO - Contents: 03:13:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80f06970a85968bd3a70b33bc8e554bf7360743793f9f746d17e06c33a639c82f7e1916df2db0ea61d675b8d00ee7c24d11b1b535c583960027ad1189e4d3f78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6bbeb9dba8c3419c913c97f6f10d736f6d98eb5de96c9f52d2b3f6a66bb2b8ba7854145f3a417655c97ee6e013c395e6430e8fc596880f4bcdbcb16f533d066"} 03:13:15 INFO - Running post-run listener: copy_logs_to_upload_dir 03:13:15 INFO - Copying logs to upload dir... 03:13:15 INFO - mkdir: /builds/slave/test/build/upload/logs 03:13:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.131955 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-18 03:13:15.402281) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 03:13:15.405780) ========= 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.EwO54eVUWn/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.VYUR7bSL09/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80f06970a85968bd3a70b33bc8e554bf7360743793f9f746d17e06c33a639c82f7e1916df2db0ea61d675b8d00ee7c24d11b1b535c583960027ad1189e4d3f78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6bbeb9dba8c3419c913c97f6f10d736f6d98eb5de96c9f52d2b3f6a66bb2b8ba7854145f3a417655c97ee6e013c395e6430e8fc596880f4bcdbcb16f533d066"} build_url:https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010893 build_url: 'https://queue.taskcluster.net/v1/task/Xoo5hC9nQ1OmradS8kd_qQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80f06970a85968bd3a70b33bc8e554bf7360743793f9f746d17e06c33a639c82f7e1916df2db0ea61d675b8d00ee7c24d11b1b535c583960027ad1189e4d3f78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6bbeb9dba8c3419c913c97f6f10d736f6d98eb5de96c9f52d2b3f6a66bb2b8ba7854145f3a417655c97ee6e013c395e6430e8fc596880f4bcdbcb16f533d066"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 03:13:15.443770) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 03:13:15.444082) ========= 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.EwO54eVUWn/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.VYUR7bSL09/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.005137 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 03:13:15.494584) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 03:13:15.494894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 03:13:15.495198) ========= ========= Total master_lag: 0.20 =========