builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0317 starttime: 1464052033.61 results: success (0) buildid: 20160523170350 builduid: 1c7caa8606bd45b590752adeac87ea70 revision: a2baee5a734efd4bb9646e5ad06d7552d6618bcb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.605514) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.605962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.606231) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.679156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.679511) ========= 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.LOxSyLXixL/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.QjKJewd0JV/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-23 18:07:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 61.6M=0s 2016-05-23 18:07:13 (61.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138879 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.844236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.844501) ========= 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.LOxSyLXixL/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.QjKJewd0JV/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.025247 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.898913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:13.899204) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a2baee5a734efd4bb9646e5ad06d7552d6618bcb --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 a2baee5a734efd4bb9646e5ad06d7552d6618bcb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LOxSyLXixL/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.QjKJewd0JV/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-23 18:07:13,980 truncating revision to first 12 chars 2016-05-23 18:07:13,980 Setting DEBUG logging. 2016-05-23 18:07:13,980 attempt 1/10 2016-05-23 18:07:13,980 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a2baee5a734e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 18:07:14,676 unpacking tar archive at: mozilla-aurora-a2baee5a734e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.953566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:14.873946) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:14.874597) ========= script_repo_revision: a2baee5a734efd4bb9646e5ad06d7552d6618bcb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:14.875490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:14.876104) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 18:07:14.892510) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-05-23 18:07:14.893152) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LOxSyLXixL/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.QjKJewd0JV/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:07:15 INFO - MultiFileLogger online at 20160523 18:07:15 in /builds/slave/test 18:07:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:07:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:07:15 INFO - {'append_to_log': False, 18:07:15 INFO - 'base_work_dir': '/builds/slave/test', 18:07:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:07:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:07:15 INFO - 'branch': 'Mozilla-Aurora', 18:07:15 INFO - 'buildbot_json_path': 'buildprops.json', 18:07:15 INFO - 'config_files': ('talos/mac_config.py',), 18:07:15 INFO - 'default_actions': ('clobber', 18:07:15 INFO - 'read-buildbot-config', 18:07:15 INFO - 'download-and-extract', 18:07:15 INFO - 'populate-webroot', 18:07:15 INFO - 'create-virtualenv', 18:07:15 INFO - 'install', 18:07:15 INFO - 'run-tests'), 18:07:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:07:15 INFO - 'download_minidump_stackwalk': True, 18:07:15 INFO - 'download_symbols': 'ondemand', 18:07:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:07:15 INFO - 'tooltool.py': '/tools/tooltool.py', 18:07:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:07:15 INFO - '/tools/misc-python/virtualenv.py')}, 18:07:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:07:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:07:15 INFO - 'installer_path': 'installer.exe', 18:07:15 INFO - 'log_level': 'info', 18:07:15 INFO - 'log_name': 'talos', 18:07:15 INFO - 'log_to_console': True, 18:07:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:07:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:07:15 INFO - 'opt_config_files': (), 18:07:15 INFO - 'pip_index': False, 18:07:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:07:15 INFO - 'cmd': ('bash', 18:07:15 INFO - '-c', 18:07:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:07:15 INFO - 'enabled': True, 18:07:15 INFO - 'halt_on_failure': False, 18:07:15 INFO - 'name': 'check_screen_resolution'},), 18:07:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:07:15 INFO - 'cmd': ('bash', 18:07:15 INFO - '-c', 18:07:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:07:15 INFO - 'enabled': True, 18:07:15 INFO - 'halt_on_failure': False, 18:07:15 INFO - 'name': 'check_screen_resolution'},), 18:07:15 INFO - 'run_cmd_checks_enabled': True, 18:07:15 INFO - 'sps_profile': False, 18:07:15 INFO - 'sps_profile_interval': 0, 18:07:15 INFO - 'suite': 'dromaeojs', 18:07:15 INFO - 'system_bits': '32', 18:07:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:07:15 INFO - 'title': 't-yosemite-r7-0317', 18:07:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:07:15 INFO - 'use_talos_json': True, 18:07:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:07:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:07:15 INFO - 'work_dir': 'build'} 18:07:15 INFO - ##### 18:07:15 INFO - ##### Running clobber step. 18:07:15 INFO - ##### 18:07:15 INFO - Running pre-action listener: _resource_record_pre_action 18:07:15 INFO - Running main action method: clobber 18:07:15 INFO - rmtree: /builds/slave/test/build 18:07:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:07:16 INFO - Running post-action listener: _resource_record_post_action 18:07:16 INFO - ##### 18:07:16 INFO - ##### Running read-buildbot-config step. 18:07:16 INFO - ##### 18:07:16 INFO - Running pre-action listener: _resource_record_pre_action 18:07:16 INFO - Running main action method: read_buildbot_config 18:07:16 INFO - Using buildbot properties: 18:07:16 INFO - { 18:07:16 INFO - "project": "", 18:07:16 INFO - "product": "firefox", 18:07:16 INFO - "script_repo_revision": "production", 18:07:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:07:16 INFO - "repository": "", 18:07:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:07:16 INFO - "buildid": "20160523170350", 18:07:16 INFO - "pgo_build": "False", 18:07:16 INFO - "basedir": "/builds/slave/test", 18:07:16 INFO - "buildnumber": 355, 18:07:16 INFO - "slavename": "t-yosemite-r7-0317", 18:07:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:07:16 INFO - "platform": "yosemite_r7", 18:07:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:07:16 INFO - "branch": "mozilla-aurora", 18:07:16 INFO - "repo_path": "releases/mozilla-aurora", 18:07:16 INFO - "revision": "a2baee5a734efd4bb9646e5ad06d7552d6618bcb", 18:07:16 INFO - "stage_platform": "macosx64", 18:07:16 INFO - "builduid": "1c7caa8606bd45b590752adeac87ea70", 18:07:16 INFO - "slavebuilddir": "test" 18:07:16 INFO - } 18:07:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:07:16 INFO - Running post-action listener: _resource_record_post_action 18:07:16 INFO - ##### 18:07:16 INFO - ##### Running download-and-extract step. 18:07:16 INFO - ##### 18:07:16 INFO - Running pre-action listener: _resource_record_pre_action 18:07:16 INFO - Running main action method: download_and_extract 18:07:16 INFO - mkdir: /builds/slave/test/build/tests 18:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:16 INFO - https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:07:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:07:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:07:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:07:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:07:40 INFO - Downloaded 1199 bytes. 18:07:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:07:40 INFO - Using the following test package requirements: 18:07:40 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:07:40 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:07:40 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:07:40 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'jsshell-mac.zip'], 18:07:40 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:07:40 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:07:40 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:07:40 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:07:40 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:07:40 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:07:40 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:07:40 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:40 INFO - https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:07:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:07:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:07:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:07:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:07:43 INFO - Downloaded 18102374 bytes. 18:07:43 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'] 18:07:43 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 18:07:46 INFO - Return code: 0 18:07:46 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 18:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:46 INFO - https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:07:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:07:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:07:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:07:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:08:00 INFO - Downloaded 18102374 bytes. 18:08:00 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'] 18:08:00 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 18:08:03 INFO - Return code: 0 18:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:03 INFO - https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:08:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:08:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:08:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:08:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:08:05 INFO - Downloaded 10840145 bytes. 18:08:05 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'] 18:08:05 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 18:08:05 INFO - Return code: 0 18:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:05 INFO - https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:08:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:08:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:08:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q5r8eDjBStOa90zSbhb8NA/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 18:08:10 INFO - Downloaded 114133051 bytes. 18:08:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:08:10 INFO - mkdir: /builds/slave/test/properties 18:08:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:08:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:08:10 INFO - Contents: 18:08:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:08:15 INFO - Running post-action listener: _resource_record_post_action 18:08:15 INFO - Running post-action listener: set_extra_try_arguments 18:08:15 INFO - ##### 18:08:15 INFO - ##### Running populate-webroot step. 18:08:15 INFO - ##### 18:08:15 INFO - Running pre-action listener: _resource_record_pre_action 18:08:15 INFO - Running main action method: populate_webroot 18:08:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:08:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:08:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:08:15 INFO - '--tppagecycles', 18:08:15 INFO - '7'], 18:08:15 INFO - 'tests': ['tsvgm']}}, 18:08:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:08:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:08:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:08:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:08:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['damp', 'tps']}, 18:08:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'tests': ['damp', 'tps']}, 18:08:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['dromaeo_dom']}, 18:08:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:08:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['a11yr', 18:08:15 INFO - 'ts_paint', 18:08:15 INFO - 'tpaint', 18:08:15 INFO - 'sessionrestore', 18:08:15 INFO - 'sessionrestore_no_auto_restore', 18:08:15 INFO - 'tabpaint']}, 18:08:15 INFO - 'other-e10s': {'tests': ['a11yr', 18:08:15 INFO - 'ts_paint', 18:08:15 INFO - 'tpaint', 18:08:15 INFO - 'sessionrestore', 18:08:15 INFO - 'sessionrestore_no_auto_restore', 18:08:15 INFO - 'tabpaint']}, 18:08:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:08:15 INFO - 'ts_paint', 18:08:15 INFO - 'tpaint', 18:08:15 INFO - 'sessionrestore', 18:08:15 INFO - 'sessionrestore_no_auto_restore', 18:08:15 INFO - 'tabpaint']}, 18:08:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:08:15 INFO - 'ts_paint', 18:08:15 INFO - 'tpaint', 18:08:15 INFO - 'sessionrestore', 18:08:15 INFO - 'sessionrestore_no_auto_restore', 18:08:15 INFO - 'tabpaint']}, 18:08:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['a11yr', 18:08:15 INFO - 'ts_paint', 18:08:15 INFO - 'tpaint', 18:08:15 INFO - 'sessionrestore', 18:08:15 INFO - 'sessionrestore_no_auto_restore', 18:08:15 INFO - 'tabpaint']}, 18:08:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['a11yr', 18:08:15 INFO - 'ts_paint', 18:08:15 INFO - 'tpaint', 18:08:15 INFO - 'sessionrestore', 18:08:15 INFO - 'sessionrestore_no_auto_restore', 18:08:15 INFO - 'tabpaint']}, 18:08:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['tsvgx', 18:08:15 INFO - 'tsvgr_opacity', 18:08:15 INFO - 'tart', 18:08:15 INFO - 'tscrollx', 18:08:15 INFO - 'cart']}, 18:08:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:08:15 INFO - 'tsvgr_opacity', 18:08:15 INFO - 'tart', 18:08:15 INFO - 'tscrollx', 18:08:15 INFO - 'cart']}, 18:08:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'talos_options': ['--disable-e10s'], 18:08:15 INFO - 'tests': ['tp5o']}, 18:08:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'tests': ['tp5o']}, 18:08:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'talos_options': ['--disable-e10s', 18:08:15 INFO - '--xperf_path', 18:08:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:15 INFO - 'tests': ['tp5n']}, 18:08:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:15 INFO - 'talos_options': ['--xperf_path', 18:08:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:15 INFO - 'tests': ['tp5n']}}, 18:08:15 INFO - 'talos.zip': {'path': '', 18:08:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:08:15 INFO - Running post-action listener: _resource_record_post_action 18:08:15 INFO - ##### 18:08:15 INFO - ##### Running create-virtualenv step. 18:08:15 INFO - ##### 18:08:15 INFO - Running pre-action listener: _resource_record_pre_action 18:08:15 INFO - Running main action method: create_virtualenv 18:08:15 INFO - Creating virtualenv /builds/slave/test/build/venv 18:08:15 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 18:08:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:08:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:08:15 INFO - Using real prefix '/tools/python27' 18:08:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:08:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:08:18 INFO - Installing pip.................done. 18:08:18 INFO - Return code: 0 18:08:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:08:18 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')]} 18:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:19 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:19 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 18:08:19 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 18:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:19 INFO - 'HOME': '/Users/cltbld', 18:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:19 INFO - 'LOGNAME': 'cltbld', 18:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:19 INFO - 'MOZ_NO_REMOTE': '1', 18:08:19 INFO - 'NO_EM_RESTART': '1', 18:08:19 INFO - 'PAGER': '/bin/cat', 18:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:19 INFO - 'PWD': '/builds/slave/test', 18:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:19 INFO - 'SHELL': '/bin/bash', 18:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:19 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:19 INFO - 'USER': 'cltbld', 18:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:19 INFO - 'XPC_FLAGS': '0x0', 18:08:19 INFO - 'XPC_SERVICE_NAME': '0', 18:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:19 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 18:08:19 INFO - Cleaning up... 18:08:19 INFO - Return code: 0 18:08:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:19 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:19 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 18:08:19 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 18:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:19 INFO - 'HOME': '/Users/cltbld', 18:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:19 INFO - 'LOGNAME': 'cltbld', 18:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:19 INFO - 'MOZ_NO_REMOTE': '1', 18:08:19 INFO - 'NO_EM_RESTART': '1', 18:08:19 INFO - 'PAGER': '/bin/cat', 18:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:19 INFO - 'PWD': '/builds/slave/test', 18:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:19 INFO - 'SHELL': '/bin/bash', 18:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:19 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:19 INFO - 'USER': 'cltbld', 18:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:19 INFO - 'XPC_FLAGS': '0x0', 18:08:19 INFO - 'XPC_SERVICE_NAME': '0', 18:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:19 INFO - Downloading/unpacking psutil>=0.7.1 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:08:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:23 INFO - Installing collected packages: psutil 18:08:23 INFO - Running setup.py install for psutil 18:08:23 INFO - building 'psutil._psutil_osx' extension 18:08:23 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 18:08:23 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 18:08:23 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 18:08:24 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 18:08:24 INFO - building 'psutil._psutil_posix' extension 18:08:24 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 18:08:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:08:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:08:24 INFO - ^ 18:08:24 INFO - 1 warning generated. 18:08:24 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 18:08:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:24 INFO - Successfully installed psutil 18:08:24 INFO - Cleaning up... 18:08:24 INFO - Return code: 0 18:08:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:24 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:24 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 18:08:24 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 18:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:24 INFO - 'HOME': '/Users/cltbld', 18:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:24 INFO - 'LOGNAME': 'cltbld', 18:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:24 INFO - 'MOZ_NO_REMOTE': '1', 18:08:24 INFO - 'NO_EM_RESTART': '1', 18:08:24 INFO - 'PAGER': '/bin/cat', 18:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:24 INFO - 'PWD': '/builds/slave/test', 18:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:24 INFO - 'SHELL': '/bin/bash', 18:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:24 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:24 INFO - 'USER': 'cltbld', 18:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:24 INFO - 'XPC_FLAGS': '0x0', 18:08:24 INFO - 'XPC_SERVICE_NAME': '0', 18:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:08:24 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 18:08:24 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 18:08:24 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 18:08:24 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 18:08:24 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 18:08:24 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 18:08:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:08:27 INFO - Installing collected packages: mozsystemmonitor 18:08:27 INFO - Running setup.py install for mozsystemmonitor 18:08:27 INFO - Successfully installed mozsystemmonitor 18:08:27 INFO - Cleaning up... 18:08:27 INFO - Return code: 0 18:08:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:27 INFO - 'HOME': '/Users/cltbld', 18:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:27 INFO - 'LOGNAME': 'cltbld', 18:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:27 INFO - 'MOZ_NO_REMOTE': '1', 18:08:27 INFO - 'NO_EM_RESTART': '1', 18:08:27 INFO - 'PAGER': '/bin/cat', 18:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:27 INFO - 'PWD': '/builds/slave/test', 18:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:27 INFO - 'SHELL': '/bin/bash', 18:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:27 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:27 INFO - 'USER': 'cltbld', 18:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:27 INFO - 'XPC_FLAGS': '0x0', 18:08:27 INFO - 'XPC_SERVICE_NAME': '0', 18:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - Downloading blobuploader-1.2.4.tar.gz 18:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:08:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:08:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:31 INFO - Downloading docopt-0.6.1.tar.gz 18:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:08:31 INFO - Installing collected packages: blobuploader, requests, docopt 18:08:31 INFO - Running setup.py install for blobuploader 18:08:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:08:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:08:31 INFO - Running setup.py install for requests 18:08:32 INFO - Running setup.py install for docopt 18:08:32 INFO - Successfully installed blobuploader requests docopt 18:08:32 INFO - Cleaning up... 18:08:32 INFO - Return code: 0 18:08:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:32 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:32 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 18:08:32 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 18:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:32 INFO - 'HOME': '/Users/cltbld', 18:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:32 INFO - 'LOGNAME': 'cltbld', 18:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:32 INFO - 'MOZ_NO_REMOTE': '1', 18:08:32 INFO - 'NO_EM_RESTART': '1', 18:08:32 INFO - 'PAGER': '/bin/cat', 18:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:32 INFO - 'PWD': '/builds/slave/test', 18:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:32 INFO - 'SHELL': '/bin/bash', 18:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:32 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:32 INFO - 'USER': 'cltbld', 18:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:32 INFO - 'XPC_FLAGS': '0x0', 18:08:32 INFO - 'XPC_SERVICE_NAME': '0', 18:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:32 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-0I2f4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:32 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-bPa4BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:32 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-L1LHDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:32 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-6jI6gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:32 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-gJreHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-eFi9ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-32OTnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-H5NsQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-YUzOcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-GrbS7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-iyBVjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-gMUoBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-xpzcxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-i075Pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:33 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-B7567Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:34 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-WOnu5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:34 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-FlCbY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:08:34 INFO - Running setup.py install for manifestparser 18:08:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Running setup.py install for mozcrash 18:08:34 INFO - Running setup.py install for mozdebug 18:08:34 INFO - Running setup.py install for mozdevice 18:08:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Running setup.py install for mozfile 18:08:35 INFO - Running setup.py install for mozhttpd 18:08:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Running setup.py install for mozinfo 18:08:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Running setup.py install for mozInstall 18:08:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Running setup.py install for mozleak 18:08:35 INFO - Running setup.py install for mozlog 18:08:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Running setup.py install for moznetwork 18:08:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Running setup.py install for mozprocess 18:08:36 INFO - Running setup.py install for mozprofile 18:08:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozrunner 18:08:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozscreenshot 18:08:36 INFO - Running setup.py install for moztest 18:08:36 INFO - Running setup.py install for mozversion 18:08:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:08:36 INFO - Cleaning up... 18:08:36 INFO - Return code: 0 18:08:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:36 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')]} 18:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:36 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')]} 18:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:36 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:36 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 18:08:36 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 18:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:36 INFO - 'HOME': '/Users/cltbld', 18:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:36 INFO - 'LOGNAME': 'cltbld', 18:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:36 INFO - 'MOZ_NO_REMOTE': '1', 18:08:36 INFO - 'NO_EM_RESTART': '1', 18:08:36 INFO - 'PAGER': '/bin/cat', 18:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:36 INFO - 'PWD': '/builds/slave/test', 18:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:36 INFO - 'SHELL': '/bin/bash', 18:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:36 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:36 INFO - 'USER': 'cltbld', 18:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:36 INFO - 'XPC_FLAGS': '0x0', 18:08:36 INFO - 'XPC_SERVICE_NAME': '0', 18:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Ze42xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-vxyZWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-r3irmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-IOtx5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-quQgOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-yEISv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-mseuZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-DsG0Xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:37 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)) 18:08:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:37 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-xMn_AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-0Gw33S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-kPKSaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-LPShPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ooOk1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-tOWSLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-FykN4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-O2LM_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:38 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-9tLIrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:38 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)) 18:08:38 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)) 18:08:38 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)) 18:08:38 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)) 18:08:38 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)) 18:08:38 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)) 18:08:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:08:38 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 18:08:38 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 18:08:38 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 18:08:38 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 18:08:38 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 18:08:38 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 18:08:41 INFO - Downloading blessings-1.6.tar.gz 18:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:08:41 INFO - Installing collected packages: blessings 18:08:41 INFO - Running setup.py install for blessings 18:08:41 INFO - Successfully installed blessings 18:08:41 INFO - Cleaning up... 18:08:41 INFO - Return code: 0 18:08:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:08:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:41 INFO - Reading from file tmpfile_stdout 18:08:41 INFO - Current package versions: 18:08:41 INFO - blessings == 1.6 18:08:41 INFO - blobuploader == 1.2.4 18:08:41 INFO - docopt == 0.6.1 18:08:41 INFO - manifestparser == 1.1 18:08:41 INFO - mozInstall == 1.12 18:08:41 INFO - mozcrash == 0.17 18:08:41 INFO - mozdebug == 0.1 18:08:41 INFO - mozdevice == 0.48 18:08:41 INFO - mozfile == 1.2 18:08:41 INFO - mozhttpd == 0.7 18:08:41 INFO - mozinfo == 0.9 18:08:41 INFO - mozleak == 0.1 18:08:41 INFO - mozlog == 3.1 18:08:41 INFO - moznetwork == 0.27 18:08:41 INFO - mozprocess == 0.22 18:08:41 INFO - mozprofile == 0.28 18:08:41 INFO - mozrunner == 6.11 18:08:41 INFO - mozscreenshot == 0.1 18:08:41 INFO - mozsystemmonitor == 0.0 18:08:41 INFO - moztest == 0.7 18:08:41 INFO - mozversion == 1.4 18:08:41 INFO - psutil == 3.1.1 18:08:41 INFO - requests == 1.2.3 18:08:41 INFO - wsgiref == 0.1.2 18:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:41 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:41 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 18:08:41 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 18:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:41 INFO - 'HOME': '/Users/cltbld', 18:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:41 INFO - 'LOGNAME': 'cltbld', 18:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:41 INFO - 'MOZ_NO_REMOTE': '1', 18:08:41 INFO - 'NO_EM_RESTART': '1', 18:08:41 INFO - 'PAGER': '/bin/cat', 18:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:41 INFO - 'PWD': '/builds/slave/test', 18:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:41 INFO - 'SHELL': '/bin/bash', 18:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:41 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:41 INFO - 'USER': 'cltbld', 18:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:41 INFO - 'XPC_FLAGS': '0x0', 18:08:41 INFO - 'XPC_SERVICE_NAME': '0', 18:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 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)) 18:08:42 INFO - Cleaning up... 18:08:42 INFO - Return code: 0 18:08:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08: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', 'jsonschema'] in /builds/slave/test/build 18:08: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 jsonschema 18:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:42 INFO - 'HOME': '/Users/cltbld', 18:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:42 INFO - 'LOGNAME': 'cltbld', 18:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:42 INFO - 'MOZ_NO_REMOTE': '1', 18:08:42 INFO - 'NO_EM_RESTART': '1', 18:08:42 INFO - 'PAGER': '/bin/cat', 18:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:42 INFO - 'PWD': '/builds/slave/test', 18:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:42 INFO - 'SHELL': '/bin/bash', 18:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:42 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:42 INFO - 'USER': 'cltbld', 18:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:42 INFO - 'XPC_FLAGS': '0x0', 18:08:42 INFO - 'XPC_SERVICE_NAME': '0', 18:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:42 INFO - Downloading/unpacking jsonschema 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:08:44 INFO - Downloading/unpacking functools32 (from jsonschema) 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:45 INFO - Downloading functools32-3.2.3-2.tar.gz 18:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:08:45 INFO - warning: no files found matching '*.txt' 18:08:45 INFO - no previously-included directories found matching 'build' 18:08:45 INFO - no previously-included directories found matching 'dist' 18:08:45 INFO - no previously-included directories found matching '.git*' 18:08:45 INFO - Installing collected packages: jsonschema, functools32 18:08:45 INFO - Running setup.py install for functools32 18:08:45 INFO - warning: no files found matching '*.txt' 18:08:45 INFO - no previously-included directories found matching 'build' 18:08:45 INFO - no previously-included directories found matching 'dist' 18:08:45 INFO - no previously-included directories found matching '.git*' 18:08:45 INFO - Successfully installed jsonschema functools32 18:08:45 INFO - Cleaning up... 18:08:45 INFO - Return code: 0 18:08:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:45 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 0x1024dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024ef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d3d821c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, '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.LOxSyLXixL/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.QjKJewd0JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:08:45 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 18:08:45 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 18:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:45 INFO - 'HOME': '/Users/cltbld', 18:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:45 INFO - 'LOGNAME': 'cltbld', 18:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:45 INFO - 'MOZ_NO_REMOTE': '1', 18:08:45 INFO - 'NO_EM_RESTART': '1', 18:08:45 INFO - 'PAGER': '/bin/cat', 18:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:45 INFO - 'PWD': '/builds/slave/test', 18:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:45 INFO - 'SHELL': '/bin/bash', 18:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:08:45 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:45 INFO - 'USER': 'cltbld', 18:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:45 INFO - 'XPC_FLAGS': '0x0', 18:08:45 INFO - 'XPC_SERVICE_NAME': '0', 18:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:45 INFO - Downloading/unpacking flake8 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:08:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:08:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:08:49 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 18:08:49 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 18:08:49 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 18:08:49 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 18:08:49 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 18:08:49 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 18:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:08:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:08:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:08:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:08:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:08:49 INFO - no previously-included directories found matching 'docs/_build' 18:08:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:08:49 INFO - Running setup.py install for pep8 18:08:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:08:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:08:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:08:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:08:49 INFO - no previously-included directories found matching 'docs/_build' 18:08:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:08:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:08:49 INFO - Cleaning up... 18:08:50 INFO - Return code: 0 18:08:50 INFO - Running post-action listener: _resource_record_post_action 18:08:50 INFO - Running post-action listener: _start_resource_monitoring 18:08:50 INFO - Starting resource monitoring. 18:08:50 INFO - ##### 18:08:50 INFO - ##### Running install step. 18:08:50 INFO - ##### 18:08:50 INFO - Running pre-action listener: _resource_record_pre_action 18:08:50 INFO - Running main action method: install 18:08:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:50 INFO - Reading from file tmpfile_stdout 18:08:50 INFO - Detecting whether we're running mozinstall >=1.0... 18:08:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:08:50 INFO - Reading from file tmpfile_stdout 18:08:50 INFO - Output received: 18:08:50 INFO - Usage: mozinstall [options] installer 18:08:50 INFO - Options: 18:08:50 INFO - -h, --help show this help message and exit 18:08:50 INFO - -d DEST, --destination=DEST 18:08:50 INFO - Directory to install application into. [default: 18:08:50 INFO - "/builds/slave/test"] 18:08:50 INFO - --app=APP Application being installed. [default: firefox] 18:08:50 INFO - mkdir: /builds/slave/test/build/application 18:08:50 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'] 18:08:50 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 18:09:22 INFO - Reading from file tmpfile_stdout 18:09:22 INFO - Output received: 18:09:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:09:22 INFO - Running post-action listener: _resource_record_post_action 18:09:22 INFO - ##### 18:09:22 INFO - ##### Running run-tests step. 18:09:22 INFO - ##### 18:09:22 INFO - Running pre-action listener: _resource_record_pre_action 18:09:22 INFO - Running main action method: run_tests 18:09:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:09:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:09:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:09:22 INFO - 2016-05-23 18:09:22.046 screenresolution[1646:8238] starting screenresolution argv=screenresolution get 18:09:22 INFO - 2016-05-23 18:09:22.048 screenresolution[1646:8238] Display 0: 1600x1200x32@60 18:09:22 INFO - 2016-05-23 18:09:22.054 screenresolution[1647:8240] starting screenresolution argv=screenresolution list 18:09:22 INFO - Available Modes on Display 0 18:09:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:09:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:09:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:09:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:09:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:09:22 INFO - Intel Iris: 18:09:22 INFO - Chipset Model: Intel Iris 18:09:22 INFO - Type: GPU 18:09:22 INFO - Bus: Built-In 18:09:22 INFO - VRAM (Dynamic, Max): 1536 MB 18:09:22 INFO - Vendor: Intel (0x8086) 18:09:22 INFO - Device ID: 0x0a2e 18:09:22 INFO - Revision ID: 0x0009 18:09:22 INFO - Displays: 18:09:22 INFO - Display: 18:09:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:09:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:09:22 INFO - Display Serial Number: 1600x1200 60 18:09:22 INFO - Main Display: Yes 18:09:22 INFO - Mirror: Off 18:09:22 INFO - Online: Yes 18:09:22 INFO - Rotation: Supported 18:09:22 INFO - Return code: 0 18:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:09:22 INFO - Python 2.7.3 18:09:22 INFO - Return code: 0 18:09:22 INFO - grabbing minidump binary from tooltool 18:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:22 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 0x10237ee00>, '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 0x1021e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102379b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:09:22 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 18:09:22 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 18:09:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:09:22 INFO - Return code: 0 18:09:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:09:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:09:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:09:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:09:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:09:23 INFO - Return code: 0 18:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0317 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LOxSyLXixL/Render', 18:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:23 INFO - 'HOME': '/Users/cltbld', 18:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:23 INFO - 'LOGNAME': 'cltbld', 18:09:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:09:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:23 INFO - 'MOZ_NO_REMOTE': '1', 18:09:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:09:23 INFO - 'NO_EM_RESTART': '1', 18:09:23 INFO - 'PAGER': '/bin/cat', 18:09:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:23 INFO - 'PWD': '/builds/slave/test', 18:09:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:23 INFO - 'SHELL': '/bin/bash', 18:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QjKJewd0JV/Listeners', 18:09:23 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:23 INFO - 'USER': 'cltbld', 18:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:23 INFO - 'XPC_FLAGS': '0x0', 18:09:23 INFO - 'XPC_SERVICE_NAME': '0', 18:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:09:23 INFO - mozversion application_buildid: 20160523170350 18:09:23 INFO - mozversion application_changeset: a2baee5a734efd4bb9646e5ad06d7552d6618bcb 18:09:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:09:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:09:23 INFO - mozversion application_name: Firefox 18:09:23 INFO - mozversion application_remotingname: firefox-dev 18:09:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:09:23 INFO - mozversion application_vendor: Mozilla 18:09:23 INFO - mozversion application_version: 48.0a2 18:09:23 INFO - mozversion platform_buildid: 20160523170350 18:09:23 INFO - mozversion platform_changeset: a2baee5a734efd4bb9646e5ad06d7552d6618bcb 18:09:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:09:23 INFO - mozversion platform_version: 48.0a2 18:09:23 INFO - using testdate: 1464052163 18:09:23 INFO - actual date: 1464052163 18:09:23 INFO - starting webserver on 'localhost:49275' 18:09:23 INFO - SUITE-START | Running 2 tests 18:09:23 INFO - TEST-START | dromaeo_css 18:09:23 INFO - operating with platform_type : mac_ 18:09:23 INFO - Initialising browser for dromaeo_css test... 18:09:23 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp7NViwG/profile http://localhost:49275/getInfo.html) 18:09:25 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 18:09:25 INFO - PROCESS | 1665 | colorDepth:24 18:09:25 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 18:09:25 INFO - PROCESS | 1665 | __metrics 18:09:26 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:09:26 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:09:26 INFO - TEST-INFO | 1665: exit 0 18:09:26 INFO - Browser initialized. 18:09:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:09:26 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp7NViwG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:38 INFO - PROCESS | 1668 | RSS: Main: 210882560 18:09:38 INFO - PROCESS | 1668 | 18:10:29 INFO - PROCESS | 1668 | 1464052229819 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] 18:12:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 18:12:41 INFO - PROCESS | 1668 | RSS: Main: 310267904 18:12:41 INFO - PROCESS | 1668 | 18:15:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 18:15:45 INFO - PROCESS | 1668 | RSS: Main: 354910208 18:15:45 INFO - PROCESS | 1668 | 18:18:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 18:18:47 INFO - PROCESS | 1668 | RSS: Main: 360632320 18:18:47 INFO - PROCESS | 1668 | 18:21:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 18:21:50 INFO - PROCESS | 1668 | RSS: Main: 356397056 18:21:50 INFO - PROCESS | 1668 | 18:24:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 18:24:54 INFO - PROCESS | 1668 | RSS: Main: 324456448 18:24:54 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 18:27:57 INFO - PROCESS | 1668 | RSS: Main: 370876416 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | __start_tp_report 18:27:57 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 18:27:57 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 18:27:57 INFO - PROCESS | 1668 | |i|pagename|runs| 18:27:57 INFO - PROCESS | 1668 | |0;dojo.html;3319.5020746887967;3390.88131915383;3402.846871890765;3420.213350929285;3451.344244593527;5552.944470555293;5553.555715542757;5558.499735023852;5561.555075593953;5579.218909352684;5516.531094856932;5551.250581171541;5578.721063946797;5580.581456390777;5641;4948.331975183358;5033.144365457872;5048.747562621873;5113.233015047754;5154.871128221787;5025.14572522672;5100.438951715309;5105.872353191163;5113.054084994288;5117.820876269331;5046.697198168104;5084.567811736007;5100.183970564711;5106.617003724717;5124.436312005676;5121.718305493197;5141.742912854343;5144.176931690911;5153.871153221199;5164.974175129119;4926.433460152057;4970.7017578945015;5038.118329292359;5073.1122053640465;5106.846794596168;4332.328489084197;4518.638508919278;5426.457354264581;5451.890962180734;5468.398476167618;6009.879802403972;6057.182280392135;6061.363556826514;6064.818055458343;6065.969670151665;5941.613795103304;6065.104231525981;6122.693865306717;6168.352323006065;6169.7532098716;5660.264165658438;5665.461781130868;5679.119736440775;5693.857653558653;5710.857228569277;6185.567010309235;6227.782027629102;6239.78160764371;6262.937370626327;6265.060240963892;5793.2179155814265;5899.528037756969;5991.310999235144;5996.850078748066;6013.488853447506;6027.427394397525;6048.637081775108;6073.392660733891;6087.786927457518;6098.512119030467;5988.131720900532;5995.430434108753;6000.979833428328;6001.849953751192;6018.946684330224;5978.073398623221;6004.249468816398;6058.394160583906;6071.392860713893;6147.44672979434;4150.730202536825;4854.17479028566;4868.002059577778;4875.317455556225;4921.926171107436;4287.3783301421745;4384.738959818132;4412.602865742098;4422.800973956179;4453.064856380196;5348;5428.457154284619;5501.477359650827;5548;5551.111822108443;6113.480354169857;6119.969400152971;6121.326654068049;6145.754169833246;6154.3845615438995;4488.573585509372;5495.587830912697;5498.065328894098;5526.281583394213;5527.39198688152;4491.910161796811;5203.869903252374;5209.869753256123;5266.394364648115;5295.602829787781;6194.659293738798;6222.875542489125;6229.470495007975;6230;6247.718852651732;5946.672932988728;6119.082137679384;6119.082137679384;6131.63210207389;6136.907946380718;6140.903050468011;6190.288116866618;6217.502599792097;6223;6224.190855188796;6000.399960003964;6003.309619393825;6045.455908968038;6048.758049677964;6063.757449701962;4833.275008748576;5749.597528173155;5860.824175274749;6115.571909966259;6123.510119190365;5795.7391917362975;5887.941120588739;5963.493103086113;5974.103884417372;5990.4908082813445;5863.501602363847;5870.119482077723;5880.147378629989;5897.498712609477;5901.527877769854;5607.80372686954;5761.769529218952;5770.221020162225;5776.162456443708;5779.2775903012125;5804.506616937608;5839.182514447895;5936.495397891227;5968.820935371946;5981.820545383645;5767.163761254511;5780.682062486603;5800.9419905800405;5812.796552120655;5826.592138550261 18:27:57 INFO - PROCESS | 1668 | |1;ext.html;508.20465970755765;521.8851852592429;537.8973105134473;549.0309603549736;596.9910451343234;25576.74423255767;38090.42864357033;38990;39078.804605977;39132.21735565287;35769.82115089421;44054.33918491217;44405.77797111018;44633.107337853224;44809.10381792362;9616.759581010496;9799;9813.362131461432;9814;9843.950780246088;9584.281178911575;9712.41725496469;9724.124828765409;9742.07450292221;9744.415335079882;9678.17735492484;9755.853662195073;9760.170385517204;9761.560729767178;9768.267379946532;9668.854967175497;9720.708378748614;9723.805523889554;9725.610975560969;9742.561584728704;9517.666881659108;9630.422174669542;9634.22926165912;9641.228701703847;9651.034896510362;33949.981500555026;40675.37298508086;40687;41221;41290;3170.1123685368134;3237.53055806909;3377.527146199534;3399.915002124942;3710.07248187953;27481.725182748116;28288.43423131526;28524.85737571299;28817.56773648397;29317.706822931712;2684.4362683836416;2690.515707172708;2701.8243814152015;2725.046233818168;2851.600775891377;363475;426933;431632;432919;433581.99999999686;36097.09757256037;63985.680071599345;64384.678076609314;64409;64437;35614;35882;35904.82047589745;35974.46038309427;36502.90491285266;15301.923490382476;15617.375304987674;15627.453039143575;15628.68742625164;15630.9218453907;13142.802857957138;13241.801372979413;13242.337883105805;13258.73482530344;13266.07137500385;8082.555459449669;8214.26071653554;8236.094029656733;8251.628676709563;8301.335893128535;3391.6777906098882;3436.5876094868777;3441.6214216436174;3444.4488881778793;3464.4456886897974;936.8641546975787;978.4569563891966;1015.1168483419325;1031.4481752262504;1039.4646756920192;27984.44031119407;28613;28720.712792872222;28726.569101463494;28758.836527981905;1083.7128161037174;1095.474172397238;1127.4869934179928;1127.6617014895498;1153.2734377342388;12592.370381480889;14353.282335883165;15533.301001454962;15906.840931590536;16264;595417;600567;601623;603507;603729;61070.38929610736;61475.38524614786;61730.382696173365;61744;61904.69047654823;1359.2727890578492;1493.3578561218562;1516.5677781832126;1527.3737767515265;1529.9617509562127;24322.783860806536;24368;24734;24744.381390464663;24775;47339.52660473351;47636.2854557175;47791.76104119457;48047.75976120097;48068.759656201495;396968.0151599339;397830;398527;398627;398682;23046.884765576066;25985;26214;26493.072742453922;26814.463710726068;41817.37273940954;42410.36384454174;43514.782426087666;43581.78209108934;43985.12029759451;35437.46843797294;35943.820280899476;35956.640433596374;36075.63924360723;36640.08399790026;29604.703952961056;35648.64351356557;35687.46468803022;36197.63802362048;36352.27295454129;35414.114647134025;35421.64578354183;35742.285154296245;36636;36722.63277367192;31191.688083119785;31584.526232106076;31645;31745.365092697553;31757.682423175473;24091.63862542028;24161.63757543603;24328.75671243336;24521.632175517025;24754.628680569447 18:27:57 INFO - PROCESS | 1668 | |2;jquery.html;398.8225239768303;399.2992298516104;402.36828179757777;402.42855145693113;415.7235438433442;14778.334974926127;14823.629409264748;20104.69842952355;20806.6878996815;20809.99999999998;16360.509184724448;17596.82403175968;20712.585748285026;20914.686279705817;21007;5817.447342502466;6142.5085993120665;6149.108379285001;6156.353582873801;6168.84577885552;4254.489461264652;5980.162777211194;6136.877262454748;6139.846503837418;6161.815145545641;6395.360463953591;6703.1956165260235;6704.396604305612;6724.327567243286;6725.966370168142;6410.230772307306;6639.900401493981;6654.23476300222;6656.63388513634;6676.799696009079;5852.853678658068;5885.117232415129;5886.411358864079;5886.470217680387;5895.4399332063385;26792.598111028154;27190.184294471197;27441.17676469709;27548.862255688593;27557;2011.688188330811;2183.825293976478;2253.6844841722173;2394.0064873077654;2885.898993535216;9336.439813611138;9946.403215807002;10053.547590358377;10095.596176152945;10365.067143957005;1664.459050808492;1748.9212985415686;1788.499220218341;1821.8633602479736;1864.3474783825689;89478;108123.45938270258;113187.43406282998;115947.42026289814;120608.3969580164;9526;11550.88449115515;11587.826182607268;11835.763284734257;11976.401179940967;15285.923570382078;15373.923130384277;15430.768538471932;15432.305546250445;15455.38178472871;7310.342069213796;7984.800379990546;8342;8437.620307086196;8803.339749518811;8905.109489051043;8932.732018039469;8946.507942063248;9017.413868098552;9030.187283144418;4272.124214536013;4684.695494792896;4722.409698787652;4737.928931066037;4757.643176761688;1709.5811526176167;1812.3053764075455;1899.6675581773134;1971.5465442947916;1973.792751761073;876.2114097312469;1004.683524689735;1021.7394564386034;1022.1924679503257;1022.7596514819012;19908.60182796336;20329.186832526535;20750.32238071923;20791.58416831655;21269.574608507744;963.9662611808693;990.5938565188238;1029.3000759483473;1043.5147656339686;1065.7016035510069;11034.227604067799;11416.25794323383;11445.313281203154;11447.54209831614;11455.885441145649;593292;597490;610532;612535;624321;128422;131372.68627313795;132413;132734.67265327417;134098.32950835378;1008.6923488336128;1016.0601443664581;1020.9642662506776;1025.8615086963314;1029.156092004564;18440.631187376446;18703.34538291153;18723.970181639597;18767.530811729815;19039.809601903802;47524.2871356923;49402.75298623484;52361.69095772484;54863;55357.44642553523;59255.814883702944;76912;81591;82026;82183.58908205421;7825.647845846836;8090.555019473985;8281.917180828355;8320.058755514514;8618.095100014409;11424.428778560872;12796.616101516596;12832.55086071983;13119.606411807661;13231.933840330737;9584.66453674118;10816.134709223086;11059.281146725438;11070.619127969012;11278.984891359816;10054.849177262493;10828.620998265023;10882.836757448486;11081.833772493257;11313.490892910004;5764.394738552392;8125.431219814556;9909.900900991186;11561.42192890369;11614.303141811519;8250.752477425687;8385.37109716786;8515.446495977742;8520.19058189471;8549.487030778173;10499.737506562396;11167;11473;11538;11584.478698458759 18:27:57 INFO - PROCESS | 1668 | |3;mootools.html;1327.6282640860563;1549.5273941447858;1565.7729629203766;1775.1656721340973;1904.1145867171763;7777.183395743443;8526.910906827829;8651.394402391836;8694.087120852315;8700.303975681945;8396.748097557067;8644.400785854621;8655.826883462343;8669.003064647568;8692.695755648554;2139.6683514055267;2358.96461553076;2416.806655467567;2425.721042080157;2444.340028192387;2458.311672731638;2462.224399314219;2462.445949661321;2466.075221791828;2476.5170791695627;2377.2773970053213;2411.5056413435204;2441.963370549443;2444.1567659157563;2447.3759191406166;1979.7228388025687;2130.62714025045;2426.708794944609;2428.7206971198184;2431.9270421887372;2392.1986134644917;2420.673209116764;2425.163318655061;2431.4529230923076;2432.1730611592143;8550.1449855014;8634.956825215897;8638.481691098492;8649.65401383944;8661.740147795575;4518.390017347649;5111.309973153614;5122.820701275475;5158.793648254065;5164.586833053347;3463.9134021649406;3506.63180366063;3579.3892748263384;3585.032041348821;3590.263995880838;1022.85168650546;1031.587365053977;1062.8405739139114;1073.3238060022213;1079.5196137718717;11980.520779168823;11989.700257493545;11990.760184796343;12009.57966471178;12013.219140755911;4622.653301002439;4691.061787642485;4704;4715.929261061086;4782.378290822171;9958.103770660675;10108.342957707726;10114.393136411838;10116.140128089048;10120;5328.8401347959625;5342.091844386464;5376.435474275223;5385.919211211835;5388.326459192601;4289.463817022872;4393.20922234001;4397.582229688174;4452.30989196675;4454.77726113693;3961.603839616015;4047.8785636430953;4155.252054630196;4169.395437661522;4173.749575025508;1334.9933250333686;1343.6506508307714;1343.7715588350004;1354.3634491788844;1361.0948719101686;909.6673373507842;918.6876462002951;937.8733870927338;942.3168203052732;945.6122989574242;5754.079347304411;5760.8271751847515;5816.709164541756;5862.970685146547;5898.400425360627;897.9730608081768;939.1453777062976;957.9377340472847;963.325672029582;992.161623428202;3660.1215708230366;3856.749311294757;3995.180987897474;4003.259397011562;4021.477207962946;9328.673496427593;10022.649207277711;10686.946565267124;10824;10849.752278488075;10246.897531024595;10550.683479495627;10601.681949541526;10726.249162558703;10762.408067556204;6890.827729306707;7272.563646181246;8375.790605234795;8429.915700843036;8445.282151017109;5404.189371594292;5545.694986775766;5606.795268080497;5698.743556540048;5735.713214339183;8146.877796832934;9193.494357810385;9241.122093401116;9463.574139163617;9686.32195746291;9633.421994680342;10063.49682515886;10073.647422340477;10075.949620252146;10141.949290253797;6704.66476676174;7232.312930271616;7497.250274972677;7499.962500187464;7503.0996280444515;7565.4325925556905;7928.960355198187;8144.022717273965;8164.428490005643;8189.959050204711;7238.457115716237;7329.120505539369;7374.373178279906;7475.850482990419;7507.774766757006;7154.821129471805;7156.9642151790995;7229.313215244543;7261.237570055068;7288.052553168266;6782.830429239111;6876.828079298058;6906.998485219515;6957.79126626202;6959.89560156588;4400.44994375703;4672.813087476463;4822.373091498082;4884.3650325457465;4961.355023846877;4882.340883980617;4904.092742842586;4970.527799859007;4991.101601711727;4993.650444468853 18:27:57 INFO - PROCESS | 1668 | |4;prototype.html;317.0614979660204;320.8812739286464;333.17539090750387;334.7690093835255;336.45998172932445;6433.2280126384785;6503.512236582252;6558.8688226235445;6576.145101136852;6619.569727967691;5549.500544950954;6230.283517395501;6373.904391434121;6396.936030639704;6497.610143391388;3893.5717071122153;4563.863084107474;4747.335373047776;4788.569028787409;4789.401324834395;4084.9591504084874;4631.60631346336;4695.788689508963;4814.9037019259595;4828.46886842447;4708.529147085298;4824.1316563018645;4826.61387089032;4849.903001939959;4870.561649451566;4842.394700662399;4848.466668666461;4871.2206047032305;4871.4154301483695;4879.219324908033;4132.40080188374;4169.478815148106;4169.833206671729;4170.436991006236;4177.456930599003;6268;6277.340879207663;6296.590721603081;6301.243850737941;6313.305536390993;5736.053551164053;5753.683547404891;5754.769809207627;5762.567807414419;5776.220210271644;6661.233958094833;6880.277570855088;6914.343137401939;6916.1700595928305;6916.858718311472;4324.718893271959;4527.569880861313;4569.840055598038;4571.862844114682;4586.220342541743;7284.235155308722;7348.926510734877;7355.963220183919;7378.225286344964;7385.150707668633;3944.4280579315837;4590.219662657356;4611.654125940568;4612.285095810154;4613.469451013109;4663.766811659403;4671;4677.859664210079;4681.133990211822;4684.718916864999;5380.488853558905;5583.190437386637;5602.355729091207;5609.971950140305;5620.662760234317;6992.895106573304;7048.224695283515;7073.752418665322;7078.221395646475;7085.043519124955;1539;1575.0156152404747;1576.7950166478513;1600.7438809790378;1625.4960962101786;975.073680003999;983.3755565216139;985.6550207427343;1026.4970164619433;1048.0672201740458;6480.2547707013355;6500.674966251669;6501.674916254168;6502.707378167994;6515.446187074057;7197.820054498679;7203.279672032755;7206.576875983866;7212.819679508054;7221.386182174574;6490.643014634241;6498.870022599616;6499.512536559777;6501.544891857525;6503.902441463382;3699.7040236780995;3764.265968136242;3822.120912190212;3847.115163512408;3872.5352957645264;7570.924290757132;7606.847863042708;7621.428392870557;7624.237576242443;7626.885596716053;7493.887591686129;7501;7518.812029699301;7523.0972283326355;7523.623818809147;6183.319834818257;7599.16409194977;7599.544027358376;7600.201978792147;7616.0860696716745;6970.128733908262;7201.279872012967;7214.2064372917885;7258.745943891939;7277.053982982178;7135.537214871043;7205.675744591401;7210.315020073084;7212.783616491513;7214.603196824015;7610.847783044197;7614.8096297593;7616.7334143304715;7618.162002179645;7626.894100355528;4586.724796512087;4642.675012749075;4646.976765116153;4649.558291962258;4669.20623494;6978.197507286727;7250.456215783732;7269.491135620456;7270.600116993616;7282.635868206675;6976.965115174392;7029.578225306498;7032.367086962198;7046.541974771623;7066.4346852250665;7020.684069217;7024.121984751906;7027.262137475696;7029.261927497743;7038.824029399306;6853.588784672936;6859.348361905611;6865.244823069558;6868.828279292858;6872.347127022925;6693.196816382066;6746.325367463214;6935.994280829353;7061.6469176542;7092.468064895051;6728.360805723644;6830.795076147723;6847.623380714109;6897.172339319314;6906.343897329745 18:27:57 INFO - PROCESS | 1668 | |5;yui.html;692.8406466512702;700.7022015643352;747.7980945144808;754.0950858969237;763.7174245529155;14683;14852.48016319429;14883.925580372083;14898.255087245636;14920.925395373035;11080.612178573752;13111.213327200374;13116.54092106774;13173.209607423561;13191.406386712597;711.8932160175976;889.6130183370223;913.5934509143434;918.9402688825238;920.0661328751289;907.4827348411408;916.2852974679727;918.150710592699;920.0983036624112;922.5433410461798;968.7287559483356;974.4202199691208;975.068809287129;975.5951280218701;976.2287792643803;758.2834221660534;768.8039549914764;771.6797529025448;773.9071311442607;945.4988855906381;712.4834494991142;726.6475759256756;733.120255693165;733.8458923626044;734.6767422334156;14952.028118172393;15016.549503514912;15025.173615451145;15040.17279049652;15070.924645376812;12553.874461255362;12839.358032098451;12843.614691559269;12857.292848893307;13003.414846331938;10059.5473203706;10162.18702503798;10183.28716989811;10426.478676066243;10430.426326552113;3432.056184549244;3494.4932984717075;3505.964940350589;3506.947395789065;3557.0395993081984;80911;81399.779003315;85748;85897;86028.56985715032;9454.527273636291;9526.952365238269;9893.406395616286;9933.50332483373;10320.916303788144;22463;22470;22492.325230243117;22516.88741556282;22528.887355563118;7161.283871612901;7291.34377905991;7448.9627551861895;7554.508956917783;7638.274363935418;3921.9607803921594;4039.3940908863906;4068.064345200561;4145.2952997990415;4306.612404883575;3552.609212986569;3622.094476380905;3625.492431059654;3626.0390996385977;3627.2564124354444;616.6146158650844;641.3650486018807;642.8135840606266;643.0964494884695;652.2987788127782;560.366785532354;568.5054003017401;607.9817605471843;610.4688920638984;613.4877377389929;8134.877976830352;8212.507249564926;8259.297959673495;8382.87425688615;8412.78968025792;518.9247559103909;570.181789132596;578.988420231593;583.5798225277794;620.1751670278519;3742.700583953277;3777.225668737902;3831.7700937943814;3910.413437984268;3925.568187499373;92092.61861072089;94111;94192;94367;94516.52741736248;76383.23616763872;76578;79190.20809791943;79438.60280698675;80185.19814801894;53976.730116349165;67414.3258567408;67843;67963;68937;7668.57822819728;7837.882431763642;7857.371410287049;7884.172161923145;7930.881036784337;38203.044923877125;38825.805870970464;47772.805679858284;47991.76004119957;48590.27114593213;54584.72707636436;67291.66354168394;68169;68966.9654955166;70167.64916175387;9981.900180998096;10099.899001009895;10235.181185505291;10325;10883.455827208767;10660.46697665129;10686.465676716289;10859.51132199037;11167.609133680282;11774.764504709687;10061.69814905554;10473.581056757645;10567;10632.30889992148;10680.412577308323;10451.581936722447;10476.63331783384;10491.737706557396;10514;10690.251682382159;10130.189584833342;10238.53926573291;10362.792744144925;10365.585376584851;10435.478226088817;8667.566621669017;8954.641814327353;8979.865302020606;9081.137291957253;9344.392614480037;8919.286457083548;8940.240079593308;8977.281817454457;8988.595513202052;9127.543622818965 18:27:57 INFO - PROCESS | 1668 | __end_tp_report 18:27:57 INFO - PROCESS | 1668 | __start_cc_report 18:27:57 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1333 18:27:57 INFO - PROCESS | 1668 | __end_cc_report 18:27:57 INFO - PROCESS | 1668 | __startTimestamp1464053277370__endTimestamp 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | ------- Summary: start ------- 18:27:57 INFO - PROCESS | 1668 | Number of tests: 6 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5568.83 Median:5779.28 stddev:637.12 (11.0%) stddev-sans-first:614.15 18:27:57 INFO - PROCESS | 1668 | Values: 3319.5 3390.9 3402.8 3420.2 3451.3 5552.9 5553.6 5558.5 5561.6 5579.2 5516.5 5551.3 5578.7 5580.6 5641.0 4948.3 5033.1 5048.7 5113.2 5154.9 5025.1 5100.4 5105.9 5113.1 5117.8 5046.7 5084.6 5100.2 5106.6 5124.4 5121.7 5141.7 5144.2 5153.9 5165.0 4926.4 4970.7 5038.1 5073.1 5106.8 4332.3 4518.6 5426.5 5451.9 5468.4 6009.9 6057.2 6061.4 6064.8 6066.0 5941.6 6065.1 6122.7 6168.4 6169.8 5660.3 5665.5 5679.1 5693.9 5710.9 6185.6 6227.8 6239.8 6262.9 6265.1 5793.2 5899.5 5991.3 5996.9 6013.5 6027.4 6048.6 6073.4 6087.8 6098.5 5988.1 5995.4 6001.0 6001.8 6018.9 5978.1 6004.2 6058.4 6071.4 6147.4 4150.7 4854.2 4868.0 4875.3 4921.9 4287.4 4384.7 4412.6 4422.8 4453.1 5348.0 5428.5 5501.5 5548.0 5551.1 6113.5 6120.0 6121.3 6145.8 6154.4 4488.6 5495.6 5498.1 5526.3 5527.4 4491.9 5203.9 5209.9 5266.4 5295.6 6194.7 6222.9 6229.5 6230.0 6247.7 5946.7 6119.1 6119.1 6131.6 6136.9 6140.9 6190.3 6217.5 6223.0 6224.2 6000.4 6003.3 6045.5 6048.8 6063.8 4833.3 5749.6 5860.8 6115.6 6123.5 5795.7 5887.9 5963.5 5974.1 5990.5 5863.5 5870.1 5880.1 5897.5 5901.5 5607.8 5761.8 5770.2 5776.2 5779.3 5804.5 5839.2 5936.5 5968.8 5981.8 5767.2 5780.7 5800.9 5812.8 5826.6 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:60181.18 Median:199836.32 stddev:128546.10 (64.3%) stddev-sans-first:128828.63 18:27:57 INFO - PROCESS | 1668 | Values: 508.2 521.9 537.9 549.0 597.0 25576.7 38090.4 38990.0 39078.8 39132.2 35769.8 44054.3 44405.8 44633.1 44809.1 9616.8 9799.0 9813.4 9814.0 9844.0 9584.3 9712.4 9724.1 9742.1 9744.4 9678.2 9755.9 9760.2 9761.6 9768.3 9668.9 9720.7 9723.8 9725.6 9742.6 9517.7 9630.4 9634.2 9641.2 9651.0 33950.0 40675.4 40687.0 41221.0 41290.0 3170.1 3237.5 3377.5 3399.9 3710.1 27481.7 28288.4 28524.9 28817.6 29317.7 2684.4 2690.5 2701.8 2725.0 2851.6 363475.0 426933.0 431632.0 432919.0 433582.0 36097.1 63985.7 64384.7 64409.0 64437.0 35614.0 35882.0 35904.8 35974.5 36502.9 15301.9 15617.4 15627.5 15628.7 15630.9 13142.8 13241.8 13242.3 13258.7 13266.1 8082.6 8214.3 8236.1 8251.6 8301.3 3391.7 3436.6 3441.6 3444.4 3464.4 936.9 978.5 1015.1 1031.4 1039.5 27984.4 28613.0 28720.7 28726.6 28758.8 1083.7 1095.5 1127.5 1127.7 1153.3 12592.4 14353.3 15533.3 15906.8 16264.0 595417.0 600567.0 601623.0 603507.0 603729.0 61070.4 61475.4 61730.4 61744.0 61904.7 1359.3 1493.4 1516.6 1527.4 1530.0 24322.8 24368.0 24734.0 24744.4 24775.0 47339.5 47636.3 47791.8 48047.8 48068.8 396968.0 397830.0 398527.0 398627.0 398682.0 23046.9 25985.0 26214.0 26493.1 26814.5 41817.4 42410.4 43514.8 43581.8 43985.1 35437.5 35943.8 35956.6 36075.6 36640.1 29604.7 35648.6 35687.5 36197.6 36352.3 35414.1 35421.6 35742.3 36636.0 36722.6 31191.7 31584.5 31645.0 31745.4 31757.7 24091.6 24161.6 24328.8 24521.6 24754.6 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35089.22 Median:2218.75 stddev:101259.75 (4563.8%) stddev-sans-first:101510.30 18:27:57 INFO - PROCESS | 1668 | Values: 398.8 399.3 402.4 402.4 415.7 14778.3 14823.6 20104.7 20806.7 20810.0 16360.5 17596.8 20712.6 20914.7 21007.0 5817.4 6142.5 6149.1 6156.4 6168.8 4254.5 5980.2 6136.9 6139.8 6161.8 6395.4 6703.2 6704.4 6724.3 6726.0 6410.2 6639.9 6654.2 6656.6 6676.8 5852.9 5885.1 5886.4 5886.5 5895.4 26792.6 27190.2 27441.2 27548.9 27557.0 2011.7 2183.8 2253.7 2394.0 2885.9 9336.4 9946.4 10053.5 10095.6 10365.1 1664.5 1748.9 1788.5 1821.9 1864.3 89478.0 108123.5 113187.4 115947.4 120608.4 9526.0 11550.9 11587.8 11835.8 11976.4 15285.9 15373.9 15430.8 15432.3 15455.4 7310.3 7984.8 8342.0 8437.6 8803.3 8905.1 8932.7 8946.5 9017.4 9030.2 4272.1 4684.7 4722.4 4737.9 4757.6 1709.6 1812.3 1899.7 1971.5 1973.8 876.2 1004.7 1021.7 1022.2 1022.8 19908.6 20329.2 20750.3 20791.6 21269.6 964.0 990.6 1029.3 1043.5 1065.7 11034.2 11416.3 11445.3 11447.5 11455.9 593292.0 597490.0 610532.0 612535.0 624321.0 128422.0 131372.7 132413.0 132734.7 134098.3 1008.7 1016.1 1021.0 1025.9 1029.2 18440.6 18703.3 18724.0 18767.5 19039.8 47524.3 49402.8 52361.7 54863.0 55357.4 59255.8 76912.0 81591.0 82026.0 82183.6 7825.6 8090.6 8281.9 8320.1 8618.1 11424.4 12796.6 12832.6 13119.6 13231.9 9584.7 10816.1 11059.3 11070.6 11279.0 10054.8 10828.6 10882.8 11081.8 11313.5 5764.4 8125.4 9909.9 11561.4 11614.3 8250.8 8385.4 8515.4 8520.2 8549.5 10499.7 11167.0 11473.0 11538.0 11584.5 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5625.64 Median:4852.36 stddev:3137.27 (64.7%) stddev-sans-first:3129.44 18:27:57 INFO - PROCESS | 1668 | Values: 1327.6 1549.5 1565.8 1775.2 1904.1 7777.2 8526.9 8651.4 8694.1 8700.3 8396.7 8644.4 8655.8 8669.0 8692.7 2139.7 2359.0 2416.8 2425.7 2444.3 2458.3 2462.2 2462.4 2466.1 2476.5 2377.3 2411.5 2442.0 2444.2 2447.4 1979.7 2130.6 2426.7 2428.7 2431.9 2392.2 2420.7 2425.2 2431.5 2432.2 8550.1 8635.0 8638.5 8649.7 8661.7 4518.4 5111.3 5122.8 5158.8 5164.6 3463.9 3506.6 3579.4 3585.0 3590.3 1022.9 1031.6 1062.8 1073.3 1079.5 11980.5 11989.7 11990.8 12009.6 12013.2 4622.7 4691.1 4704.0 4715.9 4782.4 9958.1 10108.3 10114.4 10116.1 10120.0 5328.8 5342.1 5376.4 5385.9 5388.3 4289.5 4393.2 4397.6 4452.3 4454.8 3961.6 4047.9 4155.3 4169.4 4173.7 1335.0 1343.7 1343.8 1354.4 1361.1 909.7 918.7 937.9 942.3 945.6 5754.1 5760.8 5816.7 5863.0 5898.4 898.0 939.1 957.9 963.3 992.2 3660.1 3856.7 3995.2 4003.3 4021.5 9328.7 10022.6 10686.9 10824.0 10849.8 10246.9 10550.7 10601.7 10726.2 10762.4 6890.8 7272.6 8375.8 8429.9 8445.3 5404.2 5545.7 5606.8 5698.7 5735.7 8146.9 9193.5 9241.1 9463.6 9686.3 9633.4 10063.5 10073.6 10075.9 10141.9 6704.7 7232.3 7497.3 7500.0 7503.1 7565.4 7929.0 8144.0 8164.4 8190.0 7238.5 7329.1 7374.4 7475.9 7507.8 7154.8 7157.0 7229.3 7261.2 7288.1 6782.8 6876.8 6907.0 6957.8 6959.9 4400.4 4672.8 4822.4 4884.4 4961.4 4882.3 4904.1 4970.5 4991.1 4993.7 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5727.18 Median:6502.19 stddev:1846.35 (28.4%) stddev-sans-first:1806.33 18:27:57 INFO - PROCESS | 1668 | Values: 317.1 320.9 333.2 334.8 336.5 6433.2 6503.5 6558.9 6576.1 6619.6 5549.5 6230.3 6373.9 6396.9 6497.6 3893.6 4563.9 4747.3 4788.6 4789.4 4085.0 4631.6 4695.8 4814.9 4828.5 4708.5 4824.1 4826.6 4849.9 4870.6 4842.4 4848.5 4871.2 4871.4 4879.2 4132.4 4169.5 4169.8 4170.4 4177.5 6268.0 6277.3 6296.6 6301.2 6313.3 5736.1 5753.7 5754.8 5762.6 5776.2 6661.2 6880.3 6914.3 6916.2 6916.9 4324.7 4527.6 4569.8 4571.9 4586.2 7284.2 7348.9 7356.0 7378.2 7385.2 3944.4 4590.2 4611.7 4612.3 4613.5 4663.8 4671.0 4677.9 4681.1 4684.7 5380.5 5583.2 5602.4 5610.0 5620.7 6992.9 7048.2 7073.8 7078.2 7085.0 1539.0 1575.0 1576.8 1600.7 1625.5 975.1 983.4 985.7 1026.5 1048.1 6480.3 6500.7 6501.7 6502.7 6515.4 7197.8 7203.3 7206.6 7212.8 7221.4 6490.6 6498.9 6499.5 6501.5 6503.9 3699.7 3764.3 3822.1 3847.1 3872.5 7570.9 7606.8 7621.4 7624.2 7626.9 7493.9 7501.0 7518.8 7523.1 7523.6 6183.3 7599.2 7599.5 7600.2 7616.1 6970.1 7201.3 7214.2 7258.7 7277.1 7135.5 7205.7 7210.3 7212.8 7214.6 7610.8 7614.8 7616.7 7618.2 7626.9 4586.7 4642.7 4647.0 4649.6 4669.2 6978.2 7250.5 7269.5 7270.6 7282.6 6977.0 7029.6 7032.4 7046.5 7066.4 7020.7 7024.1 7027.3 7029.3 7038.8 6853.6 6859.3 6865.2 6868.8 6872.3 6693.2 6746.3 6936.0 7061.6 7092.5 6728.4 6830.8 6847.6 6897.2 6906.3 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17933.28 Median:615.05 stddev:25629.66 (4167.1%) stddev-sans-first:25668.86 18:27:57 INFO - PROCESS | 1668 | Values: 692.8 700.7 747.8 754.1 763.7 14683.0 14852.5 14883.9 14898.3 14920.9 11080.6 13111.2 13116.5 13173.2 13191.4 711.9 889.6 913.6 918.9 920.1 907.5 916.3 918.2 920.1 922.5 968.7 974.4 975.1 975.6 976.2 758.3 768.8 771.7 773.9 945.5 712.5 726.6 733.1 733.8 734.7 14952.0 15016.5 15025.2 15040.2 15070.9 12553.9 12839.4 12843.6 12857.3 13003.4 10059.5 10162.2 10183.3 10426.5 10430.4 3432.1 3494.5 3506.0 3506.9 3557.0 80911.0 81399.8 85748.0 85897.0 86028.6 9454.5 9527.0 9893.4 9933.5 10320.9 22463.0 22470.0 22492.3 22516.9 22528.9 7161.3 7291.3 7449.0 7554.5 7638.3 3922.0 4039.4 4068.1 4145.3 4306.6 3552.6 3622.1 3625.5 3626.0 3627.3 616.6 641.4 642.8 643.1 652.3 560.4 568.5 608.0 610.5 613.5 8134.9 8212.5 8259.3 8382.9 8412.8 518.9 570.2 579.0 583.6 620.2 3742.7 3777.2 3831.8 3910.4 3925.6 92092.6 94111.0 94192.0 94367.0 94516.5 76383.2 76578.0 79190.2 79438.6 80185.2 53976.7 67414.3 67843.0 67963.0 68937.0 7668.6 7837.9 7857.4 7884.2 7930.9 38203.0 38825.8 47772.8 47991.8 48590.3 54584.7 67291.7 68169.0 68967.0 70167.6 9981.9 10099.9 10235.2 10325.0 10883.5 10660.5 10686.5 10859.5 11167.6 11774.8 10061.7 10473.6 10567.0 10632.3 10680.4 10451.6 10476.6 10491.7 10514.0 10690.3 10130.2 10238.5 10362.8 10365.6 10435.5 8667.6 8954.6 8979.9 9081.1 9344.4 8919.3 8940.2 8977.3 8988.6 9127.5 18:27:57 INFO - PROCESS | 1668 | -------- Summary: end -------- 18:27:57 INFO - PROCESS | 1668 | 18:27:57 INFO - TEST-INFO | 1668: exit 0 18:27:57 INFO - TEST-OK | dromaeo_css | took 1114247ms 18:27:57 INFO - TEST-START | kraken 18:27:57 INFO - operating with platform_type : mac_ 18:27:57 INFO - Initialising browser for kraken test... 18:27:57 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpea0vDE/profile http://localhost:49275/getInfo.html) 18:27:59 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 18:27:59 INFO - PROCESS | 1674 | colorDepth:24 18:27:59 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 18:27:59 INFO - PROCESS | 1674 | __metrics 18:28:00 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:28:00 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:28:00 INFO - TEST-INFO | 1674: exit 0 18:28:00 INFO - Browser initialized. 18:28:00 INFO - Running cycle 1/1 for kraken test... 18:28:00 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpea0vDE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:12 INFO - PROCESS | 1676 | RSS: Main: 211193856 18:28:12 INFO - PROCESS | 1676 | 18:28:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 18:28:16 INFO - PROCESS | 1676 | RSS: Main: 271425536 18:28:16 INFO - PROCESS | 1676 | 18:28:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 18:28:19 INFO - PROCESS | 1676 | RSS: Main: 285544448 18:28:19 INFO - PROCESS | 1676 | 18:28:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 18:28:23 INFO - PROCESS | 1676 | RSS: Main: 372097024 18:28:23 INFO - PROCESS | 1676 | 18:28:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 18:28:25 INFO - PROCESS | 1676 | RSS: Main: 278282240 18:28:25 INFO - PROCESS | 1676 | 18:28:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:28:28 INFO - PROCESS | 1676 | RSS: Main: 279564288 18:28:28 INFO - PROCESS | 1676 | 18:28:31 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 18:28:32 INFO - PROCESS | 1676 | RSS: Main: 319221760 18:28:32 INFO - PROCESS | 1676 | 18:28:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 18:28:35 INFO - PROCESS | 1676 | RSS: Main: 318246912 18:28:35 INFO - PROCESS | 1676 | 18:28:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 18:28:38 INFO - PROCESS | 1676 | RSS: Main: 317517824 18:28:38 INFO - PROCESS | 1676 | 18:28:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:28:41 INFO - PROCESS | 1676 | RSS: Main: 308752384 18:28:41 INFO - PROCESS | 1676 | 18:28:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:28:43 INFO - PROCESS | 1676 | RSS: Main: 315113472 18:28:43 INFO - PROCESS | 1676 | 18:28:45 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:28:46 INFO - PROCESS | 1676 | RSS: Main: 318660608 18:28:46 INFO - PROCESS | 1676 | 18:28:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:28:49 INFO - PROCESS | 1676 | RSS: Main: 323497984 18:28:49 INFO - PROCESS | 1676 | 18:28:52 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:28:53 INFO - PROCESS | 1676 | RSS: Main: 321703936 18:28:53 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 18:28:55 INFO - PROCESS | 1676 | RSS: Main: 385880064 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | __start_tp_report 18:28:55 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 18:28:55 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 18:28:55 INFO - PROCESS | 1676 | |i|pagename|runs| 18:28:55 INFO - PROCESS | 1676 | |0;ai-astar;113;113;119;113;113;113;114;114;114;134 18:28:55 INFO - PROCESS | 1676 | |1;audio-beat-detection;152;123;156;155;117;134;103;176;167;150 18:28:55 INFO - PROCESS | 1676 | |2;audio-dft;182;184;186;185;182;184;185;196;261;203 18:28:55 INFO - PROCESS | 1676 | |3;audio-fft;69;80;72;151;56;56;57;63;130;55 18:28:55 INFO - PROCESS | 1676 | |4;audio-oscillator;97;99;142;77;86;86;126;78;117;167 18:28:55 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;84;87;85;90;84;88;85;87;84 18:28:55 INFO - PROCESS | 1676 | |6;imaging-darkroom;93;92;94;92;94;94;94;93;93;93 18:28:55 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;83;84;84;84;84;83;83;85;87 18:28:55 INFO - PROCESS | 1676 | |8;json-parse-financial;60;63;65;63;63;65;65;83;61;59 18:28:55 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;65;72;71;74;72;77;98;100;68 18:28:55 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;82;81;81;80;81;80;80;81;85;85 18:28:55 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;142;141;147;129;127;125;129;127;167;159 18:28:55 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;169;168;167;169;172;224;222;168;164;219 18:28:55 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;69;68;72;71;57;84;92;95;93;93 18:28:55 INFO - PROCESS | 1676 | __end_tp_report 18:28:55 INFO - PROCESS | 1676 | __start_cc_report 18:28:55 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1944 18:28:55 INFO - PROCESS | 1676 | __end_cc_report 18:28:55 INFO - PROCESS | 1676 | __startTimestamp1464053335613__endTimestamp 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | ------- Summary: start ------- 18:28:55 INFO - PROCESS | 1676 | Number of tests: 14 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:116.00 Median:114.00 stddev:6.58 (5.8%) stddev-sans-first:6.89 18:28:55 INFO - PROCESS | 1676 | Values: 113.0 113.0 119.0 113.0 113.0 113.0 114.0 114.0 114.0 134.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:153.50 stddev:23.25 (15.1%) stddev-sans-first:24.44 18:28:55 INFO - PROCESS | 1676 | Values: 152.0 123.0 156.0 155.0 117.0 134.0 103.0 176.0 167.0 150.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:194.80 Median:185.50 stddev:24.22 (13.1%) stddev-sans-first:25.25 18:28:55 INFO - PROCESS | 1676 | Values: 182.0 184.0 186.0 185.0 182.0 184.0 185.0 196.0 261.0 203.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:78.90 Median:60.00 stddev:33.84 (56.4%) stddev-sans-first:35.71 18:28:55 INFO - PROCESS | 1676 | Values: 69.0 80.0 72.0 151.0 56.0 56.0 57.0 63.0 130.0 55.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:107.50 Median:82.00 stddev:29.95 (36.5%) stddev-sans-first:31.52 18:28:55 INFO - PROCESS | 1676 | Values: 97.0 99.0 142.0 77.0 86.0 86.0 126.0 78.0 117.0 167.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:2.02 (2.3%) stddev-sans-first:2.12 18:28:55 INFO - PROCESS | 1676 | Values: 87.0 84.0 87.0 85.0 90.0 84.0 88.0 85.0 87.0 84.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.50 stddev:0.79 (0.8%) stddev-sans-first:0.83 18:28:55 INFO - PROCESS | 1676 | Values: 93.0 92.0 94.0 92.0 94.0 94.0 94.0 93.0 93.0 93.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.27 18:28:55 INFO - PROCESS | 1676 | Values: 85.0 83.0 84.0 84.0 84.0 84.0 83.0 83.0 85.0 87.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.00 stddev:6.77 (10.6%) stddev-sans-first:6.96 18:28:55 INFO - PROCESS | 1676 | Values: 60.0 63.0 65.0 63.0 63.0 65.0 65.0 83.0 61.0 59.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:72.00 stddev:12.81 (17.8%) stddev-sans-first:12.69 18:28:55 INFO - PROCESS | 1676 | Values: 63.0 65.0 72.0 71.0 74.0 72.0 77.0 98.0 100.0 68.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.00 stddev:1.90 (2.3%) stddev-sans-first:2.01 18:28:55 INFO - PROCESS | 1676 | Values: 82.0 81.0 81.0 80.0 81.0 80.0 80.0 81.0 85.0 85.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:139.30 Median:141.50 stddev:14.70 (10.4%) stddev-sans-first:15.56 18:28:55 INFO - PROCESS | 1676 | Values: 142.0 141.0 147.0 129.0 127.0 125.0 129.0 127.0 167.0 159.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:170.50 stddev:25.96 (15.2%) stddev-sans-first:26.94 18:28:55 INFO - PROCESS | 1676 | Values: 169.0 168.0 167.0 169.0 172.0 224.0 222.0 168.0 164.0 219.0 18:28:55 INFO - PROCESS | 1676 | 18:28:55 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:88.00 stddev:13.57 (15.4%) stddev-sans-first:13.87 18:28:55 INFO - PROCESS | 1676 | Values: 69.0 68.0 72.0 71.0 57.0 84.0 92.0 95.0 93.0 93.0 18:28:55 INFO - PROCESS | 1676 | -------- Summary: end -------- 18:28:55 INFO - PROCESS | 1676 | 18:28:56 INFO - TEST-INFO | 1676: exit 0 18:28:56 INFO - TEST-OK | kraken | took 58445ms 18:28:56 INFO - SUITE-END | took 1172s 18:28:56 INFO - Completed test suite (00:19:33) 18:28:56 INFO - javascript benchmark 18:28:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.5020746887967, 3390.88131915383, 3402.846871890765, 3420.213350929285, 3451.344244593527, 5552.944470555293, 5553.555715542757, 5558.499735023852, 5561.555075593953, 5579.218909352684, 5516.531094856932, 5551.250581171541, 5578.721063946797, 5580.581456390777, 5641.0, 4948.331975183358, 5033.144365457872, 5048.747562621873, 5113.233015047754, 5154.871128221787, 5025.14572522672, 5100.438951715309, 5105.872353191163, 5113.054084994288, 5117.820876269331, 5046.697198168104, 5084.567811736007, 5100.183970564711, 5106.617003724717, 5124.436312005676, 5121.718305493197, 5141.742912854343, 5144.176931690911, 5153.871153221199, 5164.974175129119, 4926.433460152057, 4970.7017578945015, 5038.118329292359, 5073.1122053640465, 5106.846794596168, 4332.328489084197, 4518.638508919278, 5426.457354264581, 5451.890962180734, 5468.398476167618, 6009.879802403972, 6057.182280392135, 6061.363556826514, 6064.818055458343, 6065.969670151665, 5941.613795103304, 6065.104231525981, 6122.693865306717, 6168.352323006065, 6169.7532098716, 5660.264165658438, 5665.461781130868, 5679.119736440775, 5693.857653558653, 5710.857228569277, 6185.567010309235, 6227.782027629102, 6239.78160764371, 6262.937370626327, 6265.060240963892, 5793.2179155814265, 5899.528037756969, 5991.310999235144, 5996.850078748066, 6013.488853447506, 6027.427394397525, 6048.637081775108, 6073.392660733891, 6087.786927457518, 6098.512119030467, 5988.131720900532, 5995.430434108753, 6000.979833428328, 6001.849953751192, 6018.946684330224, 5978.073398623221, 6004.249468816398, 6058.394160583906, 6071.392860713893, 6147.44672979434, 4150.730202536825, 4854.17479028566, 4868.002059577778, 4875.317455556225, 4921.926171107436, 4287.3783301421745, 4384.738959818132, 4412.602865742098, 4422.800973956179, 4453.064856380196, 5348.0, 5428.457154284619, 5501.477359650827, 5548.0, 5551.111822108443, 6113.480354169857, 6119.969400152971, 6121.326654068049, 6145.754169833246, 6154.3845615438995, 4488.573585509372, 5495.587830912697, 5498.065328894098, 5526.281583394213, 5527.39198688152, 4491.910161796811, 5203.869903252374, 5209.869753256123, 5266.394364648115, 5295.602829787781, 6194.659293738798, 6222.875542489125, 6229.470495007975, 6230.0, 6247.718852651732, 5946.672932988728, 6119.082137679384, 6119.082137679384, 6131.63210207389, 6136.907946380718, 6140.903050468011, 6190.288116866618, 6217.502599792097, 6223.0, 6224.190855188796, 6000.399960003964, 6003.309619393825, 6045.455908968038, 6048.758049677964, 6063.757449701962, 4833.275008748576, 5749.597528173155, 5860.824175274749, 6115.571909966259, 6123.510119190365, 5795.7391917362975, 5887.941120588739, 5963.493103086113, 5974.103884417372, 5990.4908082813445, 5863.501602363847, 5870.119482077723, 5880.147378629989, 5897.498712609477, 5901.527877769854, 5607.80372686954, 5761.769529218952, 5770.221020162225, 5776.162456443708, 5779.2775903012125, 5804.506616937608, 5839.182514447895, 5936.495397891227, 5968.820935371946, 5981.820545383645, 5767.163761254511, 5780.682062486603, 5800.9419905800405, 5812.796552120655, 5826.592138550261], "value": 5529.875839568061, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.20465970755765, 521.8851852592429, 537.8973105134473, 549.0309603549736, 596.9910451343234, 25576.74423255767, 38090.42864357033, 38990.0, 39078.804605977, 39132.21735565287, 35769.82115089421, 44054.33918491217, 44405.77797111018, 44633.107337853224, 44809.10381792362, 9616.759581010496, 9799.0, 9813.362131461432, 9814.0, 9843.950780246088, 9584.281178911575, 9712.41725496469, 9724.124828765409, 9742.07450292221, 9744.415335079882, 9678.17735492484, 9755.853662195073, 9760.170385517204, 9761.560729767178, 9768.267379946532, 9668.854967175497, 9720.708378748614, 9723.805523889554, 9725.610975560969, 9742.561584728704, 9517.666881659108, 9630.422174669542, 9634.22926165912, 9641.228701703847, 9651.034896510362, 33949.981500555026, 40675.37298508086, 40687.0, 41221.0, 41290.0, 3170.1123685368134, 3237.53055806909, 3377.527146199534, 3399.915002124942, 3710.07248187953, 27481.725182748116, 28288.43423131526, 28524.85737571299, 28817.56773648397, 29317.706822931712, 2684.4362683836416, 2690.515707172708, 2701.8243814152015, 2725.046233818168, 2851.600775891377, 363475.0, 426933.0, 431632.0, 432919.0, 433581.99999999686, 36097.09757256037, 63985.680071599345, 64384.678076609314, 64409.0, 64437.0, 35614.0, 35882.0, 35904.82047589745, 35974.46038309427, 36502.90491285266, 15301.923490382476, 15617.375304987674, 15627.453039143575, 15628.68742625164, 15630.9218453907, 13142.802857957138, 13241.801372979413, 13242.337883105805, 13258.73482530344, 13266.07137500385, 8082.555459449669, 8214.26071653554, 8236.094029656733, 8251.628676709563, 8301.335893128535, 3391.6777906098882, 3436.5876094868777, 3441.6214216436174, 3444.4488881778793, 3464.4456886897974, 936.8641546975787, 978.4569563891966, 1015.1168483419325, 1031.4481752262504, 1039.4646756920192, 27984.44031119407, 28613.0, 28720.712792872222, 28726.569101463494, 28758.836527981905, 1083.7128161037174, 1095.474172397238, 1127.4869934179928, 1127.6617014895498, 1153.2734377342388, 12592.370381480889, 14353.282335883165, 15533.301001454962, 15906.840931590536, 16264.0, 595417.0, 600567.0, 601623.0, 603507.0, 603729.0, 61070.38929610736, 61475.38524614786, 61730.382696173365, 61744.0, 61904.69047654823, 1359.2727890578492, 1493.3578561218562, 1516.5677781832126, 1527.3737767515265, 1529.9617509562127, 24322.783860806536, 24368.0, 24734.0, 24744.381390464663, 24775.0, 47339.52660473351, 47636.2854557175, 47791.76104119457, 48047.75976120097, 48068.759656201495, 396968.0151599339, 397830.0, 398527.0, 398627.0, 398682.0, 23046.884765576066, 25985.0, 26214.0, 26493.072742453922, 26814.463710726068, 41817.37273940954, 42410.36384454174, 43514.782426087666, 43581.78209108934, 43985.12029759451, 35437.46843797294, 35943.820280899476, 35956.640433596374, 36075.63924360723, 36640.08399790026, 29604.703952961056, 35648.64351356557, 35687.46468803022, 36197.63802362048, 36352.27295454129, 35414.114647134025, 35421.64578354183, 35742.285154296245, 36636.0, 36722.63277367192, 31191.688083119785, 31584.526232106076, 31645.0, 31745.365092697553, 31757.682423175473, 24091.63862542028, 24161.63757543603, 24328.75671243336, 24521.632175517025, 24754.628680569447], "value": 18081.59708641689, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.8225239768303, 399.2992298516104, 402.36828179757777, 402.42855145693113, 415.7235438433442, 14778.334974926127, 14823.629409264748, 20104.69842952355, 20806.6878996815, 20809.99999999998, 16360.509184724448, 17596.82403175968, 20712.585748285026, 20914.686279705817, 21007.0, 5817.447342502466, 6142.5085993120665, 6149.108379285001, 6156.353582873801, 6168.84577885552, 4254.489461264652, 5980.162777211194, 6136.877262454748, 6139.846503837418, 6161.815145545641, 6395.360463953591, 6703.1956165260235, 6704.396604305612, 6724.327567243286, 6725.966370168142, 6410.230772307306, 6639.900401493981, 6654.23476300222, 6656.63388513634, 6676.799696009079, 5852.853678658068, 5885.117232415129, 5886.411358864079, 5886.470217680387, 5895.4399332063385, 26792.598111028154, 27190.184294471197, 27441.17676469709, 27548.862255688593, 27557.0, 2011.688188330811, 2183.825293976478, 2253.6844841722173, 2394.0064873077654, 2885.898993535216, 9336.439813611138, 9946.403215807002, 10053.547590358377, 10095.596176152945, 10365.067143957005, 1664.459050808492, 1748.9212985415686, 1788.499220218341, 1821.8633602479736, 1864.3474783825689, 89478.0, 108123.45938270258, 113187.43406282998, 115947.42026289814, 120608.3969580164, 9526.0, 11550.88449115515, 11587.826182607268, 11835.763284734257, 11976.401179940967, 15285.923570382078, 15373.923130384277, 15430.768538471932, 15432.305546250445, 15455.38178472871, 7310.342069213796, 7984.800379990546, 8342.0, 8437.620307086196, 8803.339749518811, 8905.109489051043, 8932.732018039469, 8946.507942063248, 9017.413868098552, 9030.187283144418, 4272.124214536013, 4684.695494792896, 4722.409698787652, 4737.928931066037, 4757.643176761688, 1709.5811526176167, 1812.3053764075455, 1899.6675581773134, 1971.5465442947916, 1973.792751761073, 876.2114097312469, 1004.683524689735, 1021.7394564386034, 1022.1924679503257, 1022.7596514819012, 19908.60182796336, 20329.186832526535, 20750.32238071923, 20791.58416831655, 21269.574608507744, 963.9662611808693, 990.5938565188238, 1029.3000759483473, 1043.5147656339686, 1065.7016035510069, 11034.227604067799, 11416.25794323383, 11445.313281203154, 11447.54209831614, 11455.885441145649, 593292.0, 597490.0, 610532.0, 612535.0, 624321.0, 128422.0, 131372.68627313795, 132413.0, 132734.67265327417, 134098.32950835378, 1008.6923488336128, 1016.0601443664581, 1020.9642662506776, 1025.8615086963314, 1029.156092004564, 18440.631187376446, 18703.34538291153, 18723.970181639597, 18767.530811729815, 19039.809601903802, 47524.2871356923, 49402.75298623484, 52361.69095772484, 54863.0, 55357.44642553523, 59255.814883702944, 76912.0, 81591.0, 82026.0, 82183.58908205421, 7825.647845846836, 8090.555019473985, 8281.917180828355, 8320.058755514514, 8618.095100014409, 11424.428778560872, 12796.616101516596, 12832.55086071983, 13119.606411807661, 13231.933840330737, 9584.66453674118, 10816.134709223086, 11059.281146725438, 11070.619127969012, 11278.984891359816, 10054.849177262493, 10828.620998265023, 10882.836757448486, 11081.833772493257, 11313.490892910004, 5764.394738552392, 8125.431219814556, 9909.900900991186, 11561.42192890369, 11614.303141811519, 8250.752477425687, 8385.37109716786, 8515.446495977742, 8520.19058189471, 8549.487030778173, 10499.737506562396, 11167.0, 11473.0, 11538.0, 11584.478698458759], "value": 9648.93391066148, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.6282640860563, 1549.5273941447858, 1565.7729629203766, 1775.1656721340973, 1904.1145867171763, 7777.183395743443, 8526.910906827829, 8651.394402391836, 8694.087120852315, 8700.303975681945, 8396.748097557067, 8644.400785854621, 8655.826883462343, 8669.003064647568, 8692.695755648554, 2139.6683514055267, 2358.96461553076, 2416.806655467567, 2425.721042080157, 2444.340028192387, 2458.311672731638, 2462.224399314219, 2462.445949661321, 2466.075221791828, 2476.5170791695627, 2377.2773970053213, 2411.5056413435204, 2441.963370549443, 2444.1567659157563, 2447.3759191406166, 1979.7228388025687, 2130.62714025045, 2426.708794944609, 2428.7206971198184, 2431.9270421887372, 2392.1986134644917, 2420.673209116764, 2425.163318655061, 2431.4529230923076, 2432.1730611592143, 8550.1449855014, 8634.956825215897, 8638.481691098492, 8649.65401383944, 8661.740147795575, 4518.390017347649, 5111.309973153614, 5122.820701275475, 5158.793648254065, 5164.586833053347, 3463.9134021649406, 3506.63180366063, 3579.3892748263384, 3585.032041348821, 3590.263995880838, 1022.85168650546, 1031.587365053977, 1062.8405739139114, 1073.3238060022213, 1079.5196137718717, 11980.520779168823, 11989.700257493545, 11990.760184796343, 12009.57966471178, 12013.219140755911, 4622.653301002439, 4691.061787642485, 4704.0, 4715.929261061086, 4782.378290822171, 9958.103770660675, 10108.342957707726, 10114.393136411838, 10116.140128089048, 10120.0, 5328.8401347959625, 5342.091844386464, 5376.435474275223, 5385.919211211835, 5388.326459192601, 4289.463817022872, 4393.20922234001, 4397.582229688174, 4452.30989196675, 4454.77726113693, 3961.603839616015, 4047.8785636430953, 4155.252054630196, 4169.395437661522, 4173.749575025508, 1334.9933250333686, 1343.6506508307714, 1343.7715588350004, 1354.3634491788844, 1361.0948719101686, 909.6673373507842, 918.6876462002951, 937.8733870927338, 942.3168203052732, 945.6122989574242, 5754.079347304411, 5760.8271751847515, 5816.709164541756, 5862.970685146547, 5898.400425360627, 897.9730608081768, 939.1453777062976, 957.9377340472847, 963.325672029582, 992.161623428202, 3660.1215708230366, 3856.749311294757, 3995.180987897474, 4003.259397011562, 4021.477207962946, 9328.673496427593, 10022.649207277711, 10686.946565267124, 10824.0, 10849.752278488075, 10246.897531024595, 10550.683479495627, 10601.681949541526, 10726.249162558703, 10762.408067556204, 6890.827729306707, 7272.563646181246, 8375.790605234795, 8429.915700843036, 8445.282151017109, 5404.189371594292, 5545.694986775766, 5606.795268080497, 5698.743556540048, 5735.713214339183, 8146.877796832934, 9193.494357810385, 9241.122093401116, 9463.574139163617, 9686.32195746291, 9633.421994680342, 10063.49682515886, 10073.647422340477, 10075.949620252146, 10141.949290253797, 6704.66476676174, 7232.312930271616, 7497.250274972677, 7499.962500187464, 7503.0996280444515, 7565.4325925556905, 7928.960355198187, 8144.022717273965, 8164.428490005643, 8189.959050204711, 7238.457115716237, 7329.120505539369, 7374.373178279906, 7475.850482990419, 7507.774766757006, 7154.821129471805, 7156.9642151790995, 7229.313215244543, 7261.237570055068, 7288.052553168266, 6782.830429239111, 6876.828079298058, 6906.998485219515, 6957.79126626202, 6959.89560156588, 4400.44994375703, 4672.813087476463, 4822.373091498082, 4884.3650325457465, 4961.355023846877, 4882.340883980617, 4904.092742842586, 4970.527799859007, 4991.101601711727, 4993.650444468853], "value": 4551.791703170748, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.0614979660204, 320.8812739286464, 333.17539090750387, 334.7690093835255, 336.45998172932445, 6433.2280126384785, 6503.512236582252, 6558.8688226235445, 6576.145101136852, 6619.569727967691, 5549.500544950954, 6230.283517395501, 6373.904391434121, 6396.936030639704, 6497.610143391388, 3893.5717071122153, 4563.863084107474, 4747.335373047776, 4788.569028787409, 4789.401324834395, 4084.9591504084874, 4631.60631346336, 4695.788689508963, 4814.9037019259595, 4828.46886842447, 4708.529147085298, 4824.1316563018645, 4826.61387089032, 4849.903001939959, 4870.561649451566, 4842.394700662399, 4848.466668666461, 4871.2206047032305, 4871.4154301483695, 4879.219324908033, 4132.40080188374, 4169.478815148106, 4169.833206671729, 4170.436991006236, 4177.456930599003, 6268.0, 6277.340879207663, 6296.590721603081, 6301.243850737941, 6313.305536390993, 5736.053551164053, 5753.683547404891, 5754.769809207627, 5762.567807414419, 5776.220210271644, 6661.233958094833, 6880.277570855088, 6914.343137401939, 6916.1700595928305, 6916.858718311472, 4324.718893271959, 4527.569880861313, 4569.840055598038, 4571.862844114682, 4586.220342541743, 7284.235155308722, 7348.926510734877, 7355.963220183919, 7378.225286344964, 7385.150707668633, 3944.4280579315837, 4590.219662657356, 4611.654125940568, 4612.285095810154, 4613.469451013109, 4663.766811659403, 4671.0, 4677.859664210079, 4681.133990211822, 4684.718916864999, 5380.488853558905, 5583.190437386637, 5602.355729091207, 5609.971950140305, 5620.662760234317, 6992.895106573304, 7048.224695283515, 7073.752418665322, 7078.221395646475, 7085.043519124955, 1539.0, 1575.0156152404747, 1576.7950166478513, 1600.7438809790378, 1625.4960962101786, 975.073680003999, 983.3755565216139, 985.6550207427343, 1026.4970164619433, 1048.0672201740458, 6480.2547707013355, 6500.674966251669, 6501.674916254168, 6502.707378167994, 6515.446187074057, 7197.820054498679, 7203.279672032755, 7206.576875983866, 7212.819679508054, 7221.386182174574, 6490.643014634241, 6498.870022599616, 6499.512536559777, 6501.544891857525, 6503.902441463382, 3699.7040236780995, 3764.265968136242, 3822.120912190212, 3847.115163512408, 3872.5352957645264, 7570.924290757132, 7606.847863042708, 7621.428392870557, 7624.237576242443, 7626.885596716053, 7493.887591686129, 7501.0, 7518.812029699301, 7523.0972283326355, 7523.623818809147, 6183.319834818257, 7599.16409194977, 7599.544027358376, 7600.201978792147, 7616.0860696716745, 6970.128733908262, 7201.279872012967, 7214.2064372917885, 7258.745943891939, 7277.053982982178, 7135.537214871043, 7205.675744591401, 7210.315020073084, 7212.783616491513, 7214.603196824015, 7610.847783044197, 7614.8096297593, 7616.7334143304715, 7618.162002179645, 7626.894100355528, 4586.724796512087, 4642.675012749075, 4646.976765116153, 4649.558291962258, 4669.20623494, 6978.197507286727, 7250.456215783732, 7269.491135620456, 7270.600116993616, 7282.635868206675, 6976.965115174392, 7029.578225306498, 7032.367086962198, 7046.541974771623, 7066.4346852250665, 7020.684069217, 7024.121984751906, 7027.262137475696, 7029.261927497743, 7038.824029399306, 6853.588784672936, 6859.348361905611, 6865.244823069558, 6868.828279292858, 6872.347127022925, 6693.196816382066, 6746.325367463214, 6935.994280829353, 7061.6469176542, 7092.468064895051, 6728.360805723644, 6830.795076147723, 6847.623380714109, 6897.172339319314, 6906.343897329745], "value": 5102.121281208136, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.8406466512702, 700.7022015643352, 747.7980945144808, 754.0950858969237, 763.7174245529155, 14683.0, 14852.48016319429, 14883.925580372083, 14898.255087245636, 14920.925395373035, 11080.612178573752, 13111.213327200374, 13116.54092106774, 13173.209607423561, 13191.406386712597, 711.8932160175976, 889.6130183370223, 913.5934509143434, 918.9402688825238, 920.0661328751289, 907.4827348411408, 916.2852974679727, 918.150710592699, 920.0983036624112, 922.5433410461798, 968.7287559483356, 974.4202199691208, 975.068809287129, 975.5951280218701, 976.2287792643803, 758.2834221660534, 768.8039549914764, 771.6797529025448, 773.9071311442607, 945.4988855906381, 712.4834494991142, 726.6475759256756, 733.120255693165, 733.8458923626044, 734.6767422334156, 14952.028118172393, 15016.549503514912, 15025.173615451145, 15040.17279049652, 15070.924645376812, 12553.874461255362, 12839.358032098451, 12843.614691559269, 12857.292848893307, 13003.414846331938, 10059.5473203706, 10162.18702503798, 10183.28716989811, 10426.478676066243, 10430.426326552113, 3432.056184549244, 3494.4932984717075, 3505.964940350589, 3506.947395789065, 3557.0395993081984, 80911.0, 81399.779003315, 85748.0, 85897.0, 86028.56985715032, 9454.527273636291, 9526.952365238269, 9893.406395616286, 9933.50332483373, 10320.916303788144, 22463.0, 22470.0, 22492.325230243117, 22516.88741556282, 22528.887355563118, 7161.283871612901, 7291.34377905991, 7448.9627551861895, 7554.508956917783, 7638.274363935418, 3921.9607803921594, 4039.3940908863906, 4068.064345200561, 4145.2952997990415, 4306.612404883575, 3552.609212986569, 3622.094476380905, 3625.492431059654, 3626.0390996385977, 3627.2564124354444, 616.6146158650844, 641.3650486018807, 642.8135840606266, 643.0964494884695, 652.2987788127782, 560.366785532354, 568.5054003017401, 607.9817605471843, 610.4688920638984, 613.4877377389929, 8134.877976830352, 8212.507249564926, 8259.297959673495, 8382.87425688615, 8412.78968025792, 518.9247559103909, 570.181789132596, 578.988420231593, 583.5798225277794, 620.1751670278519, 3742.700583953277, 3777.225668737902, 3831.7700937943814, 3910.413437984268, 3925.568187499373, 92092.61861072089, 94111.0, 94192.0, 94367.0, 94516.52741736248, 76383.23616763872, 76578.0, 79190.20809791943, 79438.60280698675, 80185.19814801894, 53976.730116349165, 67414.3258567408, 67843.0, 67963.0, 68937.0, 7668.57822819728, 7837.882431763642, 7857.371410287049, 7884.172161923145, 7930.881036784337, 38203.044923877125, 38825.805870970464, 47772.805679858284, 47991.76004119957, 48590.27114593213, 54584.72707636436, 67291.66354168394, 68169.0, 68966.9654955166, 70167.64916175387, 9981.900180998096, 10099.899001009895, 10235.181185505291, 10325.0, 10883.455827208767, 10660.46697665129, 10686.465676716289, 10859.51132199037, 11167.609133680282, 11774.764504709687, 10061.69814905554, 10473.581056757645, 10567.0, 10632.30889992148, 10680.412577308323, 10451.581936722447, 10476.63331783384, 10491.737706557396, 10514.0, 10690.251682382159, 10130.189584833342, 10238.53926573291, 10362.792744144925, 10365.585376584851, 10435.478226088817, 8667.566621669017, 8954.641814327353, 8979.865302020606, 9081.137291957253, 9344.392614480037, 8919.286457083548, 8940.240079593308, 8977.281817454457, 8988.595513202052, 9127.543622818965], "value": 6748.111294509991, "unit": "score"}], "name": "dromaeo_css", "value": 7299.00603439601, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 119.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 134.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 156.0, 155.0, 117.0, 134.0, 103.0, 176.0, 167.0, 150.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 186.0, 185.0, 182.0, 184.0, 185.0, 196.0, 261.0, 203.0], "value": 194.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 72.0, 151.0, 56.0, 56.0, 57.0, 63.0, 130.0, 55.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 142.0, 77.0, 86.0, 86.0, 126.0, 78.0, 117.0, 167.0], "value": 107.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 90.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 92.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 84.0, 84.0, 84.0, 83.0, 83.0, 85.0, 87.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 65.0, 63.0, 63.0, 65.0, 65.0, 83.0, 61.0, 59.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 72.0, 71.0, 74.0, 72.0, 77.0, 98.0, 100.0, 68.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 80.0, 81.0, 80.0, 80.0, 81.0, 85.0, 85.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 147.0, 129.0, 127.0, 125.0, 129.0, 127.0, 167.0, 159.0], "value": 139.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 167.0, 169.0, 172.0, 224.0, 222.0, 168.0, 164.0, 219.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 72.0, 71.0, 57.0, 84.0, 92.0, 95.0, 93.0, 93.0], "value": 79.4, "unit": "score"}], "name": "kraken", "value": 1529.2000000000003, "alertThreshold": 2.0}]} 18:28:56 INFO - Return code: 0 18:28:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:28:56 ERROR - # TBPL SUCCESS # 18:28:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:56 INFO - 2016-05-23 18:28:56.897 screenresolution[1680:10420] starting screenresolution argv=screenresolution get 18:28:56 INFO - 2016-05-23 18:28:56.899 screenresolution[1680:10420] Display 0: 1600x1200x32@60 18:28:56 INFO - 2016-05-23 18:28:56.905 screenresolution[1681:10423] starting screenresolution argv=screenresolution list 18:28:56 INFO - Available Modes on Display 0 18:28:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:57 INFO - Intel Iris: 18:28:57 INFO - Chipset Model: Intel Iris 18:28:57 INFO - Type: GPU 18:28:57 INFO - Bus: Built-In 18:28:57 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:57 INFO - Vendor: Intel (0x8086) 18:28:57 INFO - Device ID: 0x0a2e 18:28:57 INFO - Revision ID: 0x0009 18:28:57 INFO - Displays: 18:28:57 INFO - Display: 18:28:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:57 INFO - Display Serial Number: 1600x1200 60 18:28:57 INFO - Main Display: Yes 18:28:57 INFO - Mirror: Off 18:28:57 INFO - Online: Yes 18:28:57 INFO - Rotation: Supported 18:28:57 INFO - Return code: 0 18:28:57 INFO - Running post-action listener: _resource_record_post_action 18:28:57 INFO - Running post-run listener: _resource_record_post_run 18:28:58 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 31526400; Write bytes: 652748288; Read time: 197; Write time: 1813 18:28:58 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342729216; Write bytes: 336246784; Read time: 26437; Write time: 671 18:28:58 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30604800; Write bytes: 297164288; Read time: 186; Write time: 1086 18:28:58 INFO - Running post-run listener: _upload_blobber_files 18:28:58 INFO - Blob upload gear active. 18:28:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:28:58 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 18:28:58 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'] 18:28:58 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 18:28:58 INFO - (blobuploader) - INFO - Open directory for files ... 18:28:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:28:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:28:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:59 INFO - (blobuploader) - INFO - Done attempting. 18:28:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:28:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:29:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:00 INFO - (blobuploader) - INFO - Done attempting. 18:29:00 INFO - (blobuploader) - INFO - Iteration through files over. 18:29:00 INFO - Return code: 0 18:29:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:29:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:29:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac0daf018ac9ae3ecdc3262f3b5d95a5c5ae3f4b969761ea109f3928a4d9bc3fda35c5fe17081f704629647c4a6e1056e21df0f0ae052861378c5c5c261136a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a686e155d2b1eff4f4e4e8da61f9af2f6d382278d13f90b520c59a81bdb5e17ff3f129076ee1a84142d533b967e8fc6e2f5ba2c9c9d05217106a34c1dd5e92e"} 18:29:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:29:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:29:00 INFO - Contents: 18:29:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac0daf018ac9ae3ecdc3262f3b5d95a5c5ae3f4b969761ea109f3928a4d9bc3fda35c5fe17081f704629647c4a6e1056e21df0f0ae052861378c5c5c261136a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a686e155d2b1eff4f4e4e8da61f9af2f6d382278d13f90b520c59a81bdb5e17ff3f129076ee1a84142d533b967e8fc6e2f5ba2c9c9d05217106a34c1dd5e92e"} 18:29:00 INFO - Running post-run listener: copy_logs_to_upload_dir 18:29:00 INFO - Copying logs to upload dir... 18:29:00 INFO - mkdir: /builds/slave/test/build/upload/logs 18:29:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1305.915416 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-05-23 18:29:00.854705) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 18:29:00.858091) ========= 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.LOxSyLXixL/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.QjKJewd0JV/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac0daf018ac9ae3ecdc3262f3b5d95a5c5ae3f4b969761ea109f3928a4d9bc3fda35c5fe17081f704629647c4a6e1056e21df0f0ae052861378c5c5c261136a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a686e155d2b1eff4f4e4e8da61f9af2f6d382278d13f90b520c59a81bdb5e17ff3f129076ee1a84142d533b967e8fc6e2f5ba2c9c9d05217106a34c1dd5e92e"} build_url:https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011077 build_url: 'https://queue.taskcluster.net/v1/task/Q5r8eDjBStOa90zSbhb8NA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac0daf018ac9ae3ecdc3262f3b5d95a5c5ae3f4b969761ea109f3928a4d9bc3fda35c5fe17081f704629647c4a6e1056e21df0f0ae052861378c5c5c261136a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a686e155d2b1eff4f4e4e8da61f9af2f6d382278d13f90b520c59a81bdb5e17ff3f129076ee1a84142d533b967e8fc6e2f5ba2c9c9d05217106a34c1dd5e92e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 18:29:00.887884) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:29:00.888166) ========= 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.LOxSyLXixL/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.QjKJewd0JV/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.004923 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:29:00.936279) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 18:29:00.936587) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 18:29:00.936903) ========= ========= Total master_lag: 0.18 =========