builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0117 starttime: 1461616355.77 results: success (0) buildid: 20160425103006 builduid: 1a87fa8d522747ab8b0da4138352f4b4 revision: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:35.768371) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:35.768797) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:35.769127) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:36.423888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:36.424385) ========= 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.we0hPPdBKb/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.Mlnx1wR10s/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 13:32:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.58M=0.001s 2016-04-25 13:32:36 (8.58 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.208655 ========= master_lag: 0.51 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:37.140253) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 13:32:37.140627) ========= 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.we0hPPdBKb/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.Mlnx1wR10s/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.027171 ========= master_lag: 2.52 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 13:32:39.684432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 13:32:39.684904) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d6a91c76a9eddf2219caf028dea130f2d683ec2 --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 0d6a91c76a9eddf2219caf028dea130f2d683ec2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.we0hPPdBKb/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.Mlnx1wR10s/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 13:32:39,831 truncating revision to first 12 chars 2016-04-25 13:32:39,831 Setting DEBUG logging. 2016-04-25 13:32:39,831 attempt 1/10 2016-04-25 13:32:39,831 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0d6a91c76a9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 13:32:41,067 unpacking tar archive at: mozilla-aurora-0d6a91c76a9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.500456 ========= master_lag: 0.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 13:32:41.351855) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:41.352222) ========= script_repo_revision: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:41.352715) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:41.353020) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 13:32:41.449017) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-25 13:32:41.452363) ========= /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.we0hPPdBKb/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.Mlnx1wR10s/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:32:41 INFO - MultiFileLogger online at 20160425 13:32:41 in /builds/slave/test 13:32:41 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 13:32:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:32:41 INFO - {'append_to_log': False, 13:32:41 INFO - 'base_work_dir': '/builds/slave/test', 13:32:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:32:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:32:41 INFO - 'branch': 'Mozilla-Aurora', 13:32:41 INFO - 'buildbot_json_path': 'buildprops.json', 13:32:41 INFO - 'config_files': ('talos/mac_config.py',), 13:32:41 INFO - 'default_actions': ('clobber', 13:32:41 INFO - 'read-buildbot-config', 13:32:41 INFO - 'download-and-extract', 13:32:41 INFO - 'populate-webroot', 13:32:41 INFO - 'create-virtualenv', 13:32:41 INFO - 'install', 13:32:41 INFO - 'run-tests'), 13:32:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:32:41 INFO - 'download_minidump_stackwalk': True, 13:32:41 INFO - 'download_symbols': 'ondemand', 13:32:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:32:41 INFO - 'tooltool.py': '/tools/tooltool.py', 13:32:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:32:41 INFO - '/tools/misc-python/virtualenv.py')}, 13:32:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:32:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:32:41 INFO - 'installer_path': 'installer.exe', 13:32:41 INFO - 'log_level': 'info', 13:32:41 INFO - 'log_name': 'talos', 13:32:41 INFO - 'log_to_console': True, 13:32:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:32:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:32:41 INFO - 'opt_config_files': (), 13:32:41 INFO - 'pip_index': False, 13:32:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:32:41 INFO - 'cmd': ('bash', 13:32:41 INFO - '-c', 13:32:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:32:41 INFO - 'enabled': True, 13:32:41 INFO - 'halt_on_failure': False, 13:32:41 INFO - 'name': 'check_screen_resolution'},), 13:32:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:32:41 INFO - 'cmd': ('bash', 13:32:41 INFO - '-c', 13:32:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:32:41 INFO - 'enabled': True, 13:32:41 INFO - 'halt_on_failure': False, 13:32:41 INFO - 'name': 'check_screen_resolution'},), 13:32:41 INFO - 'run_cmd_checks_enabled': True, 13:32:41 INFO - 'sps_profile': False, 13:32:41 INFO - 'sps_profile_interval': 0, 13:32:41 INFO - 'suite': 'dromaeojs', 13:32:41 INFO - 'system_bits': '32', 13:32:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:32:41 INFO - 'title': 't-yosemite-r7-0117', 13:32:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:32:41 INFO - 'use_talos_json': True, 13:32:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:32:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:32:41 INFO - 'work_dir': 'build'} 13:32:41 INFO - ##### 13:32:41 INFO - ##### Running clobber step. 13:32:41 INFO - ##### 13:32:41 INFO - Running pre-action listener: _resource_record_pre_action 13:32:41 INFO - Running main action method: clobber 13:32:41 INFO - rmtree: /builds/slave/test/build 13:32:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:32:43 INFO - Running post-action listener: _resource_record_post_action 13:32:43 INFO - ##### 13:32:43 INFO - ##### Running read-buildbot-config step. 13:32:43 INFO - ##### 13:32:43 INFO - Running pre-action listener: _resource_record_pre_action 13:32:43 INFO - Running main action method: read_buildbot_config 13:32:43 INFO - Using buildbot properties: 13:32:43 INFO - { 13:32:43 INFO - "project": "", 13:32:43 INFO - "product": "firefox", 13:32:43 INFO - "script_repo_revision": "production", 13:32:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:32:43 INFO - "repository": "", 13:32:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:32:43 INFO - "buildid": "20160425103006", 13:32:43 INFO - "pgo_build": "False", 13:32:43 INFO - "basedir": "/builds/slave/test", 13:32:43 INFO - "buildnumber": 200, 13:32:43 INFO - "slavename": "t-yosemite-r7-0117", 13:32:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:32:43 INFO - "platform": "yosemite_r7", 13:32:43 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:32:43 INFO - "branch": "mozilla-aurora", 13:32:43 INFO - "repo_path": "releases/mozilla-aurora", 13:32:43 INFO - "revision": "0d6a91c76a9eddf2219caf028dea130f2d683ec2", 13:32:43 INFO - "stage_platform": "macosx64", 13:32:43 INFO - "builduid": "1a87fa8d522747ab8b0da4138352f4b4", 13:32:43 INFO - "slavebuilddir": "test" 13:32:43 INFO - } 13:32:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:32:43 INFO - Running post-action listener: _resource_record_post_action 13:32:43 INFO - ##### 13:32:43 INFO - ##### Running download-and-extract step. 13:32:43 INFO - ##### 13:32:43 INFO - Running pre-action listener: _resource_record_pre_action 13:32:43 INFO - Running main action method: download_and_extract 13:32:43 INFO - mkdir: /builds/slave/test/build/tests 13:32:43 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')]} 13:32:43 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:32:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:32:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:32:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:32:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:32:50 INFO - Downloaded 1199 bytes. 13:32:50 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:32:50 INFO - Using the following test package requirements: 13:32:50 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:32:50 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:32:50 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:32:50 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'jsshell-mac.zip'], 13:32:50 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:32:50 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:32:50 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:32:50 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:32:50 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:32:50 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:32:50 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:32:50 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:50 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:32:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:32:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:32:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:32:52 INFO - Downloaded 18109567 bytes. 13:32:52 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'] 13:32:52 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 13:32:55 INFO - Return code: 0 13:32:55 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 13:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:55 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:32:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:32:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:32:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:33:01 INFO - Downloaded 18109567 bytes. 13:33:01 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'] 13:33:01 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 13:33:04 INFO - Return code: 0 13:33:04 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')]} 13:33:04 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:33:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:33:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:33:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:33:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:33:06 INFO - Downloaded 10840145 bytes. 13:33:06 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'] 13:33:06 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 13:33:06 INFO - Return code: 0 13:33:06 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')]} 13:33:06 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:33:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:33:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:33:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:33:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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 13:33:09 INFO - Downloaded 114074910 bytes. 13:33:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:33:09 INFO - mkdir: /builds/slave/test/properties 13:33:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:33:09 INFO - Writing to file /builds/slave/test/properties/build_url 13:33:09 INFO - Contents: 13:33:09 INFO - build_url:https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:33:10 INFO - Running post-action listener: _resource_record_post_action 13:33:10 INFO - Running post-action listener: set_extra_try_arguments 13:33:10 INFO - ##### 13:33:10 INFO - ##### Running populate-webroot step. 13:33:10 INFO - ##### 13:33:10 INFO - Running pre-action listener: _resource_record_pre_action 13:33:10 INFO - Running main action method: populate_webroot 13:33:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:33:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:33:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:33:10 INFO - '--tppagecycles', 13:33:10 INFO - '7'], 13:33:10 INFO - 'tests': ['tsvgm']}}, 13:33:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:33:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:33:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:33:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:33:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:33:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:33:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['damp', 'tps']}, 13:33:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'tests': ['damp', 'tps']}, 13:33:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['dromaeo_dom']}, 13:33:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:33:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['a11yr', 13:33:10 INFO - 'ts_paint', 13:33:10 INFO - 'tpaint', 13:33:10 INFO - 'sessionrestore', 13:33:10 INFO - 'sessionrestore_no_auto_restore', 13:33:10 INFO - 'tabpaint']}, 13:33:10 INFO - 'other-e10s': {'tests': ['a11yr', 13:33:10 INFO - 'ts_paint', 13:33:10 INFO - 'tpaint', 13:33:10 INFO - 'sessionrestore', 13:33:10 INFO - 'sessionrestore_no_auto_restore', 13:33:10 INFO - 'tabpaint']}, 13:33:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:33:10 INFO - 'ts_paint', 13:33:10 INFO - 'tpaint', 13:33:10 INFO - 'sessionrestore', 13:33:10 INFO - 'sessionrestore_no_auto_restore', 13:33:10 INFO - 'tabpaint']}, 13:33:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:33:10 INFO - 'ts_paint', 13:33:10 INFO - 'tpaint', 13:33:10 INFO - 'sessionrestore', 13:33:10 INFO - 'sessionrestore_no_auto_restore', 13:33:10 INFO - 'tabpaint']}, 13:33:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['a11yr', 13:33:10 INFO - 'ts_paint', 13:33:10 INFO - 'tpaint', 13:33:10 INFO - 'sessionrestore', 13:33:10 INFO - 'sessionrestore_no_auto_restore', 13:33:10 INFO - 'tabpaint']}, 13:33:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['a11yr', 13:33:10 INFO - 'ts_paint', 13:33:10 INFO - 'tpaint', 13:33:10 INFO - 'sessionrestore', 13:33:10 INFO - 'sessionrestore_no_auto_restore', 13:33:10 INFO - 'tabpaint']}, 13:33:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['tsvgx', 13:33:10 INFO - 'tsvgr_opacity', 13:33:10 INFO - 'tart', 13:33:10 INFO - 'tscrollx', 13:33:10 INFO - 'cart']}, 13:33:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:33:10 INFO - 'tsvgr_opacity', 13:33:10 INFO - 'tart', 13:33:10 INFO - 'tscrollx', 13:33:10 INFO - 'cart']}, 13:33:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'talos_options': ['--disable-e10s'], 13:33:10 INFO - 'tests': ['tp5o']}, 13:33:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'tests': ['tp5o']}, 13:33:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'talos_options': ['--disable-e10s', 13:33:10 INFO - '--xperf_path', 13:33:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:33:10 INFO - 'tests': ['tp5n']}, 13:33:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:33:10 INFO - 'talos_options': ['--xperf_path', 13:33:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:33:10 INFO - 'tests': ['tp5n']}}, 13:33:10 INFO - 'talos.zip': {'path': '', 13:33:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:33:10 INFO - Running post-action listener: _resource_record_post_action 13:33:10 INFO - ##### 13:33:10 INFO - ##### Running create-virtualenv step. 13:33:10 INFO - ##### 13:33:10 INFO - Running pre-action listener: _resource_record_pre_action 13:33:10 INFO - Running main action method: create_virtualenv 13:33:10 INFO - Creating virtualenv /builds/slave/test/build/venv 13:33:10 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 13:33:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:33:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:33:10 INFO - Using real prefix '/tools/python27' 13:33:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:33:11 INFO - Installing distribute.............................................................................................................................................................................................done. 13:33:14 INFO - Installing pip.................done. 13:33:14 INFO - Return code: 0 13:33:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:14 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:14 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 13:33:14 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 13:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:14 INFO - 'HOME': '/Users/cltbld', 13:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:14 INFO - 'LOGNAME': 'cltbld', 13:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:14 INFO - 'MOZ_NO_REMOTE': '1', 13:33:14 INFO - 'NO_EM_RESTART': '1', 13:33:14 INFO - 'PAGER': '/bin/cat', 13:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:14 INFO - 'PWD': '/builds/slave/test', 13:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:14 INFO - 'SHELL': '/bin/bash', 13:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:14 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:14 INFO - 'USER': 'cltbld', 13:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:14 INFO - 'XPC_FLAGS': '0x0', 13:33:14 INFO - 'XPC_SERVICE_NAME': '0', 13:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:14 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 13:33:14 INFO - Cleaning up... 13:33:14 INFO - Return code: 0 13:33:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:14 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:14 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 13:33:14 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 13:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:14 INFO - 'HOME': '/Users/cltbld', 13:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:14 INFO - 'LOGNAME': 'cltbld', 13:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:14 INFO - 'MOZ_NO_REMOTE': '1', 13:33:14 INFO - 'NO_EM_RESTART': '1', 13:33:14 INFO - 'PAGER': '/bin/cat', 13:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:14 INFO - 'PWD': '/builds/slave/test', 13:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:14 INFO - 'SHELL': '/bin/bash', 13:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:14 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:14 INFO - 'USER': 'cltbld', 13:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:14 INFO - 'XPC_FLAGS': '0x0', 13:33:14 INFO - 'XPC_SERVICE_NAME': '0', 13:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:15 INFO - Downloading/unpacking psutil>=0.7.1 13:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:33:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:33:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:33:18 INFO - Installing collected packages: psutil 13:33:18 INFO - Running setup.py install for psutil 13:33:18 INFO - building 'psutil._psutil_osx' extension 13:33:18 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 13:33:18 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 13:33:18 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 13:33:18 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 13:33:19 INFO - building 'psutil._psutil_posix' extension 13:33:19 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 13:33:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:33:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:33:19 INFO - ^ 13:33:19 INFO - 1 warning generated. 13:33:19 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 13:33:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:33:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:33:19 INFO - Successfully installed psutil 13:33:19 INFO - Cleaning up... 13:33:19 INFO - Return code: 0 13:33:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:33: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')]} 13:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33: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')]} 13:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33: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', '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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:33: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 mozsystemmonitor==0.0.0 13:33:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:19 INFO - 'HOME': '/Users/cltbld', 13:33:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:19 INFO - 'LOGNAME': 'cltbld', 13:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:19 INFO - 'MOZ_NO_REMOTE': '1', 13:33:19 INFO - 'NO_EM_RESTART': '1', 13:33:19 INFO - 'PAGER': '/bin/cat', 13:33:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:19 INFO - 'PWD': '/builds/slave/test', 13:33:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:19 INFO - 'SHELL': '/bin/bash', 13:33:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:19 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:19 INFO - 'USER': 'cltbld', 13:33:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:19 INFO - 'XPC_FLAGS': '0x0', 13:33:19 INFO - 'XPC_SERVICE_NAME': '0', 13:33:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:33: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 13:33: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 13:33: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 13:33: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 13:33: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 13:33: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 13:33:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:33:22 INFO - Installing collected packages: mozsystemmonitor 13:33:22 INFO - Running setup.py install for mozsystemmonitor 13:33:22 INFO - Successfully installed mozsystemmonitor 13:33:22 INFO - Cleaning up... 13:33:22 INFO - Return code: 0 13:33:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:33: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')]} 13:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33: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')]} 13:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:22 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:22 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 13:33:22 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 13:33:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:22 INFO - 'HOME': '/Users/cltbld', 13:33:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:22 INFO - 'LOGNAME': 'cltbld', 13:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:22 INFO - 'MOZ_NO_REMOTE': '1', 13:33:22 INFO - 'NO_EM_RESTART': '1', 13:33:22 INFO - 'PAGER': '/bin/cat', 13:33:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:22 INFO - 'PWD': '/builds/slave/test', 13:33:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:22 INFO - 'SHELL': '/bin/bash', 13:33:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:22 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:22 INFO - 'USER': 'cltbld', 13:33:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:22 INFO - 'XPC_FLAGS': '0x0', 13:33:22 INFO - 'XPC_SERVICE_NAME': '0', 13:33:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:22 INFO - Downloading/unpacking blobuploader==1.2.4 13:33:22 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 13:33:22 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 13:33:22 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 13:33:22 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 13:33:22 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 13:33:22 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 13:33:25 INFO - Downloading blobuploader-1.2.4.tar.gz 13:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:33:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:33:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:26 INFO - Downloading docopt-0.6.1.tar.gz 13:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:33:26 INFO - Installing collected packages: blobuploader, requests, docopt 13:33:26 INFO - Running setup.py install for blobuploader 13:33:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:33:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:33:26 INFO - Running setup.py install for requests 13:33:26 INFO - Running setup.py install for docopt 13:33:27 INFO - Successfully installed blobuploader requests docopt 13:33:27 INFO - Cleaning up... 13:33:27 INFO - Return code: 0 13:33:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:33: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')]} 13:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33: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')]} 13:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:27 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:27 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 13:33:27 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 13:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:27 INFO - 'HOME': '/Users/cltbld', 13:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:27 INFO - 'LOGNAME': 'cltbld', 13:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:27 INFO - 'MOZ_NO_REMOTE': '1', 13:33:27 INFO - 'NO_EM_RESTART': '1', 13:33:27 INFO - 'PAGER': '/bin/cat', 13:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:27 INFO - 'PWD': '/builds/slave/test', 13:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:27 INFO - 'SHELL': '/bin/bash', 13:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:27 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:27 INFO - 'USER': 'cltbld', 13:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:27 INFO - 'XPC_FLAGS': '0x0', 13:33:27 INFO - 'XPC_SERVICE_NAME': '0', 13:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:33:27 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-yGQ9um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:33:27 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-nPXvPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:33:27 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Wn3y5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:33:27 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-opkvX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:33:27 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-zQ8zSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-TrwcMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Wrk5Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-NdhI1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-lbcb_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-OjVn0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ZtLYs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Gt9bH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-opBPeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-8dOm7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:33:28 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Mv5UPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:33:29 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-XxTOwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:33:29 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-QfcPWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:33:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:33:29 INFO - Running setup.py install for manifestparser 13:33:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:33:29 INFO - Running setup.py install for mozcrash 13:33:29 INFO - Running setup.py install for mozdebug 13:33:29 INFO - Running setup.py install for mozdevice 13:33:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:33:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:33:29 INFO - Running setup.py install for mozfile 13:33:29 INFO - Running setup.py install for mozhttpd 13:33:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Running setup.py install for mozinfo 13:33:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Running setup.py install for mozInstall 13:33:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Running setup.py install for mozleak 13:33:30 INFO - Running setup.py install for mozlog 13:33:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Running setup.py install for moznetwork 13:33:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:33:30 INFO - Running setup.py install for mozprocess 13:33:30 INFO - Running setup.py install for mozprofile 13:33:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:33:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:33:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:33:31 INFO - Running setup.py install for mozrunner 13:33:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:33:31 INFO - Running setup.py install for mozscreenshot 13:33:31 INFO - Running setup.py install for moztest 13:33:31 INFO - Running setup.py install for mozversion 13:33:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:33:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:33:31 INFO - Cleaning up... 13:33:31 INFO - Return code: 0 13:33:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:31 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:31 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 13:33:31 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 13:33:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:31 INFO - 'HOME': '/Users/cltbld', 13:33:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:31 INFO - 'LOGNAME': 'cltbld', 13:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:31 INFO - 'MOZ_NO_REMOTE': '1', 13:33:31 INFO - 'NO_EM_RESTART': '1', 13:33:31 INFO - 'PAGER': '/bin/cat', 13:33:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:31 INFO - 'PWD': '/builds/slave/test', 13:33:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:31 INFO - 'SHELL': '/bin/bash', 13:33:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:31 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:31 INFO - 'USER': 'cltbld', 13:33:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:31 INFO - 'XPC_FLAGS': '0x0', 13:33:31 INFO - 'XPC_SERVICE_NAME': '0', 13:33:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-1mcCF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qEQptn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-BfszOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-mTFmlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-te0Y0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-8O38PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-S6d3O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UBb8L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-GbRIMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:33:32 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)) 13:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:33:32 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Io3aWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-iwlHtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-NlWvgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-WgCQl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-bnfB4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-lcZBBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-P4ZVcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:33:33 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)) 13:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:33:33 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-f7rgHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:33:33 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)) 13:33:33 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)) 13:33:33 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)) 13:33:33 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)) 13:33:33 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)) 13:33:33 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)) 13:33:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:33:33 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 13:33:33 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 13:33:33 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 13:33:33 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 13:33:33 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 13:33:33 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 13:33:36 INFO - Downloading blessings-1.6.tar.gz 13:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:33:36 INFO - Installing collected packages: blessings 13:33:36 INFO - Running setup.py install for blessings 13:33:36 INFO - Successfully installed blessings 13:33:36 INFO - Cleaning up... 13:33:36 INFO - Return code: 0 13:33:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:33:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:33:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:33:36 INFO - Reading from file tmpfile_stdout 13:33:36 INFO - Current package versions: 13:33:36 INFO - blessings == 1.6 13:33:36 INFO - blobuploader == 1.2.4 13:33:36 INFO - docopt == 0.6.1 13:33:36 INFO - manifestparser == 1.1 13:33:36 INFO - mozInstall == 1.12 13:33:36 INFO - mozcrash == 0.17 13:33:36 INFO - mozdebug == 0.1 13:33:36 INFO - mozdevice == 0.48 13:33:36 INFO - mozfile == 1.2 13:33:36 INFO - mozhttpd == 0.7 13:33:36 INFO - mozinfo == 0.9 13:33:36 INFO - mozleak == 0.1 13:33:36 INFO - mozlog == 3.1 13:33:36 INFO - moznetwork == 0.27 13:33:36 INFO - mozprocess == 0.22 13:33:36 INFO - mozprofile == 0.28 13:33:36 INFO - mozrunner == 6.11 13:33:36 INFO - mozscreenshot == 0.1 13:33:36 INFO - mozsystemmonitor == 0.0 13:33:36 INFO - moztest == 0.7 13:33:36 INFO - mozversion == 1.4 13:33:36 INFO - psutil == 3.1.1 13:33:36 INFO - requests == 1.2.3 13:33:36 INFO - wsgiref == 0.1.2 13:33:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:33: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')]} 13:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33: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')]} 13:33:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:36 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:36 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 13:33:36 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 13:33:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:36 INFO - 'HOME': '/Users/cltbld', 13:33:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:36 INFO - 'LOGNAME': 'cltbld', 13:33:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:36 INFO - 'MOZ_NO_REMOTE': '1', 13:33:36 INFO - 'NO_EM_RESTART': '1', 13:33:36 INFO - 'PAGER': '/bin/cat', 13:33:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:36 INFO - 'PWD': '/builds/slave/test', 13:33:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:36 INFO - 'SHELL': '/bin/bash', 13:33:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:36 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:36 INFO - 'USER': 'cltbld', 13:33:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:36 INFO - 'XPC_FLAGS': '0x0', 13:33:36 INFO - 'XPC_SERVICE_NAME': '0', 13:33:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 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)) 13:33:37 INFO - Cleaning up... 13:33:37 INFO - Return code: 0 13:33:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:33:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:37 INFO - 'HOME': '/Users/cltbld', 13:33:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:37 INFO - 'LOGNAME': 'cltbld', 13:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:37 INFO - 'MOZ_NO_REMOTE': '1', 13:33:37 INFO - 'NO_EM_RESTART': '1', 13:33:37 INFO - 'PAGER': '/bin/cat', 13:33:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:37 INFO - 'PWD': '/builds/slave/test', 13:33:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:37 INFO - 'SHELL': '/bin/bash', 13:33:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:37 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:37 INFO - 'USER': 'cltbld', 13:33:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:37 INFO - 'XPC_FLAGS': '0x0', 13:33:37 INFO - 'XPC_SERVICE_NAME': '0', 13:33:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:37 INFO - Downloading/unpacking jsonschema 13:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:33:39 INFO - Downloading/unpacking functools32 (from jsonschema) 13:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:40 INFO - Downloading functools32-3.2.3-2.tar.gz 13:33:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:33:40 INFO - warning: no files found matching '*.txt' 13:33:40 INFO - no previously-included directories found matching 'build' 13:33:40 INFO - no previously-included directories found matching 'dist' 13:33:40 INFO - no previously-included directories found matching '.git*' 13:33:40 INFO - Installing collected packages: jsonschema, functools32 13:33:40 INFO - Running setup.py install for functools32 13:33:40 INFO - warning: no files found matching '*.txt' 13:33:40 INFO - no previously-included directories found matching 'build' 13:33:40 INFO - no previously-included directories found matching 'dist' 13:33:40 INFO - no previously-included directories found matching '.git*' 13:33:40 INFO - Successfully installed jsonschema functools32 13:33:40 INFO - Cleaning up... 13:33:40 INFO - Return code: 0 13:33:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:33: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')]} 13:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33: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')]} 13:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:33:40 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 0x106efea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f0e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111d8f360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, '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.we0hPPdBKb/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.Mlnx1wR10s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 13:33:40 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 13:33:40 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 13:33:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:33:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:33:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:33:40 INFO - 'HOME': '/Users/cltbld', 13:33:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:33:40 INFO - 'LOGNAME': 'cltbld', 13:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:33:40 INFO - 'MOZ_NO_REMOTE': '1', 13:33:40 INFO - 'NO_EM_RESTART': '1', 13:33:40 INFO - 'PAGER': '/bin/cat', 13:33:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:33:40 INFO - 'PWD': '/builds/slave/test', 13:33:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:33:40 INFO - 'SHELL': '/bin/bash', 13:33:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:33:40 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:33:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:33:40 INFO - 'USER': 'cltbld', 13:33:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:33:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:33:40 INFO - 'XPC_FLAGS': '0x0', 13:33:40 INFO - 'XPC_SERVICE_NAME': '0', 13:33:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:33:40 INFO - Downloading/unpacking flake8 13:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:33:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:33:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:33:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:33:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:43 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 13:33:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:33:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:33:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:33:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:33:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:33:44 INFO - no previously-included directories found matching 'docs/_build' 13:33:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:33:44 INFO - Running setup.py install for pep8 13:33:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:33:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:33:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:33:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:33:44 INFO - no previously-included directories found matching 'docs/_build' 13:33:44 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:33:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:33:44 INFO - Cleaning up... 13:33:44 INFO - Return code: 0 13:33:44 INFO - Running post-action listener: _resource_record_post_action 13:33:44 INFO - Running post-action listener: _start_resource_monitoring 13:33:44 INFO - Starting resource monitoring. 13:33:44 INFO - ##### 13:33:44 INFO - ##### Running install step. 13:33:44 INFO - ##### 13:33:44 INFO - Running pre-action listener: _resource_record_pre_action 13:33:44 INFO - Running main action method: install 13:33:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:33:45 INFO - Reading from file tmpfile_stdout 13:33:45 INFO - Detecting whether we're running mozinstall >=1.0... 13:33:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:33:45 INFO - Reading from file tmpfile_stdout 13:33:45 INFO - Output received: 13:33:45 INFO - Usage: mozinstall [options] installer 13:33:45 INFO - Options: 13:33:45 INFO - -h, --help show this help message and exit 13:33:45 INFO - -d DEST, --destination=DEST 13:33:45 INFO - Directory to install application into. [default: 13:33:45 INFO - "/builds/slave/test"] 13:33:45 INFO - --app=APP Application being installed. [default: firefox] 13:33:45 INFO - mkdir: /builds/slave/test/build/application 13:33:45 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'] 13:33:45 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 13:34:16 INFO - Reading from file tmpfile_stdout 13:34:16 INFO - Output received: 13:34:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:34:16 INFO - Running post-action listener: _resource_record_post_action 13:34:16 INFO - ##### 13:34:16 INFO - ##### Running run-tests step. 13:34:16 INFO - ##### 13:34:16 INFO - Running pre-action listener: _resource_record_pre_action 13:34:16 INFO - Running main action method: run_tests 13:34:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:34:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:34:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:34:17 INFO - 2016-04-25 13:34:17.008 screenresolution[1645:7589] starting screenresolution argv=screenresolution get 13:34:17 INFO - 2016-04-25 13:34:17.011 screenresolution[1645:7589] Display 0: 1600x1200x32@60 13:34:17 INFO - 2016-04-25 13:34:17.017 screenresolution[1646:7592] starting screenresolution argv=screenresolution list 13:34:17 INFO - Available Modes on Display 0 13:34:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:34:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:34:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:34:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:34:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:34:17 INFO - Intel Iris: 13:34:17 INFO - Chipset Model: Intel Iris 13:34:17 INFO - Type: GPU 13:34:17 INFO - Bus: Built-In 13:34:17 INFO - VRAM (Dynamic, Max): 1536 MB 13:34:17 INFO - Vendor: Intel (0x8086) 13:34:17 INFO - Device ID: 0x0a2e 13:34:17 INFO - Revision ID: 0x0009 13:34:17 INFO - Displays: 13:34:17 INFO - Display: 13:34:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:34:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:34:17 INFO - Display Serial Number: 1600x1200 60 13:34:17 INFO - Main Display: Yes 13:34:17 INFO - Mirror: Off 13:34:17 INFO - Online: Yes 13:34:17 INFO - Rotation: Supported 13:34:17 INFO - Return code: 0 13:34:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:34:17 INFO - Python 2.7.3 13:34:17 INFO - Return code: 0 13:34:17 INFO - grabbing minidump binary from tooltool 13:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:34:17 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 0x106d9de00>, '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 0x106c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:34:17 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 13:34:17 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 13:34:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:34:17 INFO - Return code: 0 13:34:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:34:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:34:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:34:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:34:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:34:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:34:18 INFO - Return code: 0 13:34:18 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/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 13:34:18 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/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 13:34:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we0hPPdBKb/Render', 13:34:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:18 INFO - 'HOME': '/Users/cltbld', 13:34:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:18 INFO - 'LOGNAME': 'cltbld', 13:34:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:34:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:18 INFO - 'MOZ_NO_REMOTE': '1', 13:34:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:34:18 INFO - 'NO_EM_RESTART': '1', 13:34:18 INFO - 'PAGER': '/bin/cat', 13:34:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:18 INFO - 'PWD': '/builds/slave/test', 13:34:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:34:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:18 INFO - 'SHELL': '/bin/bash', 13:34:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mlnx1wR10s/Listeners', 13:34:18 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 13:34:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:18 INFO - 'USER': 'cltbld', 13:34:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:18 INFO - 'XPC_FLAGS': '0x0', 13:34:18 INFO - 'XPC_SERVICE_NAME': '0', 13:34:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:18 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/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 13:34:18 INFO - mozversion application_buildid: 20160425103006 13:34:18 INFO - mozversion application_changeset: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 13:34:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:34:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:34:18 INFO - mozversion application_name: Firefox 13:34:18 INFO - mozversion application_remotingname: firefox-dev 13:34:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:34:18 INFO - mozversion application_vendor: Mozilla 13:34:18 INFO - mozversion application_version: 48.0a2 13:34:18 INFO - mozversion platform_buildid: 20160425103006 13:34:18 INFO - mozversion platform_changeset: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 13:34:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:34:18 INFO - mozversion platform_version: 48.0a2 13:34:18 INFO - using testdate: 1461616458 13:34:18 INFO - actual date: 1461616458 13:34:18 INFO - starting webserver on 'localhost:49279' 13:34:18 INFO - SUITE-START | Running 2 tests 13:34:18 INFO - TEST-START | dromaeo_css 13:34:18 INFO - operating with platform_type : mac_ 13:34:18 INFO - Initialising browser for dromaeo_css test... 13:34:18 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpA5V2HS/profile http://localhost:49279/getInfo.html) 13:34:21 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 13:34:21 INFO - PROCESS | 1664 | colorDepth:24 13:34:21 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 13:34:21 INFO - PROCESS | 1664 | __metrics 13:34:21 INFO - PROCESS | 1664 | 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" 13:34:21 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:34:21 INFO - TEST-INFO | 1664: exit 0 13:34:21 INFO - Browser initialized. 13:34:21 INFO - Running cycle 1/1 for dromaeo_css test... 13:34:21 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpA5V2HS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:34:33 INFO - PROCESS | 1666 | RSS: Main: 211746816 13:34:33 INFO - PROCESS | 1666 | 13:35:25 INFO - PROCESS | 1666 | 1461616525344 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] 13:37:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 13:37:37 INFO - PROCESS | 1666 | RSS: Main: 311803904 13:37:37 INFO - PROCESS | 1666 | 13:40:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 13:40:40 INFO - PROCESS | 1666 | RSS: Main: 358682624 13:40:40 INFO - PROCESS | 1666 | 13:43:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 13:43:43 INFO - PROCESS | 1666 | RSS: Main: 359714816 13:43:43 INFO - PROCESS | 1666 | 13:46:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 13:46:46 INFO - PROCESS | 1666 | RSS: Main: 363048960 13:46:46 INFO - PROCESS | 1666 | 13:49:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 13:49:50 INFO - PROCESS | 1666 | RSS: Main: 330190848 13:49:50 INFO - PROCESS | 1666 | 13:52:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 13:52:53 INFO - PROCESS | 1666 | RSS: Main: 416837632 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | __start_tp_report 13:52:53 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:52:53 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:52:53 INFO - PROCESS | 1666 | |i|pagename|runs| 13:52:53 INFO - PROCESS | 1666 | |0;dojo.html;3396;3461.3077384523103;3475.16596016956;3483.4774783782414;3528.6062005507824;5669.914951275734;5714;5740.454656807602;5741.913871291934;5745.396733343002;5733.598648094635;5759.452851979066;5766.682832444214;5771.567132465061;5778.942210577893;5076.84769456915;5112.207607820785;5137.7944882204665;5200.791968321262;5207.739613019353;5197.428282888879;5202.271681964528;5205.297284866551;5215.921761173586;5216.113260745682;5136.897262054776;5180.378354597454;5182.948170518303;5190.558802501791;5198.220266959967;5170.224466330062;5240.659357141773;5242;5249.816256431007;5296.947030529684;5019.598432125421;5037.899242015176;5080.263361812516;5135.229715542661;5136.229565565158;4173.979130104361;5593.832185034415;5616.887662246773;5624.943750562483;5634.1830434586755;6130.724117414727;6177.69111544421;6209.472194863442;6224.190855188842;6227.750889964396;5998.1602575639445;6120.418560236771;6121.571489995702;6121.909756669077;6135.84660383494;5859.974504461702;5870.559708021916;5870.73581688825;5871.266091738533;5912.822615321547;6311.211098612674;6318.210223722035;6323.968380158161;6327.620342779448;6328.240611126695;5985.16207730918;6071.150038994544;6075.2405949256345;6093.969530152321;6094.360092190344;6116.66358350295;6153.353897840751;6158.230221222348;6178.7219575119225;6180.81457556274;5998.86021655884;6057.186229763555;6078.149059131725;6084.878302433926;6110.26676798787;5211.557017653466;6006.669633170218;6067.848303792352;6069.393060693984;6112.969435152796;4458.71018383804;4681.859544213679;4868.634852386014;4900.313956046157;4916;4422.027154026109;4428.7121337112985;4457.24226881431;4469.664775141812;4475.753833539186;4762.928556071662;5659.236003139606;5669.688167150764;5677.261955945701;5782.10377391505;6105.053716673834;6160.722767475474;6187.84530386744;6194.349593292639;6228.688565571703;5555.3333599967455;5728.971355143281;5735.340435849847;5745.2243947067445;5748.655080695088;5227.398849132398;5341.412444631087;5349.946500534945;5379.973100134474;5383.273258110184;5664.716764161858;6340.873182536416;6360.332165122595;6361.332060133712;6368.968155159195;6178.165947597017;6201.255849298113;6204.658743768956;6208.658523781055;6220.937790622217;6038.5772995889865;6122.794608433986;6257.78097766576;6332.113504109337;6333.841653958688;6179.5056395489155;6193.473554747897;6209.068639704081;6210.906836397367;6219.315875253809;6081.908771368345;6184.969075154775;6204.069389591597;6205.596636218631;6207.472364849038;5889.411058894248;6037.305709843248;6106;6131.570790044832;6142.723577438936;5577.44225577439;5678.375378708256;6073.696315184311;6084.148219249397;6108.938910610837;5936.910946335895;5949.434803693643;5961.523078153824;5970.283565972111;5984.611000284967;5933.3769954154195;6070.028795392716;6113.415495409546;6152.354002829638;6155.953487907156;5988.700564971647;6000.669963152069;6003.489703375262;6007.068904319837;6009.429104235091 13:52:53 INFO - PROCESS | 1666 | |1;ext.html;485.2769373633286;497.9402471703394;514.8056508899352;532.2893936594646;599.8290487211145;30077.24806879829;37921.620783792154;38836.64071757489;38877;38896;43098;43411;43454;43456.782716086374;43529.78235108828;8492.78768030798;8696.347773916981;9453.574589143487;9696.951515242414;9699.951500242489;11110.722231944184;11390;11392.772144557104;11397.544098236061;11410.144239182053;11325.41444197813;11493.367864767435;11494.195406321562;11494.827577586342;11540.538378464851;8421.073681894955;8624.310055195569;8644.438111522732;8645.956770216171;11236.876312368779;11043.944780276048;11336.94331528337;11340.149488788291;11351.489182986785;11360.943195284053;37563.81218093892;38080;38159;38188.42717359242;38207.80896095529;3144.559761633373;3228.4834426491648;3272.5418441417983;3573.249617580311;3876.9418458723144;27570.448591028067;28124.859375702992;28218;28236.629365616598;28833.85583072092;2734.644496215499;2754.6418965534353;2787.846668433235;2838.8296702197936;2905.375344300964;484418;492097;492898;493243;494412.9999999964;33753.8186163488;34244.82877585596;34313.65686343155;60013.69993150094;60640.39359606348;30690;34909.42907569165;34946.65053349434;35214.647853521135;35357.292854142775;15099.849001510063;15328.310226039855;15340.156291403857;15346;15359.155246461329;11658.242214256215;11824.467898944567;11830.231034982871;11862.466189021343;11872.228305160137;6845;7894.21057894206;7981;8043.517388956681;8067.636956336862;3338.432466480704;3400.8639654413546;3408.727301815849;3416.0947348952545;3452.2922800825772;1007.4156988946394;1016.5679586175846;1033.3231733214757;1085.1769028032822;1106.6845948904524;28568.0001199957;28907.42185156327;29321;29595.704042959296;30128.24679383033;1122.663201039701;1124.2242852431796;1181.5510106159604;1185.4191446191305;1225.822255772908;15147.939644225018;15311.540653780405;15414.691706165813;15541.689166216613;15600.063996160266;684168.579157101;686546;690714;692907;693190;58941.23176304718;60004;60011.69994150088;60294;60339.39660603338;1306.3468265867066;1356.1727346318737;1423.8291405031255;1487.8989547734598;1508.5700575335977;27850.58224126599;28743.568846467737;28871;29325.85337073301;29510.852445737633;45728.31407528956;52524.474755251955;53540;53600;53754.73122634362;592551;594680;604832;605617;607635;32659.510107347935;38298.425523616614;38471.03822404462;39060;39528.640065594744;41342.793286033375;42687.35968960406;43058.56941430546;43512;43758.781206093765;35423;35610;36637.26725465529;37371.43942840914;38131.04672383213;36173.81913090418;36819;36889.44665830069;37399;37702.62297376991;34292.31415371629;34897.30205395928;35137.29725405529;35975.460368095024;36717.816410917774;30662;31885.521717174724;32102.148180622087;32306.030819075466;32431.675683242865;24566.76097380896;24612.450510990806;24862.378440538407;24931.87534062318;25018.749812501643 13:52:53 INFO - PROCESS | 1666 | |2;jquery.html;395.2490268489869;400.3374415342609;400.555383524288;402.8811500607321;411.0914878119356;14840.406383744665;20298.492537686565;20938.26716064938;21085.894570527165;21090.367288981335;16024.35902563896;18256.269749210016;20402;20511.487212819688;20995.790042099576;6037.758489660419;6215.192025036739;6240.843978900519;6266.436020758131;6274.152989346426;6121.112438696387;6163.907541386883;6178.3512731163255;6185.381461853801;6195.7298753755595;6004.249468816398;6174.166487524194;6177.845553861144;6181.350958149397;6202.472789812871;5871.442212989759;6161.568690191689;6196.9690151549175;6220.688965551704;6228;5862.794802181903;5946.167536544887;6086.482648974842;6091.329953705089;6103.176071230371;13913.704238800587;13965.092269002482;19104.949227792466;24950.750492495023;25433.109841155656;2091.372588223542;2196.3301193136085;2297.8046866016407;2675.62541244226;2793.664760228786;8899.955500222522;9317.673881414186;9414.105659962293;10297.691069267934;10396.844047339297;1570.748680211172;1649.4144578674518;1673.6067024249292;1686.123215927723;1752.6582316448298;110641;132417.3653922108;135262;135984.6401535982;138585;9783.853242201372;11462.770744585228;11643.476043578059;11736.237144585575;11837.112216583619;15451.227438628024;15590.610234743996;15602.687946241012;15628.2185890705;15634.296456659478;7339.88990165148;7601.809954751176;7661.885071723928;8785.648574057093;8834.249088827393;8646.524441155796;8697.739067827975;8754.91245087541;8778.60496277669;8799.560021999003;4617.815287388534;4693.624510039189;4702.435707715096;4719.362886010414;4774.761261936889;1935.5257961799168;1982.6133903888892;1986.632472992501;1992.4520756791708;1994.1126198841375;915.6840889892976;944.2257348973912;969.6073090398259;991.6182269826169;993.8409854423257;20137.597248054957;20877.58244835095;20996.745504446833;21181.68227476589;21590.892045539673;979.2459805949379;1008.2639673038576;1014.8629934958686;1031.6595523477235;1040.7242080848582;8863.734087977242;11928.46321915516;12130.332831694164;12149.635510934686;12195.268283902995;637032.8148359229;641190;643965;657406;657838;120444.39777801055;144168.5583144155;146393;148588;151464.70941694744;1065.0733861540355;1071.2875937501474;1119.6249256499002;1152.0898490192653;1152.2222499812592;19210.70008291172;19553.120109595387;19590.902045490253;19648.410547683594;19893;37380.25239495249;44658.77670611756;55073.07244246432;55560.44439555714;57448.42551574598;68483.3151668477;71129.64435177791;71828;72709.45516906882;74990.62504687441;6358.713857876499;6398.968005159944;8371.37214708887;8578.4424012439;8653.091425400495;9100.044495327895;12127.454264558293;12651.746965060831;12819.487220511075;13136.40886160106;10907.400092994643;11078.501467433824;11323.377214253294;11488.368139752394;11553.768924621292;8893.955530222307;11043.282186657842;11192.328460292083;11332.433378331216;11656.18406711522;10159.7460063499;10520.368777873353;11004.504797283982;11631.47658355359;11855.051595872483;8205.507669539847;8328.83342333162;8569.971603407632;8598.61306241233;8809.823803523765;11042.282251653616;11082.833757493483;11187.664370068911;11478.368689722145;11683.474243658886 13:52:53 INFO - PROCESS | 1666 | |3;mootools.html;1428.9928550357247;1568.4667213147527;1599.784029156064;1781.5100847267008;1922.951926201844;6621.238557565875;8547.18801713837;8761.299096072315;8765.298776097912;8788.252998495127;8397.53813540255;8598.398112132154;8609.39734218605;8624.870626940601;8637.827243455127;2149.6882951971957;2404.5311164322966;2423.9636405453934;2467.987660061697;2490.7758301752838;2129.4250552350854;2497.126005897939;2521.1680145552014;2522;2529.291798296471;2437.9634305485433;2461.2616215135386;2477.2939712182033;2482.094035676983;2482.751724827521;2138.6578147496402;2382;2439.658447817298;2458.5697502936914;2464.4331803685254;2437.6831011968507;2450.6936632920883;2472.777450029488;2473.0107956817237;2474.3566672665056;8560;8561.785955351104;8636.0500344962;8650.356432277847;8655.221030107257;5208.140656791625;5386.407495175527;5392.973035134838;5402.78388864445;5436.320459942507;3426.229098452853;3535.045537704831;3540.822958852047;3563.4476656118345;3614.7699420122226;920.3235621817959;1000.7548150703125;1050.1283934334483;1055.456439933435;1085.234909388879;11794.174407791457;11809.704757381134;11824.527018919232;11825.526978920832;11827.290362578273;4716.127516409475;4799.921248215821;4865.95134048662;4888.242322440027;4907.214845624683;9936.254780891462;9944.602215911427;9960;9986.300958932805;9990.350627209353;5244.947550524522;5376.220448035013;5376.247325374451;5384.326959130109;5386.084365657847;4396.450443694538;4596.632269418438;4785.377900872864;4963.553280204799;4965.1807451770055;3985.960140398617;4019.618136277049;4082.8366865325647;4115.218108559364;4149.647279981235;1277.4570807406815;1282.640860559045;1289.471316760124;1302.602706174607;1325.7746183148888;905.1898550797;908.4867050116663;929.5305870535336;942.5334459442482;974.3033730882453;5668.8866222675315;5713.171590119409;5809.2738407699035;5852.912206316909;5869.148973398833;437.4051290245264;445.1364353154869;453.9001419687663;455.80856040463084;469.6524571816899;3847.961050516345;3882.281777871103;3915.569287378386;3920.3923391874305;3987.4816273884207;10208.43853588045;10555.366677999345;10560.049595536439;10612.734681633021;10641.255112142077;10102.444365559964;10560.735981600521;10657;10664.093552048163;10673.83989240162;7822.256885595859;7955.766856137308;8072.233137851895;8115.4319197655595;8182.263596276363;5674.460926212003;5706.514946229617;5722;5832.183494310715;5914.994450943436;8695.739127826175;9604.66383676568;9651.9034809651;9916;9965.302428829913;9553.18797902186;10041.849372259276;10102.141317988053;10103.696889093339;10192.184625230113;6526.902096468461;6731.495137864582;6942.409895158968;7328.267173282629;7434.665440055319;7594.582297973665;7686.116096648957;7689.846203076019;7691.615419228904;8160.8775868360835;7137;7252.4560657949805;7254.927450725425;7391.6304184791625;7421.443391745533;7113.253108423541;7145;7180.7845764627145;7283.980242766123;7327.413806895329;6934.757283495053;6964.094667693167;7019.4735394844565;7059.964700176466;7061.858762824818;4835.44392394865;4908.410990681141;4932.358793356841;4990.72551009698;5106.387233532;4383.824647014211;4934.333864928189;4954.702717836942;4969.229769385751;5203.869903252449 13:52:53 INFO - PROCESS | 1666 | |4;prototype.html;318.6224324175852;319.2736524406974;326.11459886408403;326.20406208850403;330.0823710085959;4972.975135124324;5279.63042587019;5296.867578310539;5318.441563635821;5329.120695085307;4341.674374421923;4687.531246875311;4805.702460335708;4981.576565991895;5291.3650361956525;3492.807895565743;3877.108265099029;3905.2775236581183;4007.5391329996987;4028.3554631259003;3185.4425475541802;3973.96676864016;3980.6417422431973;3994;4046.4334993100842;3787.545494540659;3977.5028121484816;4029.3351596986463;4059.4113853491226;4077.0237726459704;3378.662133786614;3667.6332366763377;4073.9204110910337;4080.8775736727944;4090.1819636072905;2992;3244.416005119101;3413.934569585415;3502.702270307027;3597.568291804974;4799.7520644632295;4897.534734200245;5125.3080834087295;5130.9743451282875;5136;4192.5807419258135;4240.300350442173;4651.255799072166;4682.882927926795;4692.249240121564;5054.721990290533;5526.171074338841;5556.638818476786;5566.192902029183;5624.565735737361;3246.853891574885;3329.7014164475872;3605.6394360563995;3924.9018774530578;3981.7212795104356;4735.715857048588;4936.506349365035;5556.555475561986;5914;5929.466348028655;4011.6389524942897;4026.516817981861;4042.555318914917;4055.918881622351;4061.7156799024374;3461.5288502386384;4032.294348489003;4098.282800509899;4126.628603425646;4149.796558997918;4532.796024178854;4669.39297891272;4670.641326130282;4692.436907571045;4738.218193998021;4531.210171982048;5237.423883372826;5314.840554783363;5533.972330138324;5624.3250809903075;1291.689994401337;1366.6105160029344;1496.3191747754745;1496.4612739413803;1498.625343664084;906.3927168796922;952.6903756279237;976.6435251133286;1001.324106228293;1032.1278519650882;4233.301505251599;5040.991801639613;5073.391193056783;5547.557635014925;5584.162375643686;5025.84922452327;5188.818391356284;5486.862828429242;5700.800471983461;6281.403266689657;5342.358916929993;5542.251796007568;5621.213030175779;5629.579280909166;5637.577181711306;2622.409957759527;3070.831104289241;3236.336551007038;3266.738660907122;3268.8038717677014;4994.425641051325;5705.258316418839;6241.812745617639;6459.709313080923;6467.967660161669;5931.703414829241;6356;6384.968075159594;6400.359964003563;6410.743570257137;5427.538659213932;5508.586855985817;6044.637321760709;6502.154719886479;6510.893148164918;5899.321578018411;6205.410486003822;6207.696383759461;6209.937900620936;6241.531885108726;4884.975575122102;5472.823342981278;5722.656640601577;5913.349531551612;6109.450149486567;4905.337779399735;5144.459831717765;5505.1557728161515;5752.763155921497;5859.677717725566;3636.5816132835052;3834.405667121601;3946.6645335145686;4047.2310261050307;4051.1364772205116;5493.945060549343;5763.279590051244;5770.52851522859;5779.595428319977;5835.270591176103;4731.526847315378;5545.535843645904;5590.468905453975;5597.776088956396;5609.784135266628;4901.607871370353;5556.388797232382;5588.441155884379;5601.367357342043;5677.886442271214;4751.220535986348;5255.3168088148295;5439.374471935778;5471.8084867029465;5493.587980901529;5117.5394214520875;5382.138857782736;5574.191742197439;5576.354975282383;5615.129654903497;4453.643708503377;4711.693739906895;5398.5951053671915;5445.69303367197;5573.219749235192 13:52:53 INFO - PROCESS | 1666 | |5;yui.html;677.1637028270086;689.4243306838789;738.2211766586253;751.9398448124151;752.6537792615396;14223.502177423792;14765;15066.84933150668;15067.773983390258;15137.621559461018;13749.862501374982;13855.445782168701;13921.30393480327;13924.51264205753;13977.371018304175;700.7442283566487;723.6381809095452;857.5240741388527;881.2905610983156;898.6046139698544;879.6965047058786;885.7962668586229;889.4707648948897;890.0209769253834;892.0321451225428;936.5738588942049;940.8259471997668;941.6939494664227;941.8728471656342;942.2556180617338;734.170387462167;911.3301723233451;932.2868005975434;945.2249155692335;948.83869742144;603.3996173807046;705.8482426278323;707.3563057617595;708.989365159523;709.5033476566424;14743.115413075142;14806.481773137995;14809.259537023107;14838;14844.960852740312;12140.939295303468;12181.00749454506;12187.207831491014;12497;12557;9770.316077874551;9912.306138570302;10020.849687254697;10048.09567138961;10148.543315550818;4021.5174179098694;4042.0703238255073;4102.343625019982;4152.83388664453;4160.54234034256;60866;81266.59366703188;81356.59321703413;81451.5927420365;84755.99999999939;9169.40398874071;9475.431474111576;9551.42691438516;9599.992000839811;9754.317197796086;20627.17491300361;20893;20894.895525522275;21062;21141;6359.872802543923;7293.489455738048;7303.379212766974;7318.560886346836;7587.241275872479;4003.53959294679;4040.4343391925154;4068.8575899843363;4093;4223.965128543527;3132.7650426218124;3625.0393645683916;3641.089727568108;3673.2469843682516;3685.631436856293;653.5882394091692;658.7924803686823;676.1311714447011;711.4877288352378;716.029779648579;562.2157090858229;591.3170288317001;592.679952825478;605.6335916770379;611.1108337369117;8202.343812494986;8260.793480163044;8315.875261871077;8406.453580517247;8480.575971201415;589.1616120971787;598.7455331484102;613.947814435769;617.3178637605381;618.6782872906064;2999.8500074996164;3897.5322961244833;3944.546377166605;3979.900502487403;3988.202359528106;102138.97861021294;103196.96803031873;103610;105382;107493;72336;83593;83959.58020209982;85271.72092418619;86681.5665921679;33230;53232;57966;75953;78067;7689.884651730116;7757.573333466714;7965;8005.1594582568;8009.8398032040195;45405.31892021556;46196.538034619225;47295.52704472911;47305.52694473011;48844;60325.79348412919;62525.06212406726;64809;69127;69324;10109.34289271195;10342.689719308433;10442.321249118782;10459.633912813015;10481.318714283547;11066.004059634672;11095.667129986114;11216.607418740632;11220.270682405617;11261.831072533754;10105.898941010495;10836.566537338418;11092.279001864854;11215.898109373968;11460;11144.2199046066;11262.831057534308;11508.42457877086;11593.536258549564;11711.297322160697;10733.570657173937;11351.659450216506;11382.658520244406;11388.544458221579;11619.617978314256;8962.955185224033;9030.322725795722;9407.670731524364;10018.098371146632;10097.697069087939;9138.40600361001;9623.326367154232;9760.804783904425;9794.118529332394;9930.354526955725 13:52:53 INFO - PROCESS | 1666 | __end_tp_report 13:52:53 INFO - PROCESS | 1666 | __start_cc_report 13:52:53 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1256 13:52:53 INFO - PROCESS | 1666 | __end_cc_report 13:52:53 INFO - PROCESS | 1666 | __startTimestamp1461617573144__endTimestamp 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:52:53 INFO - PROCESS | 1666 | Number of tests: 6 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5690.36 Median:5949.43 stddev:635.78 (10.7%) stddev-sans-first:611.72 13:52:53 INFO - PROCESS | 1666 | Values: 3396.0 3461.3 3475.2 3483.5 3528.6 5669.9 5714.0 5740.5 5741.9 5745.4 5733.6 5759.5 5766.7 5771.6 5778.9 5076.8 5112.2 5137.8 5200.8 5207.7 5197.4 5202.3 5205.3 5215.9 5216.1 5136.9 5180.4 5182.9 5190.6 5198.2 5170.2 5240.7 5242.0 5249.8 5296.9 5019.6 5037.9 5080.3 5135.2 5136.2 4174.0 5593.8 5616.9 5624.9 5634.2 6130.7 6177.7 6209.5 6224.2 6227.8 5998.2 6120.4 6121.6 6121.9 6135.8 5860.0 5870.6 5870.7 5871.3 5912.8 6311.2 6318.2 6324.0 6327.6 6328.2 5985.2 6071.2 6075.2 6094.0 6094.4 6116.7 6153.4 6158.2 6178.7 6180.8 5998.9 6057.2 6078.1 6084.9 6110.3 5211.6 6006.7 6067.8 6069.4 6113.0 4458.7 4681.9 4868.6 4900.3 4916.0 4422.0 4428.7 4457.2 4469.7 4475.8 4762.9 5659.2 5669.7 5677.3 5782.1 6105.1 6160.7 6187.8 6194.3 6228.7 5555.3 5729.0 5735.3 5745.2 5748.7 5227.4 5341.4 5349.9 5380.0 5383.3 5664.7 6340.9 6360.3 6361.3 6369.0 6178.2 6201.3 6204.7 6208.7 6220.9 6038.6 6122.8 6257.8 6332.1 6333.8 6179.5 6193.5 6209.1 6210.9 6219.3 6081.9 6185.0 6204.1 6205.6 6207.5 5889.4 6037.3 6106.0 6131.6 6142.7 5577.4 5678.4 6073.7 6084.1 6108.9 5936.9 5949.4 5961.5 5970.3 5984.6 5933.4 6070.0 6113.4 6152.4 6156.0 5988.7 6000.7 6003.5 6007.1 6009.4 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:70553.49 Median:19174.80 stddev:160826.47 (838.7%) stddev-sans-first:161191.58 13:52:53 INFO - PROCESS | 1666 | Values: 485.3 497.9 514.8 532.3 599.8 30077.2 37921.6 38836.6 38877.0 38896.0 43098.0 43411.0 43454.0 43456.8 43529.8 8492.8 8696.3 9453.6 9697.0 9700.0 11110.7 11390.0 11392.8 11397.5 11410.1 11325.4 11493.4 11494.2 11494.8 11540.5 8421.1 8624.3 8644.4 8646.0 11236.9 11043.9 11336.9 11340.1 11351.5 11360.9 37563.8 38080.0 38159.0 38188.4 38207.8 3144.6 3228.5 3272.5 3573.2 3876.9 27570.4 28124.9 28218.0 28236.6 28833.9 2734.6 2754.6 2787.8 2838.8 2905.4 484418.0 492097.0 492898.0 493243.0 494413.0 33753.8 34244.8 34313.7 60013.7 60640.4 30690.0 34909.4 34946.7 35214.6 35357.3 15099.8 15328.3 15340.2 15346.0 15359.2 11658.2 11824.5 11830.2 11862.5 11872.2 6845.0 7894.2 7981.0 8043.5 8067.6 3338.4 3400.9 3408.7 3416.1 3452.3 1007.4 1016.6 1033.3 1085.2 1106.7 28568.0 28907.4 29321.0 29595.7 30128.2 1122.7 1124.2 1181.6 1185.4 1225.8 15147.9 15311.5 15414.7 15541.7 15600.1 684168.6 686546.0 690714.0 692907.0 693190.0 58941.2 60004.0 60011.7 60294.0 60339.4 1306.3 1356.2 1423.8 1487.9 1508.6 27850.6 28743.6 28871.0 29325.9 29510.9 45728.3 52524.5 53540.0 53600.0 53754.7 592551.0 594680.0 604832.0 605617.0 607635.0 32659.5 38298.4 38471.0 39060.0 39528.6 41342.8 42687.4 43058.6 43512.0 43758.8 35423.0 35610.0 36637.3 37371.4 38131.0 36173.8 36819.0 36889.4 37399.0 37702.6 34292.3 34897.3 35137.3 35975.5 36717.8 30662.0 31885.5 32102.1 32306.0 32431.7 24566.8 24612.5 24862.4 24931.9 25018.7 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:36758.10 Median:11893.61 stddev:108265.88 (910.3%) stddev-sans-first:108535.17 13:52:53 INFO - PROCESS | 1666 | Values: 395.2 400.3 400.6 402.9 411.1 14840.4 20298.5 20938.3 21085.9 21090.4 16024.4 18256.3 20402.0 20511.5 20995.8 6037.8 6215.2 6240.8 6266.4 6274.2 6121.1 6163.9 6178.4 6185.4 6195.7 6004.2 6174.2 6177.8 6181.4 6202.5 5871.4 6161.6 6197.0 6220.7 6228.0 5862.8 5946.2 6086.5 6091.3 6103.2 13913.7 13965.1 19104.9 24950.8 25433.1 2091.4 2196.3 2297.8 2675.6 2793.7 8900.0 9317.7 9414.1 10297.7 10396.8 1570.7 1649.4 1673.6 1686.1 1752.7 110641.0 132417.4 135262.0 135984.6 138585.0 9783.9 11462.8 11643.5 11736.2 11837.1 15451.2 15590.6 15602.7 15628.2 15634.3 7339.9 7601.8 7661.9 8785.6 8834.2 8646.5 8697.7 8754.9 8778.6 8799.6 4617.8 4693.6 4702.4 4719.4 4774.8 1935.5 1982.6 1986.6 1992.5 1994.1 915.7 944.2 969.6 991.6 993.8 20137.6 20877.6 20996.7 21181.7 21590.9 979.2 1008.3 1014.9 1031.7 1040.7 8863.7 11928.5 12130.3 12149.6 12195.3 637032.8 641190.0 643965.0 657406.0 657838.0 120444.4 144168.6 146393.0 148588.0 151464.7 1065.1 1071.3 1119.6 1152.1 1152.2 19210.7 19553.1 19590.9 19648.4 19893.0 37380.3 44658.8 55073.1 55560.4 57448.4 68483.3 71129.6 71828.0 72709.5 74990.6 6358.7 6399.0 8371.4 8578.4 8653.1 9100.0 12127.5 12651.7 12819.5 13136.4 10907.4 11078.5 11323.4 11488.4 11553.8 8894.0 11043.3 11192.3 11332.4 11656.2 10159.7 10520.4 11004.5 11631.5 11855.1 8205.5 8328.8 8570.0 8598.6 8809.8 11042.3 11082.8 11187.7 11478.4 11683.5 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5635.50 Median:4944.52 stddev:3141.82 (63.5%) stddev-sans-first:3134.73 13:52:53 INFO - PROCESS | 1666 | Values: 1429.0 1568.5 1599.8 1781.5 1923.0 6621.2 8547.2 8761.3 8765.3 8788.3 8397.5 8598.4 8609.4 8624.9 8637.8 2149.7 2404.5 2424.0 2468.0 2490.8 2129.4 2497.1 2521.2 2522.0 2529.3 2438.0 2461.3 2477.3 2482.1 2482.8 2138.7 2382.0 2439.7 2458.6 2464.4 2437.7 2450.7 2472.8 2473.0 2474.4 8560.0 8561.8 8636.1 8650.4 8655.2 5208.1 5386.4 5393.0 5402.8 5436.3 3426.2 3535.0 3540.8 3563.4 3614.8 920.3 1000.8 1050.1 1055.5 1085.2 11794.2 11809.7 11824.5 11825.5 11827.3 4716.1 4799.9 4866.0 4888.2 4907.2 9936.3 9944.6 9960.0 9986.3 9990.4 5244.9 5376.2 5376.2 5384.3 5386.1 4396.5 4596.6 4785.4 4963.6 4965.2 3986.0 4019.6 4082.8 4115.2 4149.6 1277.5 1282.6 1289.5 1302.6 1325.8 905.2 908.5 929.5 942.5 974.3 5668.9 5713.2 5809.3 5852.9 5869.1 437.4 445.1 453.9 455.8 469.7 3848.0 3882.3 3915.6 3920.4 3987.5 10208.4 10555.4 10560.0 10612.7 10641.3 10102.4 10560.7 10657.0 10664.1 10673.8 7822.3 7955.8 8072.2 8115.4 8182.3 5674.5 5706.5 5722.0 5832.2 5915.0 8695.7 9604.7 9651.9 9916.0 9965.3 9553.2 10041.8 10102.1 10103.7 10192.2 6526.9 6731.5 6942.4 7328.3 7434.7 7594.6 7686.1 7689.8 7691.6 8160.9 7137.0 7252.5 7254.9 7391.6 7421.4 7113.3 7145.0 7180.8 7284.0 7327.4 6934.8 6964.1 7019.5 7060.0 7061.9 4835.4 4908.4 4932.4 4990.7 5106.4 4383.8 4934.3 4954.7 4969.2 5203.9 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4575.89 Median:5010.14 stddev:1418.79 (28.3%) stddev-sans-first:1386.32 13:52:53 INFO - PROCESS | 1666 | Values: 318.6 319.3 326.1 326.2 330.1 4973.0 5279.6 5296.9 5318.4 5329.1 4341.7 4687.5 4805.7 4981.6 5291.4 3492.8 3877.1 3905.3 4007.5 4028.4 3185.4 3974.0 3980.6 3994.0 4046.4 3787.5 3977.5 4029.3 4059.4 4077.0 3378.7 3667.6 4073.9 4080.9 4090.2 2992.0 3244.4 3413.9 3502.7 3597.6 4799.8 4897.5 5125.3 5131.0 5136.0 4192.6 4240.3 4651.3 4682.9 4692.2 5054.7 5526.2 5556.6 5566.2 5624.6 3246.9 3329.7 3605.6 3924.9 3981.7 4735.7 4936.5 5556.6 5914.0 5929.5 4011.6 4026.5 4042.6 4055.9 4061.7 3461.5 4032.3 4098.3 4126.6 4149.8 4532.8 4669.4 4670.6 4692.4 4738.2 4531.2 5237.4 5314.8 5534.0 5624.3 1291.7 1366.6 1496.3 1496.5 1498.6 906.4 952.7 976.6 1001.3 1032.1 4233.3 5041.0 5073.4 5547.6 5584.2 5025.8 5188.8 5486.9 5700.8 6281.4 5342.4 5542.3 5621.2 5629.6 5637.6 2622.4 3070.8 3236.3 3266.7 3268.8 4994.4 5705.3 6241.8 6459.7 6468.0 5931.7 6356.0 6385.0 6400.4 6410.7 5427.5 5508.6 6044.6 6502.2 6510.9 5899.3 6205.4 6207.7 6209.9 6241.5 4885.0 5472.8 5722.7 5913.3 6109.5 4905.3 5144.5 5505.2 5752.8 5859.7 3636.6 3834.4 3946.7 4047.2 4051.1 5493.9 5763.3 5770.5 5779.6 5835.3 4731.5 5545.5 5590.5 5597.8 5609.8 4901.6 5556.4 5588.4 5601.4 5677.9 4751.2 5255.3 5439.4 5471.8 5493.6 5117.5 5382.1 5574.2 5576.4 5615.1 4453.6 4711.7 5398.6 5445.7 5573.2 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18137.57 Median:592.00 stddev:26308.59 (4444.0%) stddev-sans-first:26349.73 13:52:53 INFO - PROCESS | 1666 | Values: 677.2 689.4 738.2 751.9 752.7 14223.5 14765.0 15066.8 15067.8 15137.6 13749.9 13855.4 13921.3 13924.5 13977.4 700.7 723.6 857.5 881.3 898.6 879.7 885.8 889.5 890.0 892.0 936.6 940.8 941.7 941.9 942.3 734.2 911.3 932.3 945.2 948.8 603.4 705.8 707.4 709.0 709.5 14743.1 14806.5 14809.3 14838.0 14845.0 12140.9 12181.0 12187.2 12497.0 12557.0 9770.3 9912.3 10020.8 10048.1 10148.5 4021.5 4042.1 4102.3 4152.8 4160.5 60866.0 81266.6 81356.6 81451.6 84756.0 9169.4 9475.4 9551.4 9600.0 9754.3 20627.2 20893.0 20894.9 21062.0 21141.0 6359.9 7293.5 7303.4 7318.6 7587.2 4003.5 4040.4 4068.9 4093.0 4224.0 3132.8 3625.0 3641.1 3673.2 3685.6 653.6 658.8 676.1 711.5 716.0 562.2 591.3 592.7 605.6 611.1 8202.3 8260.8 8315.9 8406.5 8480.6 589.2 598.7 613.9 617.3 618.7 2999.9 3897.5 3944.5 3979.9 3988.2 102139.0 103197.0 103610.0 105382.0 107493.0 72336.0 83593.0 83959.6 85271.7 86681.6 33230.0 53232.0 57966.0 75953.0 78067.0 7689.9 7757.6 7965.0 8005.2 8009.8 45405.3 46196.5 47295.5 47305.5 48844.0 60325.8 62525.1 64809.0 69127.0 69324.0 10109.3 10342.7 10442.3 10459.6 10481.3 11066.0 11095.7 11216.6 11220.3 11261.8 10105.9 10836.6 11092.3 11215.9 11460.0 11144.2 11262.8 11508.4 11593.5 11711.3 10733.6 11351.7 11382.7 11388.5 11619.6 8963.0 9030.3 9407.7 10018.1 10097.7 9138.4 9623.3 9760.8 9794.1 9930.4 13:52:53 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:52:53 INFO - PROCESS | 1666 | 13:52:53 INFO - TEST-INFO | 1666: exit 0 13:52:53 INFO - TEST-OK | dromaeo_css | took 1115143ms 13:52:53 INFO - TEST-START | kraken 13:52:53 INFO - operating with platform_type : mac_ 13:52:53 INFO - Initialising browser for kraken test... 13:52:53 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmp_3JLXa/profile http://localhost:49279/getInfo.html) 13:52:56 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 13:52:56 INFO - PROCESS | 1673 | colorDepth:24 13:52:56 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 13:52:56 INFO - PROCESS | 1673 | __metrics 13:52:56 INFO - PROCESS | 1673 | 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" 13:52:56 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:52:56 INFO - TEST-INFO | 1673: exit 0 13:52:56 INFO - Browser initialized. 13:52:56 INFO - Running cycle 1/1 for kraken test... 13:52:56 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmp_3JLXa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:53:09 INFO - PROCESS | 1675 | RSS: Main: 211795968 13:53:09 INFO - PROCESS | 1675 | 13:53:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 13:53:12 INFO - PROCESS | 1675 | RSS: Main: 269615104 13:53:12 INFO - PROCESS | 1675 | 13:53:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 13:53:15 INFO - PROCESS | 1675 | RSS: Main: 284319744 13:53:15 INFO - PROCESS | 1675 | 13:53:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 13:53:19 INFO - PROCESS | 1675 | RSS: Main: 372604928 13:53:19 INFO - PROCESS | 1675 | 13:53:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 13:53:22 INFO - PROCESS | 1675 | RSS: Main: 276787200 13:53:22 INFO - PROCESS | 1675 | 13:53:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:53:25 INFO - PROCESS | 1675 | RSS: Main: 277258240 13:53:25 INFO - PROCESS | 1675 | 13:53:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 13:53:28 INFO - PROCESS | 1675 | RSS: Main: 318115840 13:53:28 INFO - PROCESS | 1675 | 13:53:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 13:53:32 INFO - PROCESS | 1675 | RSS: Main: 317362176 13:53:32 INFO - PROCESS | 1675 | 13:53:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 13:53:35 INFO - PROCESS | 1675 | RSS: Main: 315203584 13:53:35 INFO - PROCESS | 1675 | 13:53:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:53:37 INFO - PROCESS | 1675 | RSS: Main: 307466240 13:53:37 INFO - PROCESS | 1675 | 13:53:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:53:40 INFO - PROCESS | 1675 | RSS: Main: 313937920 13:53:40 INFO - PROCESS | 1675 | 13:53:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:53:43 INFO - PROCESS | 1675 | RSS: Main: 316575744 13:53:43 INFO - PROCESS | 1675 | 13:53:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:53:46 INFO - PROCESS | 1675 | RSS: Main: 322154496 13:53:46 INFO - PROCESS | 1675 | 13:53:49 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:53:49 INFO - PROCESS | 1675 | RSS: Main: 319852544 13:53:49 INFO - PROCESS | 1675 | 13:53:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 13:53:52 INFO - PROCESS | 1675 | RSS: Main: 384925696 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | __start_tp_report 13:53:52 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 13:53:52 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 13:53:52 INFO - PROCESS | 1675 | |i|pagename|runs| 13:53:52 INFO - PROCESS | 1675 | |0;ai-astar;113;113;108;115;112;116;114;114;101;115 13:53:52 INFO - PROCESS | 1675 | |1;audio-beat-detection;155;122;145;165;119;141;122;151;158;119 13:53:52 INFO - PROCESS | 1675 | |2;audio-dft;185;184;184;182;182;183;185;186;184;188 13:53:52 INFO - PROCESS | 1675 | |3;audio-fft;69;79;73;147;61;79;83;89;157;72 13:53:52 INFO - PROCESS | 1675 | |4;audio-oscillator;96;94;140;77;83;91;125;80;78;127 13:53:52 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;90;87;89;87;88;82;89;86;87;84 13:53:52 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;97;95;87;96;139;150;110;93;94 13:53:52 INFO - PROCESS | 1675 | |7;imaging-desaturate;86;83;85;86;83;83;85;83;84;85 13:53:52 INFO - PROCESS | 1675 | |8;json-parse-financial;48;62;62;59;62;62;63;80;60;59 13:53:52 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;64;63;82;70;69;68;68;68;69 13:53:52 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;80;80;93;103;101;102;79;80;83;84 13:53:52 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;136;135;137;135;139;147;124;146;188;151 13:53:52 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;166;166;166;170;167;171;164;216;176;163 13:53:52 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;67;69;70;67;68;72;95;92;89 13:53:52 INFO - PROCESS | 1675 | __end_tp_report 13:53:52 INFO - PROCESS | 1675 | __start_cc_report 13:53:52 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2013 13:53:52 INFO - PROCESS | 1675 | __end_cc_report 13:53:52 INFO - PROCESS | 1675 | __startTimestamp1461617632017__endTimestamp 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | ------- Summary: start ------- 13:53:52 INFO - PROCESS | 1675 | Number of tests: 14 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:112.10 Median:114.00 stddev:4.48 (3.9%) stddev-sans-first:4.74 13:53:52 INFO - PROCESS | 1675 | Values: 113.0 113.0 108.0 115.0 112.0 116.0 114.0 114.0 101.0 115.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:148.00 stddev:17.80 (12.0%) stddev-sans-first:17.99 13:53:52 INFO - PROCESS | 1675 | Values: 155.0 122.0 145.0 165.0 119.0 141.0 122.0 151.0 158.0 119.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.50 stddev:1.83 (1.0%) stddev-sans-first:1.92 13:53:52 INFO - PROCESS | 1675 | Values: 185.0 184.0 184.0 182.0 182.0 183.0 185.0 186.0 184.0 188.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:90.90 Median:76.00 stddev:33.19 (43.7%) stddev-sans-first:34.25 13:53:52 INFO - PROCESS | 1675 | Values: 69.0 79.0 73.0 147.0 61.0 79.0 83.0 89.0 157.0 72.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:99.10 Median:81.50 stddev:23.05 (28.3%) stddev-sans-first:24.42 13:53:52 INFO - PROCESS | 1675 | Values: 96.0 94.0 140.0 77.0 83.0 91.0 125.0 80.0 78.0 127.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.50 stddev:2.42 (2.8%) stddev-sans-first:2.30 13:53:52 INFO - PROCESS | 1675 | Values: 90.0 87.0 89.0 87.0 88.0 82.0 89.0 86.0 87.0 84.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:94.00 stddev:21.50 (22.9%) stddev-sans-first:22.39 13:53:52 INFO - PROCESS | 1675 | Values: 94.0 97.0 95.0 87.0 96.0 139.0 150.0 110.0 93.0 94.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.17 13:53:52 INFO - PROCESS | 1675 | Values: 86.0 83.0 85.0 86.0 83.0 83.0 85.0 83.0 84.0 85.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:62.00 stddev:7.76 (12.5%) stddev-sans-first:6.46 13:53:52 INFO - PROCESS | 1675 | Values: 48.0 62.0 62.0 59.0 62.0 62.0 63.0 80.0 60.0 59.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:5.44 (7.9%) stddev-sans-first:5.41 13:53:52 INFO - PROCESS | 1675 | Values: 63.0 64.0 63.0 82.0 70.0 69.0 68.0 68.0 68.0 69.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:80.00 stddev:10.15 (12.7%) stddev-sans-first:10.28 13:53:52 INFO - PROCESS | 1675 | Values: 80.0 80.0 93.0 103.0 101.0 102.0 79.0 80.0 83.0 84.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:142.50 stddev:17.31 (12.1%) stddev-sans-first:18.13 13:53:52 INFO - PROCESS | 1675 | Values: 136.0 135.0 137.0 135.0 139.0 147.0 124.0 146.0 188.0 151.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:168.50 stddev:15.75 (9.3%) stddev-sans-first:16.53 13:53:52 INFO - PROCESS | 1675 | Values: 166.0 166.0 166.0 170.0 167.0 171.0 164.0 216.0 176.0 163.0 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:71.00 stddev:11.51 (16.2%) stddev-sans-first:11.78 13:53:52 INFO - PROCESS | 1675 | Values: 67.0 67.0 69.0 70.0 67.0 68.0 72.0 95.0 92.0 89.0 13:53:52 INFO - PROCESS | 1675 | -------- Summary: end -------- 13:53:52 INFO - PROCESS | 1675 | 13:53:52 INFO - TEST-INFO | 1675: exit 0 13:53:52 INFO - TEST-OK | kraken | took 59026ms 13:53:52 INFO - SUITE-END | took 1174s 13:53:53 INFO - Completed test suite (00:19:34) 13:53:53 INFO - javascript benchmark 13:53:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.0, 3461.3077384523103, 3475.16596016956, 3483.4774783782414, 3528.6062005507824, 5669.914951275734, 5714.0, 5740.454656807602, 5741.913871291934, 5745.396733343002, 5733.598648094635, 5759.452851979066, 5766.682832444214, 5771.567132465061, 5778.942210577893, 5076.84769456915, 5112.207607820785, 5137.7944882204665, 5200.791968321262, 5207.739613019353, 5197.428282888879, 5202.271681964528, 5205.297284866551, 5215.921761173586, 5216.113260745682, 5136.897262054776, 5180.378354597454, 5182.948170518303, 5190.558802501791, 5198.220266959967, 5170.224466330062, 5240.659357141773, 5242.0, 5249.816256431007, 5296.947030529684, 5019.598432125421, 5037.899242015176, 5080.263361812516, 5135.229715542661, 5136.229565565158, 4173.979130104361, 5593.832185034415, 5616.887662246773, 5624.943750562483, 5634.1830434586755, 6130.724117414727, 6177.69111544421, 6209.472194863442, 6224.190855188842, 6227.750889964396, 5998.1602575639445, 6120.418560236771, 6121.571489995702, 6121.909756669077, 6135.84660383494, 5859.974504461702, 5870.559708021916, 5870.73581688825, 5871.266091738533, 5912.822615321547, 6311.211098612674, 6318.210223722035, 6323.968380158161, 6327.620342779448, 6328.240611126695, 5985.16207730918, 6071.150038994544, 6075.2405949256345, 6093.969530152321, 6094.360092190344, 6116.66358350295, 6153.353897840751, 6158.230221222348, 6178.7219575119225, 6180.81457556274, 5998.86021655884, 6057.186229763555, 6078.149059131725, 6084.878302433926, 6110.26676798787, 5211.557017653466, 6006.669633170218, 6067.848303792352, 6069.393060693984, 6112.969435152796, 4458.71018383804, 4681.859544213679, 4868.634852386014, 4900.313956046157, 4916.0, 4422.027154026109, 4428.7121337112985, 4457.24226881431, 4469.664775141812, 4475.753833539186, 4762.928556071662, 5659.236003139606, 5669.688167150764, 5677.261955945701, 5782.10377391505, 6105.053716673834, 6160.722767475474, 6187.84530386744, 6194.349593292639, 6228.688565571703, 5555.3333599967455, 5728.971355143281, 5735.340435849847, 5745.2243947067445, 5748.655080695088, 5227.398849132398, 5341.412444631087, 5349.946500534945, 5379.973100134474, 5383.273258110184, 5664.716764161858, 6340.873182536416, 6360.332165122595, 6361.332060133712, 6368.968155159195, 6178.165947597017, 6201.255849298113, 6204.658743768956, 6208.658523781055, 6220.937790622217, 6038.5772995889865, 6122.794608433986, 6257.78097766576, 6332.113504109337, 6333.841653958688, 6179.5056395489155, 6193.473554747897, 6209.068639704081, 6210.906836397367, 6219.315875253809, 6081.908771368345, 6184.969075154775, 6204.069389591597, 6205.596636218631, 6207.472364849038, 5889.411058894248, 6037.305709843248, 6106.0, 6131.570790044832, 6142.723577438936, 5577.44225577439, 5678.375378708256, 6073.696315184311, 6084.148219249397, 6108.938910610837, 5936.910946335895, 5949.434803693643, 5961.523078153824, 5970.283565972111, 5984.611000284967, 5933.3769954154195, 6070.028795392716, 6113.415495409546, 6152.354002829638, 6155.953487907156, 5988.700564971647, 6000.669963152069, 6003.489703375262, 6007.068904319837, 6009.429104235091], "value": 5651.683355725759, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.2769373633286, 497.9402471703394, 514.8056508899352, 532.2893936594646, 599.8290487211145, 30077.24806879829, 37921.620783792154, 38836.64071757489, 38877.0, 38896.0, 43098.0, 43411.0, 43454.0, 43456.782716086374, 43529.78235108828, 8492.78768030798, 8696.347773916981, 9453.574589143487, 9696.951515242414, 9699.951500242489, 11110.722231944184, 11390.0, 11392.772144557104, 11397.544098236061, 11410.144239182053, 11325.41444197813, 11493.367864767435, 11494.195406321562, 11494.827577586342, 11540.538378464851, 8421.073681894955, 8624.310055195569, 8644.438111522732, 8645.956770216171, 11236.876312368779, 11043.944780276048, 11336.94331528337, 11340.149488788291, 11351.489182986785, 11360.943195284053, 37563.81218093892, 38080.0, 38159.0, 38188.42717359242, 38207.80896095529, 3144.559761633373, 3228.4834426491648, 3272.5418441417983, 3573.249617580311, 3876.9418458723144, 27570.448591028067, 28124.859375702992, 28218.0, 28236.629365616598, 28833.85583072092, 2734.644496215499, 2754.6418965534353, 2787.846668433235, 2838.8296702197936, 2905.375344300964, 484418.0, 492097.0, 492898.0, 493243.0, 494412.9999999964, 33753.8186163488, 34244.82877585596, 34313.65686343155, 60013.69993150094, 60640.39359606348, 30690.0, 34909.42907569165, 34946.65053349434, 35214.647853521135, 35357.292854142775, 15099.849001510063, 15328.310226039855, 15340.156291403857, 15346.0, 15359.155246461329, 11658.242214256215, 11824.467898944567, 11830.231034982871, 11862.466189021343, 11872.228305160137, 6845.0, 7894.21057894206, 7981.0, 8043.517388956681, 8067.636956336862, 3338.432466480704, 3400.8639654413546, 3408.727301815849, 3416.0947348952545, 3452.2922800825772, 1007.4156988946394, 1016.5679586175846, 1033.3231733214757, 1085.1769028032822, 1106.6845948904524, 28568.0001199957, 28907.42185156327, 29321.0, 29595.704042959296, 30128.24679383033, 1122.663201039701, 1124.2242852431796, 1181.5510106159604, 1185.4191446191305, 1225.822255772908, 15147.939644225018, 15311.540653780405, 15414.691706165813, 15541.689166216613, 15600.063996160266, 684168.579157101, 686546.0, 690714.0, 692907.0, 693190.0, 58941.23176304718, 60004.0, 60011.69994150088, 60294.0, 60339.39660603338, 1306.3468265867066, 1356.1727346318737, 1423.8291405031255, 1487.8989547734598, 1508.5700575335977, 27850.58224126599, 28743.568846467737, 28871.0, 29325.85337073301, 29510.852445737633, 45728.31407528956, 52524.474755251955, 53540.0, 53600.0, 53754.73122634362, 592551.0, 594680.0, 604832.0, 605617.0, 607635.0, 32659.510107347935, 38298.425523616614, 38471.03822404462, 39060.0, 39528.640065594744, 41342.793286033375, 42687.35968960406, 43058.56941430546, 43512.0, 43758.781206093765, 35423.0, 35610.0, 36637.26725465529, 37371.43942840914, 38131.04672383213, 36173.81913090418, 36819.0, 36889.44665830069, 37399.0, 37702.62297376991, 34292.31415371629, 34897.30205395928, 35137.29725405529, 35975.460368095024, 36717.816410917774, 30662.0, 31885.521717174724, 32102.148180622087, 32306.030819075466, 32431.675683242865, 24566.76097380896, 24612.450510990806, 24862.378440538407, 24931.87534062318, 25018.749812501643], "value": 18758.463757129368, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2490268489869, 400.3374415342609, 400.555383524288, 402.8811500607321, 411.0914878119356, 14840.406383744665, 20298.492537686565, 20938.26716064938, 21085.894570527165, 21090.367288981335, 16024.35902563896, 18256.269749210016, 20402.0, 20511.487212819688, 20995.790042099576, 6037.758489660419, 6215.192025036739, 6240.843978900519, 6266.436020758131, 6274.152989346426, 6121.112438696387, 6163.907541386883, 6178.3512731163255, 6185.381461853801, 6195.7298753755595, 6004.249468816398, 6174.166487524194, 6177.845553861144, 6181.350958149397, 6202.472789812871, 5871.442212989759, 6161.568690191689, 6196.9690151549175, 6220.688965551704, 6228.0, 5862.794802181903, 5946.167536544887, 6086.482648974842, 6091.329953705089, 6103.176071230371, 13913.704238800587, 13965.092269002482, 19104.949227792466, 24950.750492495023, 25433.109841155656, 2091.372588223542, 2196.3301193136085, 2297.8046866016407, 2675.62541244226, 2793.664760228786, 8899.955500222522, 9317.673881414186, 9414.105659962293, 10297.691069267934, 10396.844047339297, 1570.748680211172, 1649.4144578674518, 1673.6067024249292, 1686.123215927723, 1752.6582316448298, 110641.0, 132417.3653922108, 135262.0, 135984.6401535982, 138585.0, 9783.853242201372, 11462.770744585228, 11643.476043578059, 11736.237144585575, 11837.112216583619, 15451.227438628024, 15590.610234743996, 15602.687946241012, 15628.2185890705, 15634.296456659478, 7339.88990165148, 7601.809954751176, 7661.885071723928, 8785.648574057093, 8834.249088827393, 8646.524441155796, 8697.739067827975, 8754.91245087541, 8778.60496277669, 8799.560021999003, 4617.815287388534, 4693.624510039189, 4702.435707715096, 4719.362886010414, 4774.761261936889, 1935.5257961799168, 1982.6133903888892, 1986.632472992501, 1992.4520756791708, 1994.1126198841375, 915.6840889892976, 944.2257348973912, 969.6073090398259, 991.6182269826169, 993.8409854423257, 20137.597248054957, 20877.58244835095, 20996.745504446833, 21181.68227476589, 21590.892045539673, 979.2459805949379, 1008.2639673038576, 1014.8629934958686, 1031.6595523477235, 1040.7242080848582, 8863.734087977242, 11928.46321915516, 12130.332831694164, 12149.635510934686, 12195.268283902995, 637032.8148359229, 641190.0, 643965.0, 657406.0, 657838.0, 120444.39777801055, 144168.5583144155, 146393.0, 148588.0, 151464.70941694744, 1065.0733861540355, 1071.2875937501474, 1119.6249256499002, 1152.0898490192653, 1152.2222499812592, 19210.70008291172, 19553.120109595387, 19590.902045490253, 19648.410547683594, 19893.0, 37380.25239495249, 44658.77670611756, 55073.07244246432, 55560.44439555714, 57448.42551574598, 68483.3151668477, 71129.64435177791, 71828.0, 72709.45516906882, 74990.62504687441, 6358.713857876499, 6398.968005159944, 8371.37214708887, 8578.4424012439, 8653.091425400495, 9100.044495327895, 12127.454264558293, 12651.746965060831, 12819.487220511075, 13136.40886160106, 10907.400092994643, 11078.501467433824, 11323.377214253294, 11488.368139752394, 11553.768924621292, 8893.955530222307, 11043.282186657842, 11192.328460292083, 11332.433378331216, 11656.18406711522, 10159.7460063499, 10520.368777873353, 11004.504797283982, 11631.47658355359, 11855.051595872483, 8205.507669539847, 8328.83342333162, 8569.971603407632, 8598.61306241233, 8809.823803523765, 11042.282251653616, 11082.833757493483, 11187.664370068911, 11478.368689722145, 11683.474243658886], "value": 9673.48330193707, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.9928550357247, 1568.4667213147527, 1599.784029156064, 1781.5100847267008, 1922.951926201844, 6621.238557565875, 8547.18801713837, 8761.299096072315, 8765.298776097912, 8788.252998495127, 8397.53813540255, 8598.398112132154, 8609.39734218605, 8624.870626940601, 8637.827243455127, 2149.6882951971957, 2404.5311164322966, 2423.9636405453934, 2467.987660061697, 2490.7758301752838, 2129.4250552350854, 2497.126005897939, 2521.1680145552014, 2522.0, 2529.291798296471, 2437.9634305485433, 2461.2616215135386, 2477.2939712182033, 2482.094035676983, 2482.751724827521, 2138.6578147496402, 2382.0, 2439.658447817298, 2458.5697502936914, 2464.4331803685254, 2437.6831011968507, 2450.6936632920883, 2472.777450029488, 2473.0107956817237, 2474.3566672665056, 8560.0, 8561.785955351104, 8636.0500344962, 8650.356432277847, 8655.221030107257, 5208.140656791625, 5386.407495175527, 5392.973035134838, 5402.78388864445, 5436.320459942507, 3426.229098452853, 3535.045537704831, 3540.822958852047, 3563.4476656118345, 3614.7699420122226, 920.3235621817959, 1000.7548150703125, 1050.1283934334483, 1055.456439933435, 1085.234909388879, 11794.174407791457, 11809.704757381134, 11824.527018919232, 11825.526978920832, 11827.290362578273, 4716.127516409475, 4799.921248215821, 4865.95134048662, 4888.242322440027, 4907.214845624683, 9936.254780891462, 9944.602215911427, 9960.0, 9986.300958932805, 9990.350627209353, 5244.947550524522, 5376.220448035013, 5376.247325374451, 5384.326959130109, 5386.084365657847, 4396.450443694538, 4596.632269418438, 4785.377900872864, 4963.553280204799, 4965.1807451770055, 3985.960140398617, 4019.618136277049, 4082.8366865325647, 4115.218108559364, 4149.647279981235, 1277.4570807406815, 1282.640860559045, 1289.471316760124, 1302.602706174607, 1325.7746183148888, 905.1898550797, 908.4867050116663, 929.5305870535336, 942.5334459442482, 974.3033730882453, 5668.8866222675315, 5713.171590119409, 5809.2738407699035, 5852.912206316909, 5869.148973398833, 437.4051290245264, 445.1364353154869, 453.9001419687663, 455.80856040463084, 469.6524571816899, 3847.961050516345, 3882.281777871103, 3915.569287378386, 3920.3923391874305, 3987.4816273884207, 10208.43853588045, 10555.366677999345, 10560.049595536439, 10612.734681633021, 10641.255112142077, 10102.444365559964, 10560.735981600521, 10657.0, 10664.093552048163, 10673.83989240162, 7822.256885595859, 7955.766856137308, 8072.233137851895, 8115.4319197655595, 8182.263596276363, 5674.460926212003, 5706.514946229617, 5722.0, 5832.183494310715, 5914.994450943436, 8695.739127826175, 9604.66383676568, 9651.9034809651, 9916.0, 9965.302428829913, 9553.18797902186, 10041.849372259276, 10102.141317988053, 10103.696889093339, 10192.184625230113, 6526.902096468461, 6731.495137864582, 6942.409895158968, 7328.267173282629, 7434.665440055319, 7594.582297973665, 7686.116096648957, 7689.846203076019, 7691.615419228904, 8160.8775868360835, 7137.0, 7252.4560657949805, 7254.927450725425, 7391.6304184791625, 7421.443391745533, 7113.253108423541, 7145.0, 7180.7845764627145, 7283.980242766123, 7327.413806895329, 6934.757283495053, 6964.094667693167, 7019.4735394844565, 7059.964700176466, 7061.858762824818, 4835.44392394865, 4908.410990681141, 4932.358793356841, 4990.72551009698, 5106.387233532, 4383.824647014211, 4934.333864928189, 4954.702717836942, 4969.229769385751, 5203.869903252449], "value": 4483.790713230985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.6224324175852, 319.2736524406974, 326.11459886408403, 326.20406208850403, 330.0823710085959, 4972.975135124324, 5279.63042587019, 5296.867578310539, 5318.441563635821, 5329.120695085307, 4341.674374421923, 4687.531246875311, 4805.702460335708, 4981.576565991895, 5291.3650361956525, 3492.807895565743, 3877.108265099029, 3905.2775236581183, 4007.5391329996987, 4028.3554631259003, 3185.4425475541802, 3973.96676864016, 3980.6417422431973, 3994.0, 4046.4334993100842, 3787.545494540659, 3977.5028121484816, 4029.3351596986463, 4059.4113853491226, 4077.0237726459704, 3378.662133786614, 3667.6332366763377, 4073.9204110910337, 4080.8775736727944, 4090.1819636072905, 2992.0, 3244.416005119101, 3413.934569585415, 3502.702270307027, 3597.568291804974, 4799.7520644632295, 4897.534734200245, 5125.3080834087295, 5130.9743451282875, 5136.0, 4192.5807419258135, 4240.300350442173, 4651.255799072166, 4682.882927926795, 4692.249240121564, 5054.721990290533, 5526.171074338841, 5556.638818476786, 5566.192902029183, 5624.565735737361, 3246.853891574885, 3329.7014164475872, 3605.6394360563995, 3924.9018774530578, 3981.7212795104356, 4735.715857048588, 4936.506349365035, 5556.555475561986, 5914.0, 5929.466348028655, 4011.6389524942897, 4026.516817981861, 4042.555318914917, 4055.918881622351, 4061.7156799024374, 3461.5288502386384, 4032.294348489003, 4098.282800509899, 4126.628603425646, 4149.796558997918, 4532.796024178854, 4669.39297891272, 4670.641326130282, 4692.436907571045, 4738.218193998021, 4531.210171982048, 5237.423883372826, 5314.840554783363, 5533.972330138324, 5624.3250809903075, 1291.689994401337, 1366.6105160029344, 1496.3191747754745, 1496.4612739413803, 1498.625343664084, 906.3927168796922, 952.6903756279237, 976.6435251133286, 1001.324106228293, 1032.1278519650882, 4233.301505251599, 5040.991801639613, 5073.391193056783, 5547.557635014925, 5584.162375643686, 5025.84922452327, 5188.818391356284, 5486.862828429242, 5700.800471983461, 6281.403266689657, 5342.358916929993, 5542.251796007568, 5621.213030175779, 5629.579280909166, 5637.577181711306, 2622.409957759527, 3070.831104289241, 3236.336551007038, 3266.738660907122, 3268.8038717677014, 4994.425641051325, 5705.258316418839, 6241.812745617639, 6459.709313080923, 6467.967660161669, 5931.703414829241, 6356.0, 6384.968075159594, 6400.359964003563, 6410.743570257137, 5427.538659213932, 5508.586855985817, 6044.637321760709, 6502.154719886479, 6510.893148164918, 5899.321578018411, 6205.410486003822, 6207.696383759461, 6209.937900620936, 6241.531885108726, 4884.975575122102, 5472.823342981278, 5722.656640601577, 5913.349531551612, 6109.450149486567, 4905.337779399735, 5144.459831717765, 5505.1557728161515, 5752.763155921497, 5859.677717725566, 3636.5816132835052, 3834.405667121601, 3946.6645335145686, 4047.2310261050307, 4051.1364772205116, 5493.945060549343, 5763.279590051244, 5770.52851522859, 5779.595428319977, 5835.270591176103, 4731.526847315378, 5545.535843645904, 5590.468905453975, 5597.776088956396, 5609.784135266628, 4901.607871370353, 5556.388797232382, 5588.441155884379, 5601.367357342043, 5677.886442271214, 4751.220535986348, 5255.3168088148295, 5439.374471935778, 5471.8084867029465, 5493.587980901529, 5117.5394214520875, 5382.138857782736, 5574.191742197439, 5576.354975282383, 5615.129654903497, 4453.643708503377, 4711.693739906895, 5398.5951053671915, 5445.69303367197, 5573.219749235192], "value": 4141.99697521175, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.1637028270086, 689.4243306838789, 738.2211766586253, 751.9398448124151, 752.6537792615396, 14223.502177423792, 14765.0, 15066.84933150668, 15067.773983390258, 15137.621559461018, 13749.862501374982, 13855.445782168701, 13921.30393480327, 13924.51264205753, 13977.371018304175, 700.7442283566487, 723.6381809095452, 857.5240741388527, 881.2905610983156, 898.6046139698544, 879.6965047058786, 885.7962668586229, 889.4707648948897, 890.0209769253834, 892.0321451225428, 936.5738588942049, 940.8259471997668, 941.6939494664227, 941.8728471656342, 942.2556180617338, 734.170387462167, 911.3301723233451, 932.2868005975434, 945.2249155692335, 948.83869742144, 603.3996173807046, 705.8482426278323, 707.3563057617595, 708.989365159523, 709.5033476566424, 14743.115413075142, 14806.481773137995, 14809.259537023107, 14838.0, 14844.960852740312, 12140.939295303468, 12181.00749454506, 12187.207831491014, 12497.0, 12557.0, 9770.316077874551, 9912.306138570302, 10020.849687254697, 10048.09567138961, 10148.543315550818, 4021.5174179098694, 4042.0703238255073, 4102.343625019982, 4152.83388664453, 4160.54234034256, 60866.0, 81266.59366703188, 81356.59321703413, 81451.5927420365, 84755.99999999939, 9169.40398874071, 9475.431474111576, 9551.42691438516, 9599.992000839811, 9754.317197796086, 20627.17491300361, 20893.0, 20894.895525522275, 21062.0, 21141.0, 6359.872802543923, 7293.489455738048, 7303.379212766974, 7318.560886346836, 7587.241275872479, 4003.53959294679, 4040.4343391925154, 4068.8575899843363, 4093.0, 4223.965128543527, 3132.7650426218124, 3625.0393645683916, 3641.089727568108, 3673.2469843682516, 3685.631436856293, 653.5882394091692, 658.7924803686823, 676.1311714447011, 711.4877288352378, 716.029779648579, 562.2157090858229, 591.3170288317001, 592.679952825478, 605.6335916770379, 611.1108337369117, 8202.343812494986, 8260.793480163044, 8315.875261871077, 8406.453580517247, 8480.575971201415, 589.1616120971787, 598.7455331484102, 613.947814435769, 617.3178637605381, 618.6782872906064, 2999.8500074996164, 3897.5322961244833, 3944.546377166605, 3979.900502487403, 3988.202359528106, 102138.97861021294, 103196.96803031873, 103610.0, 105382.0, 107493.0, 72336.0, 83593.0, 83959.58020209982, 85271.72092418619, 86681.5665921679, 33230.0, 53232.0, 57966.0, 75953.0, 78067.0, 7689.884651730116, 7757.573333466714, 7965.0, 8005.1594582568, 8009.8398032040195, 45405.31892021556, 46196.538034619225, 47295.52704472911, 47305.52694473011, 48844.0, 60325.79348412919, 62525.06212406726, 64809.0, 69127.0, 69324.0, 10109.34289271195, 10342.689719308433, 10442.321249118782, 10459.633912813015, 10481.318714283547, 11066.004059634672, 11095.667129986114, 11216.607418740632, 11220.270682405617, 11261.831072533754, 10105.898941010495, 10836.566537338418, 11092.279001864854, 11215.898109373968, 11460.0, 11144.2199046066, 11262.831057534308, 11508.42457877086, 11593.536258549564, 11711.297322160697, 10733.570657173937, 11351.659450216506, 11382.658520244406, 11388.544458221579, 11619.617978314256, 8962.955185224033, 9030.322725795722, 9407.670731524364, 10018.098371146632, 10097.697069087939, 9138.40600361001, 9623.326367154232, 9760.804783904425, 9794.118529332394, 9930.354526955725], "value": 6828.00265304079, "unit": "score"}], "name": "dromaeo_css", "value": 7118.008138066389, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 108.0, 115.0, 112.0, 116.0, 114.0, 114.0, 101.0, 115.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 145.0, 165.0, 119.0, 141.0, 122.0, 151.0, 158.0, 119.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 182.0, 182.0, 183.0, 185.0, 186.0, 184.0, 188.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 73.0, 147.0, 61.0, 79.0, 83.0, 89.0, 157.0, 72.0], "value": 90.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 140.0, 77.0, 83.0, 91.0, 125.0, 80.0, 78.0, 127.0], "value": 99.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 88.0, 82.0, 89.0, 86.0, 87.0, 84.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 95.0, 87.0, 96.0, 139.0, 150.0, 110.0, 93.0, 94.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 86.0, 83.0, 83.0, 85.0, 83.0, 84.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 62.0, 62.0, 59.0, 62.0, 62.0, 63.0, 80.0, 60.0, 59.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 82.0, 70.0, 69.0, 68.0, 68.0, 68.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 93.0, 103.0, 101.0, 102.0, 79.0, 80.0, 83.0, 84.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 137.0, 135.0, 139.0, 147.0, 124.0, 146.0, 188.0, 151.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 166.0, 170.0, 167.0, 171.0, 164.0, 216.0, 176.0, 163.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 70.0, 67.0, 68.0, 72.0, 95.0, 92.0, 89.0], "value": 75.6, "unit": "score"}], "name": "kraken", "value": 1513.3, "alertThreshold": 2.0}]} 13:53:53 INFO - Return code: 0 13:53:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:53:53 ERROR - # TBPL SUCCESS # 13:53:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:53:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:53:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:53:53 INFO - 2016-04-25 13:53:53.286 screenresolution[1679:9704] starting screenresolution argv=screenresolution get 13:53:53 INFO - 2016-04-25 13:53:53.289 screenresolution[1679:9704] Display 0: 1600x1200x32@60 13:53:53 INFO - 2016-04-25 13:53:53.295 screenresolution[1680:9707] starting screenresolution argv=screenresolution list 13:53:53 INFO - Available Modes on Display 0 13:53:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:53:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:53:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:53:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:53:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:53:53 INFO - Intel Iris: 13:53:53 INFO - Chipset Model: Intel Iris 13:53:53 INFO - Type: GPU 13:53:53 INFO - Bus: Built-In 13:53:53 INFO - VRAM (Dynamic, Max): 1536 MB 13:53:53 INFO - Vendor: Intel (0x8086) 13:53:53 INFO - Device ID: 0x0a2e 13:53:53 INFO - Revision ID: 0x0009 13:53:53 INFO - Displays: 13:53:53 INFO - Display: 13:53:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:53:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:53:53 INFO - Display Serial Number: 1600x1200 60 13:53:53 INFO - Main Display: Yes 13:53:53 INFO - Mirror: Off 13:53:53 INFO - Online: Yes 13:53:53 INFO - Rotation: Supported 13:53:53 INFO - Return code: 0 13:53:53 INFO - Running post-action listener: _resource_record_post_action 13:53:53 INFO - Running post-run listener: _resource_record_post_run 13:53:54 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37832192; Write bytes: 651623424; Read time: 391; Write time: 2087 13:53:54 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 359899648; Write bytes: 356112384; Read time: 27604; Write time: 736 13:53:54 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30670336; Write bytes: 294495232; Read time: 303; Write time: 1346 13:53:54 INFO - Running post-run listener: _upload_blobber_files 13:53:54 INFO - Blob upload gear active. 13:53:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:53:54 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 13:53:54 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'] 13:53:54 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 13:53:55 INFO - (blobuploader) - INFO - Open directory for files ... 13:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:53:55 INFO - (blobuploader) - INFO - Done attempting. 13:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:53:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:53:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:53:56 INFO - (blobuploader) - INFO - Done attempting. 13:53:56 INFO - (blobuploader) - INFO - Iteration through files over. 13:53:56 INFO - Return code: 0 13:53:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:53:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:53:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b031e848220c060bd8b7523e86ad9506781ebc55a00a8e42052f82bf523eff311137113a244db6c1297e97a71b490858e2b8f1480245c03c50ce98d473124fe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a0611c26aa35aeb8f39cec7b71a29bfffa458f641e9fdf993b0b67a5a6c7510d9609892574c9a2f8857540cfaf3b3958bf09862a5e52d3304ebba21ebb3ed73"} 13:53:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:53:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:53:56 INFO - Contents: 13:53:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b031e848220c060bd8b7523e86ad9506781ebc55a00a8e42052f82bf523eff311137113a244db6c1297e97a71b490858e2b8f1480245c03c50ce98d473124fe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a0611c26aa35aeb8f39cec7b71a29bfffa458f641e9fdf993b0b67a5a6c7510d9609892574c9a2f8857540cfaf3b3958bf09862a5e52d3304ebba21ebb3ed73"} 13:53:56 INFO - Running post-run listener: copy_logs_to_upload_dir 13:53:56 INFO - Copying logs to upload dir... 13:53:56 INFO - mkdir: /builds/slave/test/build/upload/logs 13:53:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.228409 ========= master_lag: 0.69 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-25 13:53:57.374457) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-04-25 13:53:57.375842) ========= 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.we0hPPdBKb/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.Mlnx1wR10s/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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/b031e848220c060bd8b7523e86ad9506781ebc55a00a8e42052f82bf523eff311137113a244db6c1297e97a71b490858e2b8f1480245c03c50ce98d473124fe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a0611c26aa35aeb8f39cec7b71a29bfffa458f641e9fdf993b0b67a5a6c7510d9609892574c9a2f8857540cfaf3b3958bf09862a5e52d3304ebba21ebb3ed73"} build_url:https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/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/b031e848220c060bd8b7523e86ad9506781ebc55a00a8e42052f82bf523eff311137113a244db6c1297e97a71b490858e2b8f1480245c03c50ce98d473124fe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a0611c26aa35aeb8f39cec7b71a29bfffa458f641e9fdf993b0b67a5a6c7510d9609892574c9a2f8857540cfaf3b3958bf09862a5e52d3304ebba21ebb3ed73"}' ========= master_lag: 2.43 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-04-25 13:53:59.813750) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 13:53:59.814126) ========= 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.we0hPPdBKb/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.Mlnx1wR10s/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.004985 ========= master_lag: 2.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 13:54:01.909535) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 13:54:01.909974) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 13:54:01.990931) ========= ========= Total master_lag: 8.40 =========