builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1461612130.64 results: success (0) buildid: 20160425103006 builduid: 1a87fa8d522747ab8b0da4138352f4b4 revision: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.637637) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.638046) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.638328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.665986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.666340) ========= 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.5H7489PY6m/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.MNmm6DEHQp/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:10-- 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% 9.12M=0.001s 2016-04-25 12:22:10 (9.12 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082573 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.766158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.766478) ========= 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.5H7489PY6m/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.MNmm6DEHQp/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.025892 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:10.814618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 12:22:10.814997) ========= 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.5H7489PY6m/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.MNmm6DEHQp/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:10,867 truncating revision to first 12 chars 2016-04-25 12:22:10,867 Setting DEBUG logging. 2016-04-25 12:22:10,867 attempt 1/10 2016-04-25 12:22:10,867 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 12:22:12,353 unpacking tar archive at: mozilla-aurora-0d6a91c76a9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.727099 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 12:22:12.820378) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:12.820699) ========= script_repo_revision: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:12.821106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:12.821376) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 12:22:12.839254) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-25 12:22:12.839644) ========= /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.5H7489PY6m/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.MNmm6DEHQp/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:12 INFO - MultiFileLogger online at 20160425 12:22:12 in /builds/slave/test 12:22:12 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 12:22:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:12 INFO - {'append_to_log': False, 12:22:12 INFO - 'base_work_dir': '/builds/slave/test', 12:22:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:22:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:12 INFO - 'branch': 'Mozilla-Aurora', 12:22:13 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:13 INFO - 'config_files': ('talos/mac_config.py',), 12:22:13 INFO - 'default_actions': ('clobber', 12:22:13 INFO - 'read-buildbot-config', 12:22:13 INFO - 'download-and-extract', 12:22:13 INFO - 'populate-webroot', 12:22:13 INFO - 'create-virtualenv', 12:22:13 INFO - 'install', 12:22:13 INFO - 'run-tests'), 12:22:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:13 INFO - 'download_minidump_stackwalk': True, 12:22:13 INFO - 'download_symbols': 'ondemand', 12:22:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:13 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:13 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:13 INFO - 'installer_path': 'installer.exe', 12:22:13 INFO - 'log_level': 'info', 12:22:13 INFO - 'log_name': 'talos', 12:22:13 INFO - 'log_to_console': True, 12:22:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:22:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:22:13 INFO - 'opt_config_files': (), 12:22:13 INFO - 'pip_index': False, 12:22:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:22:13 INFO - 'cmd': ('bash', 12:22:13 INFO - '-c', 12:22:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:22:13 INFO - 'enabled': True, 12:22:13 INFO - 'halt_on_failure': False, 12:22:13 INFO - 'name': 'check_screen_resolution'},), 12:22:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:22:13 INFO - 'cmd': ('bash', 12:22:13 INFO - '-c', 12:22:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:22:13 INFO - 'enabled': True, 12:22:13 INFO - 'halt_on_failure': False, 12:22:13 INFO - 'name': 'check_screen_resolution'},), 12:22:13 INFO - 'run_cmd_checks_enabled': True, 12:22:13 INFO - 'sps_profile': False, 12:22:13 INFO - 'sps_profile_interval': 0, 12:22:13 INFO - 'suite': 'dromaeojs', 12:22:13 INFO - 'system_bits': '32', 12:22:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:13 INFO - 'title': 't-yosemite-r7-0095', 12:22:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:13 INFO - 'use_talos_json': True, 12:22:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:13 INFO - 'work_dir': 'build'} 12:22:13 INFO - ##### 12:22:13 INFO - ##### Running clobber step. 12:22:13 INFO - ##### 12:22:13 INFO - Running pre-action listener: _resource_record_pre_action 12:22:13 INFO - Running main action method: clobber 12:22:13 INFO - rmtree: /builds/slave/test/build 12:22:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:14 INFO - Running post-action listener: _resource_record_post_action 12:22:14 INFO - ##### 12:22:14 INFO - ##### Running read-buildbot-config step. 12:22:14 INFO - ##### 12:22:14 INFO - Running pre-action listener: _resource_record_pre_action 12:22:14 INFO - Running main action method: read_buildbot_config 12:22:14 INFO - Using buildbot properties: 12:22:14 INFO - { 12:22:14 INFO - "project": "", 12:22:14 INFO - "product": "firefox", 12:22:14 INFO - "script_repo_revision": "production", 12:22:14 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:22:14 INFO - "repository": "", 12:22:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:22:14 INFO - "buildid": "20160425103006", 12:22:14 INFO - "pgo_build": "False", 12:22:14 INFO - "basedir": "/builds/slave/test", 12:22:14 INFO - "buildnumber": 321, 12:22:14 INFO - "slavename": "t-yosemite-r7-0095", 12:22:14 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:22:14 INFO - "platform": "yosemite_r7", 12:22:14 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:22:14 INFO - "branch": "mozilla-aurora", 12:22:14 INFO - "repo_path": "releases/mozilla-aurora", 12:22:14 INFO - "revision": "0d6a91c76a9eddf2219caf028dea130f2d683ec2", 12:22:14 INFO - "stage_platform": "macosx64", 12:22:14 INFO - "builduid": "1a87fa8d522747ab8b0da4138352f4b4", 12:22:14 INFO - "slavebuilddir": "test" 12:22:14 INFO - } 12:22:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:22:14 INFO - Running post-action listener: _resource_record_post_action 12:22:14 INFO - ##### 12:22:14 INFO - ##### Running download-and-extract step. 12:22:14 INFO - ##### 12:22:14 INFO - Running pre-action listener: _resource_record_pre_action 12:22:14 INFO - Running main action method: download_and_extract 12:22:14 INFO - mkdir: /builds/slave/test/build/tests 12:22: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')]} 12:22:14 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 12:22:14 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 12:22:14 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 12:22:14 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 12:22:14 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 12:22:15 INFO - Downloaded 1199 bytes. 12:22:15 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:22:15 INFO - Using the following test package requirements: 12:22:15 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:22:15 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:22:15 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:22:15 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'jsshell-mac.zip'], 12:22:15 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:22:15 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:22:15 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:22:15 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:22:15 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:15 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:22:15 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:22:16 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:22:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:16 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 12:22:16 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 12:22:16 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 12:22:16 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 12:22:16 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 12:22:17 INFO - Downloaded 18109567 bytes. 12:22:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:22:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:22:20 INFO - Return code: 0 12:22:20 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 12:22:20 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')]} 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 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 12:22:28 INFO - Downloaded 18109567 bytes. 12:22:28 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'] 12:22:28 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 12:22:31 INFO - Return code: 0 12:22: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')]} 12:22:31 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 12:22:31 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 12:22:31 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 12:22:31 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 12:22:31 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 12:22:32 INFO - Downloaded 10840145 bytes. 12:22:32 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'] 12:22:32 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 12:22:32 INFO - Return code: 0 12:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:32 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:22:32 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 12:22:32 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 12:22:32 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 12:22:32 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 12:22:35 INFO - Downloaded 114074910 bytes. 12:22:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:22:35 INFO - mkdir: /builds/slave/test/properties 12:22:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:22:35 INFO - Writing to file /builds/slave/test/properties/build_url 12:22:35 INFO - Contents: 12:22:35 INFO - build_url:https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:22:36 INFO - Running post-action listener: _resource_record_post_action 12:22:36 INFO - Running post-action listener: set_extra_try_arguments 12:22:36 INFO - ##### 12:22:36 INFO - ##### Running populate-webroot step. 12:22:36 INFO - ##### 12:22:36 INFO - Running pre-action listener: _resource_record_pre_action 12:22:36 INFO - Running main action method: populate_webroot 12:22:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:22:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:22:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:22:36 INFO - '--tppagecycles', 12:22:36 INFO - '7'], 12:22:36 INFO - 'tests': ['tsvgm']}}, 12:22:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:22:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:22:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:22:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:22:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['damp', 'tps']}, 12:22:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'tests': ['damp', 'tps']}, 12:22:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['dromaeo_dom']}, 12:22:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:22:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['a11yr', 12:22:36 INFO - 'ts_paint', 12:22:36 INFO - 'tpaint', 12:22:36 INFO - 'sessionrestore', 12:22:36 INFO - 'sessionrestore_no_auto_restore', 12:22:36 INFO - 'tabpaint']}, 12:22:36 INFO - 'other-e10s': {'tests': ['a11yr', 12:22:36 INFO - 'ts_paint', 12:22:36 INFO - 'tpaint', 12:22:36 INFO - 'sessionrestore', 12:22:36 INFO - 'sessionrestore_no_auto_restore', 12:22:36 INFO - 'tabpaint']}, 12:22:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:22:36 INFO - 'ts_paint', 12:22:36 INFO - 'tpaint', 12:22:36 INFO - 'sessionrestore', 12:22:36 INFO - 'sessionrestore_no_auto_restore', 12:22:36 INFO - 'tabpaint']}, 12:22:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:22:36 INFO - 'ts_paint', 12:22:36 INFO - 'tpaint', 12:22:36 INFO - 'sessionrestore', 12:22:36 INFO - 'sessionrestore_no_auto_restore', 12:22:36 INFO - 'tabpaint']}, 12:22:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['a11yr', 12:22:36 INFO - 'ts_paint', 12:22:36 INFO - 'tpaint', 12:22:36 INFO - 'sessionrestore', 12:22:36 INFO - 'sessionrestore_no_auto_restore', 12:22:36 INFO - 'tabpaint']}, 12:22:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['a11yr', 12:22:36 INFO - 'ts_paint', 12:22:36 INFO - 'tpaint', 12:22:36 INFO - 'sessionrestore', 12:22:36 INFO - 'sessionrestore_no_auto_restore', 12:22:36 INFO - 'tabpaint']}, 12:22:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['tsvgx', 12:22:36 INFO - 'tsvgr_opacity', 12:22:36 INFO - 'tart', 12:22:36 INFO - 'tscrollx', 12:22:36 INFO - 'cart']}, 12:22:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:22:36 INFO - 'tsvgr_opacity', 12:22:36 INFO - 'tart', 12:22:36 INFO - 'tscrollx', 12:22:36 INFO - 'cart']}, 12:22:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'talos_options': ['--disable-e10s'], 12:22:36 INFO - 'tests': ['tp5o']}, 12:22:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'tests': ['tp5o']}, 12:22:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'talos_options': ['--disable-e10s', 12:22:36 INFO - '--xperf_path', 12:22:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:36 INFO - 'tests': ['tp5n']}, 12:22:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:36 INFO - 'talos_options': ['--xperf_path', 12:22:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:36 INFO - 'tests': ['tp5n']}}, 12:22:36 INFO - 'talos.zip': {'path': '', 12:22:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:22:36 INFO - Running post-action listener: _resource_record_post_action 12:22:36 INFO - ##### 12:22:36 INFO - ##### Running create-virtualenv step. 12:22:36 INFO - ##### 12:22:36 INFO - Running pre-action listener: _resource_record_pre_action 12:22:36 INFO - Running main action method: create_virtualenv 12:22:36 INFO - Creating virtualenv /builds/slave/test/build/venv 12:22:36 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 12:22:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:22:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:22:36 INFO - Using real prefix '/tools/python27' 12:22:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:22:37 INFO - Installing distribute.............................................................................................................................................................................................done. 12:22:40 INFO - Installing pip.................done. 12:22:40 INFO - Return code: 0 12:22:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22: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', 'pip>=1.5'] in /builds/slave/test/build 12:22: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 pip>=1.5 12:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:40 INFO - 'HOME': '/Users/cltbld', 12:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:40 INFO - 'LOGNAME': 'cltbld', 12:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:40 INFO - 'MOZ_NO_REMOTE': '1', 12:22:40 INFO - 'NO_EM_RESTART': '1', 12:22:40 INFO - 'PAGER': '/bin/cat', 12:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:40 INFO - 'PWD': '/builds/slave/test', 12:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:40 INFO - 'SHELL': '/bin/bash', 12:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:22:40 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:40 INFO - 'USER': 'cltbld', 12:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:40 INFO - 'XPC_FLAGS': '0x0', 12:22:40 INFO - 'XPC_SERVICE_NAME': '0', 12:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:40 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 12:22:40 INFO - Cleaning up... 12:22:40 INFO - Return code: 0 12:22:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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', '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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22: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', 'psutil>=0.7.1'] in /builds/slave/test/build 12:22: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 psutil>=0.7.1 12:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:40 INFO - 'HOME': '/Users/cltbld', 12:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:40 INFO - 'LOGNAME': 'cltbld', 12:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:40 INFO - 'MOZ_NO_REMOTE': '1', 12:22:40 INFO - 'NO_EM_RESTART': '1', 12:22:40 INFO - 'PAGER': '/bin/cat', 12:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:40 INFO - 'PWD': '/builds/slave/test', 12:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:40 INFO - 'SHELL': '/bin/bash', 12:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:22:40 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:40 INFO - 'USER': 'cltbld', 12:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:40 INFO - 'XPC_FLAGS': '0x0', 12:22:40 INFO - 'XPC_SERVICE_NAME': '0', 12:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:40 INFO - Downloading/unpacking psutil>=0.7.1 12:22: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 12:22: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 12:22: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 12:22: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 12:22: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 12:22: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 12:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:22:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:43 INFO - Installing collected packages: psutil 12:22:43 INFO - Running setup.py install for psutil 12:22:43 INFO - building 'psutil._psutil_osx' extension 12:22:43 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 12:22:44 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 12:22:44 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 12:22:44 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 12:22:44 INFO - building 'psutil._psutil_posix' extension 12:22:44 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 12:22:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:22:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:22:44 INFO - ^ 12:22:44 INFO - 1 warning generated. 12:22:44 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 12:22:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:44 INFO - Successfully installed psutil 12:22:44 INFO - Cleaning up... 12:22:44 INFO - Return code: 0 12:22:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:22:44 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')]} 12:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:44 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')]} 12:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:44 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:44 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 12:22:44 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 12:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:44 INFO - 'HOME': '/Users/cltbld', 12:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:44 INFO - 'LOGNAME': 'cltbld', 12:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:44 INFO - 'MOZ_NO_REMOTE': '1', 12:22:44 INFO - 'NO_EM_RESTART': '1', 12:22:44 INFO - 'PAGER': '/bin/cat', 12:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:44 INFO - 'PWD': '/builds/slave/test', 12:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:44 INFO - 'SHELL': '/bin/bash', 12:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:22:44 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:44 INFO - 'USER': 'cltbld', 12:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:44 INFO - 'XPC_FLAGS': '0x0', 12:22:44 INFO - 'XPC_SERVICE_NAME': '0', 12:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:22:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:22:47 INFO - Installing collected packages: mozsystemmonitor 12:22:47 INFO - Running setup.py install for mozsystemmonitor 12:22:47 INFO - Successfully installed mozsystemmonitor 12:22:47 INFO - Cleaning up... 12:22:47 INFO - Return code: 0 12:22:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:47 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:47 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 12:22:47 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 12:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:47 INFO - 'HOME': '/Users/cltbld', 12:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:47 INFO - 'LOGNAME': 'cltbld', 12:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:47 INFO - 'MOZ_NO_REMOTE': '1', 12:22:47 INFO - 'NO_EM_RESTART': '1', 12:22:47 INFO - 'PAGER': '/bin/cat', 12:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:47 INFO - 'PWD': '/builds/slave/test', 12:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:47 INFO - 'SHELL': '/bin/bash', 12:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:22:47 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:47 INFO - 'USER': 'cltbld', 12:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:47 INFO - 'XPC_FLAGS': '0x0', 12:22:47 INFO - 'XPC_SERVICE_NAME': '0', 12:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:48 INFO - Downloading/unpacking blobuploader==1.2.4 12:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:50 INFO - Downloading blobuploader-1.2.4.tar.gz 12:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:22:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:22:50 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 12:22:50 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 12:22:50 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 12:22:50 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 12:22:50 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 12:22:50 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 12:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:22:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:22:51 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 12:22:51 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 12:22:51 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 12:22:51 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 12:22:51 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 12:22:51 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 12:22:51 INFO - Downloading docopt-0.6.1.tar.gz 12:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:22:51 INFO - Installing collected packages: blobuploader, requests, docopt 12:22:51 INFO - Running setup.py install for blobuploader 12:22:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:22:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:22:51 INFO - Running setup.py install for requests 12:22:52 INFO - Running setup.py install for docopt 12:22:52 INFO - Successfully installed blobuploader requests docopt 12:22:52 INFO - Cleaning up... 12:22:52 INFO - Return code: 0 12:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:52 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')]} 12:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:52 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')]} 12:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:52 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:52 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 12:22:52 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 12:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:52 INFO - 'HOME': '/Users/cltbld', 12:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:52 INFO - 'LOGNAME': 'cltbld', 12:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:52 INFO - 'MOZ_NO_REMOTE': '1', 12:22:52 INFO - 'NO_EM_RESTART': '1', 12:22:52 INFO - 'PAGER': '/bin/cat', 12:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:52 INFO - 'PWD': '/builds/slave/test', 12:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:52 INFO - 'SHELL': '/bin/bash', 12:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:22:52 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:52 INFO - 'USER': 'cltbld', 12:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:52 INFO - 'XPC_FLAGS': '0x0', 12:22:52 INFO - 'XPC_SERVICE_NAME': '0', 12:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:52 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-OToDLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:52 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-heQwri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QTbTHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-UYc33T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-m3Dosb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-LDo0ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-lixYt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QpdEyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-vxR8iV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-lw_sv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-XsEzJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_MNLY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-IbUYc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-CmzzCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-5cpnOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-pnXDJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-EXJtiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:22:54 INFO - Running setup.py install for manifestparser 12:22:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:22:54 INFO - Running setup.py install for mozcrash 12:22:54 INFO - Running setup.py install for mozdebug 12:22:55 INFO - Running setup.py install for mozdevice 12:22:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Running setup.py install for mozfile 12:22:55 INFO - Running setup.py install for mozhttpd 12:22:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Running setup.py install for mozinfo 12:22:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Running setup.py install for mozInstall 12:22:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Running setup.py install for mozleak 12:22:55 INFO - Running setup.py install for mozlog 12:22:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for moznetwork 12:22:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozprocess 12:22:56 INFO - Running setup.py install for mozprofile 12:22:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozrunner 12:22:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozscreenshot 12:22:56 INFO - Running setup.py install for moztest 12:22:57 INFO - Running setup.py install for mozversion 12:22:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:22:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:22:57 INFO - Cleaning up... 12:22:57 INFO - Return code: 0 12:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:57 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:22:57 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 12:22:57 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 12:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:57 INFO - 'HOME': '/Users/cltbld', 12:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:57 INFO - 'LOGNAME': 'cltbld', 12:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:57 INFO - 'MOZ_NO_REMOTE': '1', 12:22:57 INFO - 'NO_EM_RESTART': '1', 12:22:57 INFO - 'PAGER': '/bin/cat', 12:22:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:57 INFO - 'PWD': '/builds/slave/test', 12:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:57 INFO - 'SHELL': '/bin/bash', 12:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:22:57 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:57 INFO - 'USER': 'cltbld', 12:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:57 INFO - 'XPC_FLAGS': '0x0', 12:22:57 INFO - 'XPC_SERVICE_NAME': '0', 12:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Ik6RvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:57 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)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-K3dAC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:57 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)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8mUzYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:57 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)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-nqwoi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-hCqZtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ofXVVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-V8wpRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-U_rJ3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-SILI8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-cKSmF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Zxt9oz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-wOxCzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rUr6kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ftNO3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:59 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)) 12:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-iG7ync-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:59 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)) 12:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-P9lpAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:59 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)) 12:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8E5TCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:59 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)) 12:22:59 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)) 12:22:59 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)) 12:22:59 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)) 12:22:59 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)) 12:22:59 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)) 12:22:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:59 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 12:22:59 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 12:22:59 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 12:22:59 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 12:22:59 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 12:22:59 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 12:23:01 INFO - Downloading blessings-1.6.tar.gz 12:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:01 INFO - Installing collected packages: blessings 12:23:01 INFO - Running setup.py install for blessings 12:23:02 INFO - Successfully installed blessings 12:23:02 INFO - Cleaning up... 12:23:02 INFO - Return code: 0 12:23:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:02 INFO - Reading from file tmpfile_stdout 12:23:02 INFO - Current package versions: 12:23:02 INFO - blessings == 1.6 12:23:02 INFO - blobuploader == 1.2.4 12:23:02 INFO - docopt == 0.6.1 12:23:02 INFO - manifestparser == 1.1 12:23:02 INFO - mozInstall == 1.12 12:23:02 INFO - mozcrash == 0.17 12:23:02 INFO - mozdebug == 0.1 12:23:02 INFO - mozdevice == 0.48 12:23:02 INFO - mozfile == 1.2 12:23:02 INFO - mozhttpd == 0.7 12:23:02 INFO - mozinfo == 0.9 12:23:02 INFO - mozleak == 0.1 12:23:02 INFO - mozlog == 3.1 12:23:02 INFO - moznetwork == 0.27 12:23:02 INFO - mozprocess == 0.22 12:23:02 INFO - mozprofile == 0.28 12:23:02 INFO - mozrunner == 6.11 12:23:02 INFO - mozscreenshot == 0.1 12:23:02 INFO - mozsystemmonitor == 0.0 12:23:02 INFO - moztest == 0.7 12:23:02 INFO - mozversion == 1.4 12:23:02 INFO - psutil == 3.1.1 12:23:02 INFO - requests == 1.2.3 12:23:02 INFO - wsgiref == 0.1.2 12:23:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:02 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')]} 12:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:02 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')]} 12:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:02 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:23:02 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 12:23:02 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 12:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:02 INFO - 'HOME': '/Users/cltbld', 12:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:02 INFO - 'LOGNAME': 'cltbld', 12:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:02 INFO - 'MOZ_NO_REMOTE': '1', 12:23:02 INFO - 'NO_EM_RESTART': '1', 12:23:02 INFO - 'PAGER': '/bin/cat', 12:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:02 INFO - 'PWD': '/builds/slave/test', 12:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:02 INFO - 'SHELL': '/bin/bash', 12:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:23:02 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:02 INFO - 'USER': 'cltbld', 12:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:02 INFO - 'XPC_FLAGS': '0x0', 12:23:02 INFO - 'XPC_SERVICE_NAME': '0', 12:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 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)) 12:23:02 INFO - Cleaning up... 12:23:02 INFO - Return code: 0 12:23:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:02 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')]} 12:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:02 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')]} 12:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:02 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:23:02 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 12:23:02 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 12:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:02 INFO - 'HOME': '/Users/cltbld', 12:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:02 INFO - 'LOGNAME': 'cltbld', 12:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:02 INFO - 'MOZ_NO_REMOTE': '1', 12:23:02 INFO - 'NO_EM_RESTART': '1', 12:23:02 INFO - 'PAGER': '/bin/cat', 12:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:02 INFO - 'PWD': '/builds/slave/test', 12:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:02 INFO - 'SHELL': '/bin/bash', 12:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:23:02 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:02 INFO - 'USER': 'cltbld', 12:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:02 INFO - 'XPC_FLAGS': '0x0', 12:23:02 INFO - 'XPC_SERVICE_NAME': '0', 12:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:02 INFO - Downloading/unpacking jsonschema 12:23:02 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 12:23:02 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 12:23:02 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 12:23:02 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 12:23:02 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 12:23:02 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 12:23:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:05 INFO - Downloading/unpacking functools32 (from jsonschema) 12:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:05 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:05 INFO - warning: no files found matching '*.txt' 12:23:05 INFO - no previously-included directories found matching 'build' 12:23:05 INFO - no previously-included directories found matching 'dist' 12:23:05 INFO - no previously-included directories found matching '.git*' 12:23:05 INFO - Installing collected packages: jsonschema, functools32 12:23:06 INFO - Running setup.py install for functools32 12:23:06 INFO - warning: no files found matching '*.txt' 12:23:06 INFO - no previously-included directories found matching 'build' 12:23:06 INFO - no previously-included directories found matching 'dist' 12:23:06 INFO - no previously-included directories found matching '.git*' 12:23:06 INFO - Successfully installed jsonschema functools32 12:23:06 INFO - Cleaning up... 12:23:06 INFO - Return code: 0 12:23:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:06 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 0x1065b4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065c4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93bd77f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, '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.5H7489PY6m/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.MNmm6DEHQp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:23:06 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 12:23:06 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 12:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:06 INFO - 'HOME': '/Users/cltbld', 12:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:06 INFO - 'LOGNAME': 'cltbld', 12:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:06 INFO - 'MOZ_NO_REMOTE': '1', 12:23:06 INFO - 'NO_EM_RESTART': '1', 12:23:06 INFO - 'PAGER': '/bin/cat', 12:23:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:06 INFO - 'PWD': '/builds/slave/test', 12:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:06 INFO - 'SHELL': '/bin/bash', 12:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:23:06 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:06 INFO - 'USER': 'cltbld', 12:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:06 INFO - 'XPC_FLAGS': '0x0', 12:23:06 INFO - 'XPC_SERVICE_NAME': '0', 12:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:06 INFO - Downloading/unpacking flake8 12:23:06 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 12:23:06 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 12:23:06 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 12:23:06 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 12:23:06 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 12:23:06 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 12:23:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:23:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:23:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 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 12:23:09 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 12:23:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:23:10 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 12:23:10 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 12:23:10 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 12:23:10 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 12:23:10 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 12:23:10 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 12:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:23:10 INFO - no previously-included directories found matching 'docs/_build' 12:23:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:23:10 INFO - Running setup.py install for pep8 12:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:23:10 INFO - no previously-included directories found matching 'docs/_build' 12:23:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:23:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:23:10 INFO - Cleaning up... 12:23:10 INFO - Return code: 0 12:23:10 INFO - Running post-action listener: _resource_record_post_action 12:23:10 INFO - Running post-action listener: _start_resource_monitoring 12:23:10 INFO - Starting resource monitoring. 12:23:10 INFO - ##### 12:23:10 INFO - ##### Running install step. 12:23:10 INFO - ##### 12:23:10 INFO - Running pre-action listener: _resource_record_pre_action 12:23:10 INFO - Running main action method: install 12:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:11 INFO - Reading from file tmpfile_stdout 12:23:11 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:11 INFO - Reading from file tmpfile_stdout 12:23:11 INFO - Output received: 12:23:11 INFO - Usage: mozinstall [options] installer 12:23:11 INFO - Options: 12:23:11 INFO - -h, --help show this help message and exit 12:23:11 INFO - -d DEST, --destination=DEST 12:23:11 INFO - Directory to install application into. [default: 12:23:11 INFO - "/builds/slave/test"] 12:23:11 INFO - --app=APP Application being installed. [default: firefox] 12:23:11 INFO - mkdir: /builds/slave/test/build/application 12:23:11 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'] 12:23:11 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 12:23:43 INFO - Reading from file tmpfile_stdout 12:23:43 INFO - Output received: 12:23:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:23:43 INFO - Running post-action listener: _resource_record_post_action 12:23:43 INFO - ##### 12:23:43 INFO - ##### Running run-tests step. 12:23:43 INFO - ##### 12:23:43 INFO - Running pre-action listener: _resource_record_pre_action 12:23:43 INFO - Running main action method: run_tests 12:23:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:43 INFO - 2016-04-25 12:23:43.158 screenresolution[1638:7644] starting screenresolution argv=screenresolution get 12:23:43 INFO - 2016-04-25 12:23:43.161 screenresolution[1638:7644] Display 0: 1600x1200x32@60 12:23:43 INFO - 2016-04-25 12:23:43.166 screenresolution[1639:7647] starting screenresolution argv=screenresolution list 12:23:43 INFO - Available Modes on Display 0 12:23:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:43 INFO - Intel Iris: 12:23:43 INFO - Chipset Model: Intel Iris 12:23:43 INFO - Type: GPU 12:23:43 INFO - Bus: Built-In 12:23:43 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:43 INFO - Vendor: Intel (0x8086) 12:23:43 INFO - Device ID: 0x0a2e 12:23:43 INFO - Revision ID: 0x0009 12:23:43 INFO - Displays: 12:23:43 INFO - Display: 12:23:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:43 INFO - Display Serial Number: 1600x1200 60 12:23:43 INFO - Main Display: Yes 12:23:43 INFO - Mirror: Off 12:23:43 INFO - Online: Yes 12:23:43 INFO - Rotation: Supported 12:23:43 INFO - Return code: 0 12:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:43 INFO - Python 2.7.3 12:23:43 INFO - Return code: 0 12:23:43 INFO - grabbing minidump binary from tooltool 12:23: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')]} 12:23:43 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 0x106453e00>, '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 0x1062bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10644eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:43 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 12:23:43 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 12:23:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:43 INFO - Return code: 0 12:23:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:23:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:23:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:23:44 INFO - Return code: 0 12:23:44 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-0095', '--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 12:23:44 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-0095 --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 12:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5H7489PY6m/Render', 12:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:44 INFO - 'HOME': '/Users/cltbld', 12:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:44 INFO - 'LOGNAME': 'cltbld', 12:23:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:44 INFO - 'MOZ_NO_REMOTE': '1', 12:23:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:44 INFO - 'NO_EM_RESTART': '1', 12:23:44 INFO - 'PAGER': '/bin/cat', 12:23:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:44 INFO - 'PWD': '/builds/slave/test', 12:23:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:44 INFO - 'SHELL': '/bin/bash', 12:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MNmm6DEHQp/Listeners', 12:23:44 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:44 INFO - 'USER': 'cltbld', 12:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:44 INFO - 'XPC_FLAGS': '0x0', 12:23:44 INFO - 'XPC_SERVICE_NAME': '0', 12:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:44 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-0095', '--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 12:23:44 INFO - mozversion application_buildid: 20160425103006 12:23:44 INFO - mozversion application_changeset: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 12:23:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:23:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:44 INFO - mozversion application_name: Firefox 12:23:44 INFO - mozversion application_remotingname: firefox-dev 12:23:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:23:44 INFO - mozversion application_vendor: Mozilla 12:23:44 INFO - mozversion application_version: 48.0a2 12:23:44 INFO - mozversion platform_buildid: 20160425103006 12:23:44 INFO - mozversion platform_changeset: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 12:23:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:23:44 INFO - mozversion platform_version: 48.0a2 12:23:44 INFO - using testdate: 1461612224 12:23:44 INFO - actual date: 1461612224 12:23:44 INFO - starting webserver on 'localhost:49270' 12:23:44 INFO - SUITE-START | Running 2 tests 12:23:44 INFO - TEST-START | dromaeo_css 12:23:44 INFO - operating with platform_type : mac_ 12:23:44 INFO - Initialising browser for dromaeo_css test... 12:23:44 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpzVdxNT/profile http://localhost:49270/getInfo.html) 12:23:47 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 12:23:47 INFO - PROCESS | 1657 | colorDepth:24 12:23:47 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 12:23:47 INFO - PROCESS | 1657 | __metrics 12:23:47 INFO - PROCESS | 1657 | 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" 12:23:47 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:23:47 INFO - TEST-INFO | 1657: exit 0 12:23:47 INFO - Browser initialized. 12:23:47 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:47 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpzVdxNT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:59 INFO - PROCESS | 1659 | RSS: Main: 211369984 12:23:59 INFO - PROCESS | 1659 | 12:24:50 INFO - PROCESS | 1659 | 1461612290554 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] 12:27:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 12:27:02 INFO - PROCESS | 1659 | RSS: Main: 337416192 12:27:02 INFO - PROCESS | 1659 | 12:30:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 12:30:06 INFO - PROCESS | 1659 | RSS: Main: 351698944 12:30:06 INFO - PROCESS | 1659 | 12:33:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 12:33:09 INFO - PROCESS | 1659 | RSS: Main: 358113280 12:33:09 INFO - PROCESS | 1659 | 12:36:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 12:36:11 INFO - PROCESS | 1659 | RSS: Main: 356876288 12:36:11 INFO - PROCESS | 1659 | 12:39:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 12:39:15 INFO - PROCESS | 1659 | RSS: Main: 331538432 12:39:15 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 12:42:18 INFO - PROCESS | 1659 | RSS: Main: 367005696 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | __start_tp_report 12:42:18 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 12:42:18 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 12:42:18 INFO - PROCESS | 1659 | |i|pagename|runs| 12:42:18 INFO - PROCESS | 1659 | |0;dojo.html;3385.9661403385962;3456.9135771605693;3461.325041616885;3479.6520347965193;3530.7704999175053;5615.943840561598;5673.631213971094;5680.630759000673;5684.317881854184;5693.8576535586635;5629.043062679344;5701.800436984707;5710.514606258473;5733.082706766918;5735.283089613798;5041.546260836524;5070.8225212117395;5137.152369858969;5148.407933087687;5178.14560597501;5054.595632349422;5144.511271429208;5185.040767458014;5188.974055129719;5208.531232189102;5244.475552444744;5252.6848389096585;5255.710935898524;5256.080185967459;5288.867778305535;5201.375834899798;5217.765200565984;5235.73821308933;5236.1098613235845;5268.894622107575;5002.974985125087;5036.622253330978;5072.061668591323;5144.6655967362;5161.974190129025;5563.554915606742;5587.74855131516;5597.6641401515635;5603.299587551556;5606;6155.630662160284;6228.470580000705;6248.968755156195;6249.156363890862;6250.218722659668;5893.941060589425;6124.724387402578;6163.753449862;6200.503959683259;6295.496360291166;4776.4268287805335;5767.596268261182;5774.97112514439;5788.421157884262;5794.130880367937;6243.125962365272;6246.063090536456;6249.218847644044;6251.343608920998;6269.780557680459;5837.737301821343;6031.517478601701;6056.091586262096;6144.3717414885095;6164.260288765287;6271.874562508724;6277.24673039229;6286.465650419674;6287.025511045796;6287.748490060347;5110;6122.969385153046;6197.5351848611535;6208.975519039398;6219.564630475914;6046.123312119718;6134.069235337315;6136.549713441251;6242.625442473466;6256.843578910563;4871.0014447038275;4882.902341953141;4896.779644915988;4900.460949295574;4922.655414121049;4377.890552736207;4388.495323037828;4391.5388884166705;4399.912001759947;4462.732236065846;5573.554115670737;5644.435556444405;5669.461401166883;5670.262865827416;5709.543236541067;6104.732011601605;6148.893199224182;6163.013917773134;6192.690365481708;6323.936760632335;5643.209950606918;5675.233843431167;5686.402927692616;5714.342850572238;5721.199032135504;4466.665000125004;5330.253764472977;5347.946520534822;5371.57027437804;5401.756920938567;6158.815235542851;6185.165002724664;6207.472364849038;6227.564070515021;6289.7484100635465;6284.120223168668;6292.685365731603;6311.084892690626;6314.873702526015;6322;6197.101420294122;6199.442050215322;6244.406781355764;6269.811905642838;6283.214598175228;5987.101934709828;6118.388161184024;6131.386861313833;6179.443850053337;6185.350538193425;6225.439710426083;6229.50163986889;6245.781397651061;6247;6250.656213908097;4835.637327200404;6081.969590152021;6082.9695851520455;6092.421219984094;6095.116208149705;5767.221425107557;5934.525237980864;6033.064874944391;6084.847878803065;6103.389661034039;5827.358990511125;5903.291605007426;5921;5929.822105336847;5935.614185077956;6091.329953705001;6091.329953705177;6100.481459076028;6115.816525504242;6119.418655227746;5687.516561092188;5799.130130480462;5846.123081537803;5923.141144534105;5944.851378715567 12:42:18 INFO - PROCESS | 1659 | |1;ext.html;513.9280500729899;531.2933798048596;532.1219987021415;547.5209191957039;566.4618612318299;31251.68748312519;39857.601423985754;40984.79507602465;41002.7949860251;41005.71954243661;44570.777146114306;45589.86025349368;45767.313490297674;45781.771091144496;45880.54119458804;8604.569771511431;9649.035096490348;9902.306838521306;9917;9938.950305248463;8692.261157801595;11401.828972565418;11660.300381977066;11667.183297169202;11713.94143029288;11641.01051410631;11800.232984856;11804.055675545937;11807.94096029523;11852.7629447411;8540.188682075193;11377.658670239905;11531.769364612703;11598.76802463946;11654.24247423919;11525.077993760478;11675.182737208399;11677.007454366389;11679.88320116805;11686.298822070617;41116.58883411157;41528;41536.792316038525;41545.79227103845;41605.375919361235;3280.1471617379416;3435.0210190095954;3462.7576069675133;3572.1962558424407;3586.33652774235;28737.994170203943;28961.855190723913;29038.537941994564;29804.403911921858;30214.697853021407;2740.066636829288;2747.175847245818;2749.175247425764;2801.3976994946183;2848.686644469107;360175;360689;361196;361513.9999999974;361594;34181.658183418345;35164;35274;35368.82315588457;65481;35726.92819215428;35764.28471430557;35908.10229744225;35976.46035309473;36045.63954360423;13888.930555347159;16010.119443430724;16226.513204603882;16340.918295408685;16364;12226.694332641755;12428.502859885684;12500;13193.934030329787;13653.726925461435;7020.5787652741;8159.877601835978;8268.131846156188;8274.917250827535;8303.00363956329;3342.565466489341;3414.3171365726953;3420.538227339327;3425.0648383663647;3451.2062225687732;935.8736570563022;939.7838497145694;964.9228061755043;994.3487016004384;1111.9499622517005;25418.110366137098;29500.262493437407;30028.84985575058;30160.698393015788;30683.539746903814;1107.3909349857547;1125.5441546173813;1179.817128345108;1200.2198570928792;1219.1344145656683;13506;15356.07863528192;15658.45195418155;15673.608159796097;15722.828346830918;677941.2205877977;682689;687238.5638071777;689760;693315;62532.37467625357;63324;63436;63447;63591;1342.1812694256496;1486.5020218226798;1507.705997330532;1510.4335874047233;1576.2985471465317;29340.55989160207;29346.853265733534;29611.96358127455;29684;30449;49288.75355623199;49576;50007;50049.74975125101;50184;384820;386808;387115;387389;388903;23300.417489562897;26007.609885852107;26496.735032649427;26815;26867;43007.92480187895;44139.55860441483;44459.777701111285;44950.77524612356;45399.77300113478;36944;37936.81031594824;37972.62027379801;38110.618893811814;38745.4188187183;37036.62963370332;37802.676906308145;37951.81024094862;38465.88437635957;38737.80631096826;37195.25609487741;37894.242115156994;38048.85853424402;38232.80883595564;38526;31855;32903.506447402826;33119.337613248084;33237.833810830794;33254.66745332516;23510.647340290252;23808.52382952366;24176.395590110387;24187.911543980914;24235.51528969446 12:42:18 INFO - PROCESS | 1659 | |2;jquery.html;330.55210190192287;402.8509451502944;406.24906652328445;409.3900088867586;414.3660199894161;17681;19783.901080494576;20074.49813754655;20434.591308173847;20675.896620516913;20431.284905028326;20761.688574671392;21820.78179218211;21889.890550547265;21906.452338691503;5873.353931067569;6178.938210617904;6187.381261873822;6188;6196.81409557714;5876.794312199074;6039.003564411867;6101;6103.81688549342;6144;5917.733701983421;6046.939530604682;6087.360827113156;6091.634501929899;6097.51219902409;6010.429009244137;6070.908936365936;6097.634141951475;6109.938900611026;6110.694465276719;5307.363116426053;5359.571234301286;5377.3009508764;5378.8924221515745;5384.542313903323;26303.605445918325;26657.333566660796;27081;27084.322891927663;27088.729112708817;2559.078731656602;2595.390083330416;2634.8814303356394;2710.675277046972;3073.661897191307;14833.629159271104;14890.404383824634;15122.319495622723;15124.697506049817;15622.453214137564;1693.2888186961507;1736.792928914405;1752.0976697001054;1759.3138675916402;1814.872958892878;118781.81218187793;121437.39281303625;122673.38663306716;122783;123318;10569.471526423647;10713.321669582605;10732.409717465614;11056.613018544313;11641.53433862636;13246.40391182399;14127.85872141286;15037.022593531385;15090.320935557926;15116.924415377853;8967.148120928501;8986.68546600866;9040.231580315616;9042.86435703465;9114.179723824887;9051;9065.864012039825;9080.636774528944;9118.40730352525;9167.404118732262;3955.5253369595257;4645.651576131803;4744.454387745384;4753.263244197155;4789.784459699322;1952.589956109233;2000.789917058717;2008.7187793708893;2023.787502312236;2039.7348344715092;839.9458679357367;844.6156998565638;1031.5203430404897;1036.8133735927606;1053.0781324083578;19714.6199766015;20138.899305503674;20389.694154587694;21549;21668.349949501226;896.6278994217457;945.1824172091092;946.9763255918657;965.5268918230157;973.7516933181993;10936.781264374828;11088.33469991803;11126.38804865724;11162.944185279184;11163.609273675382;597488;622686;639751;654990;658908;98159.01840981499;138117;138633.2273354527;144358;149149;1028.3675539543126;1042.1714736784274;1055.9788804223872;1059.5708737961138;1064.5369264370024;18128.637427251644;18227.817721823143;19004.049797509793;19022.7146592804;19440;53800.73099634477;53806.73096634492;55781;55816.44183558112;56971.14543281937;81699.59150204211;83274;83402.58298708468;83465;83729.58135209528;8175;8322.001359836859;8336.291415229773;8375.497470151811;8384.203500667427;6814.2163651180745;8072.515649061075;12506.624801255977;12527.87472125267;12784.424700888296;10518.474076296001;11008.724781880197;11218.049464393416;11270.323780573191;11333.603323883624;9904.752381190528;10479.58081676755;10555.313904596176;10678.83981740259;10728.678139655824;9561.187299079656;11213.215074944676;11436.485358159069;11562.080119432694;11601.941990289995;8123.268905798506;8128.878066828884;8381.413301068867;8399.412041157304;8487.321014318717;10439.686809395731;11184.328940263611;11460.19778615489;11526.423678816192;11544.884551154717 12:42:18 INFO - PROCESS | 1659 | |3;mootools.html;1359.6396954806976;1418.7020725647612;1581.3832605283942;1727.3263427263369;1892.271475481939;7800.98587183666;8595.91404085959;8814.515201663906;8822.382433229677;8825.51459669718;8434.578271086451;8679.21887030167;8699.608517616707;8699.695510657128;8727.127287271269;2059.907304171308;2413.52936177445;2437.756224377557;2442.0476014354417;2445.4986727720775;1987.6322880267126;2450.1669432392982;2471.789897858684;2474.3690358958443;2483.950320993579;2430.3924018995253;2452.619843924195;2464.0143942423083;2473.369290830836;2476.826622136451;2431.7203521595065;2434.5739495588377;2456.0666946560373;2460.8031357491445;2481.04479775286;2434.91477798278;2453.8036957043396;2462.384403899025;2463.556559819232;2465.1125594785863;8614.009388920293;8686.652533898636;8866.955665221698;8879.245264152554;8892.955535222347;5257.842264732064;5417.51242388183;5420.674759514442;5453.863653408657;5466.535344495723;3121.799131612208;3470.201853573667;3499.195185107414;3595.406757884946;3614.8373323200517;1032.7521394865253;1039.8232300508932;1041.7656027393853;1050.8633877595864;1052.131991107332;11753.412329383582;11893.762124757457;11902.107341949388;11915.880841191563;11945.522179112739;4565.315202719618;4645.9303110453375;4686.179918514246;4720.763961801897;4724.645651576146;9277.443353398818;9288.860667089999;9300.488473134043;9304.302177336727;9311.394759340621;5281.656692314988;5324.893502129936;5378.2470454136455;5395.190721391744;5402.945970540244;4465.866024019285;4632.814687412466;4842.927356089662;4904.950950490521;4994.076095922266;3797;3809.504764380613;3869.1681288523105;3909.042284640281;3928.2339943710704;1120.6664069756964;1218.141210446633;1226.2213494430923;1231.4150778380197;1246.8690787467367;665.1552528289143;771.7298945369076;810.7770363150181;952.1240458777941;964.628617982082;5700.800471983461;5772.134179873053;5946.85132871673;5960.165576819249;6032.276126864805;478.0963978081429;498.6559274100859;499.3284032975642;500.0124753611588;650.2457149706318;3787.1857550626746;3950.288947989332;4020.819063142107;4052.7163098582814;4070.9619047142787;10236.744081397876;10777.191710621735;10828.350298982086;10935.726606834734;10955.233133680566;10799.244052916221;10855.185861060452;10884;10884.455777211107;10908.072813810757;7788.260115289038;8170.714025009096;8289.253967142866;8294.709685160991;8301.8339633207;5673.631213971079;6007.158997740233;6007.339192688888;6056.424639659225;6257.937420625735;9474.431534107976;9611.519424028911;10058.04448577384;10064.547095380873;10081.092701656886;10068.144207742424;10128.240381971234;10195.541200646137;10225.284230103822;10240.48797560134;7313.268673132644;7446.329830315298;7447.478676492594;7448.739294124896;7449.47853650261;8074.434789564674;8341.499510029418;8457.36569757283;8468.915310846813;8482.109378515168;7289.725185390858;7473.551586904803;7506.699732010658;7542.132654744774;7695.153533111241;7351.3751331137455;7373.815654608463;7421.554706717614;7499.5875226863045;7505.024346834876;6956.373926346653;7122.145342558926;7129.215786263403;7148.964255178899;7230.566166030055;4959.876003099807;4986.227134794112;4999.65002449825;5098.668586541863;5189.844304670866;4953.925691114564;5010.073136469765;5083.186690129562;5157.406898206755;5290.312259406252 12:42:18 INFO - PROCESS | 1659 | |4;prototype.html;316.1054216567115;318.6717680788788;323.9012101309101;325.6694455128044;327.5414419812264;4723.952760472394;5352.545033672138;5383.057964856149;5409.513143817056;5410.269613602168;4665.883352916178;4805.303231031498;5312.893742125155;5345.33183352081;5349.598780091489;3943.4479172915817;3958.44581758554;4062.8577999770014;4168.457241082937;4176.2900306947895;3177.8728850845937;4111.999999999985;4135.255653982282;4158.8544400945975;4167.145735124307;3365.5961284645755;4006.679465642756;4077.961019490255;4108.931677763788;4133.751974881502;3820.4842346283176;3882.145927895844;4112.362583799516;4114.958850411487;4130.793460326971;3554.36021516127;3607.981960090209;3613.3676606594004;3619.9276014479565;3629.4192929131214;3988.940165897514;5128.9230661540105;5134.691918484866;5154.278401023859;5175.327207463006;4128.483939507561;4546.249868771649;4658.557437043475;4680.953190468086;4758.072175925696;4874.488178741217;5116.797552575142;5610.69141197234;5619.7471113799975;5645.237892884449;3014.9246268843235;3975.3043217436834;3991.4012898065234;4001.4998125234347;4014.8594799181888;5155.71643559604;5715.657060576337;5810.273715785527;5846.883062338729;5889.676067816311;3534.7274981006817;3953.347697629916;4022.577629348935;4034.959650403517;4043;3433.6738009889023;3887.3391523441082;4136.234796562646;4147.624256409456;4152.314868046798;4512.887177820515;4677.532246775295;4686.695364801277;4719.244920812722;4736.389627526658;4517.480489743656;5595.328560572757;5634.661920284796;5635;5644.661320320712;1320.9933950330378;1414.6251243420502;1496.1995332497063;1501.6095815088156;1504.5411149599477;786.3944762532932;842.928351090152;975.0834215837232;1005.5726316315537;1019.4698756646502;4408;5497.422770609109;5515.972420137874;5520.944790552124;5529.281193444826;4789.664723469394;5367.5169234769055;5488.204210389471;5494.8076817312;5513.807016754475;4400.44994375703;4770.809167633256;5212.895742085213;5216.556592689664;5279.841604751864;3147;3268.8202148881564;3338.2322065924973;3343.5151902973935;3355.3660376604735;6031.215941927521;6410;6575.342465753482;6586.110875031905;6655.6339401332425;5790.942090579125;6201.317855035943;6459.257185423642;6551.672416379162;6575.671216439159;5353.437889021597;6081.391860813883;6594.967025164843;6617.981515637776;6639.634820084845;6045.1864440668505;6314.936850631619;6330.145430367026;6362.6818659067785;6367.363263673596;5958.433948774859;6253.968730156502;6274.529410294337;6300.7479700811455;6320.209973753281;5632.493075623213;5816.970915145566;6130.570860039755;6541.934580654323;6615.6030638161865;3027.833469159217;3941.9605803941595;3972.2849887020616;3997.5402828675074;4013.799310034545;4957.454679985127;5432.728363581884;5838.4453476919625;5854.531637469077;5871.7651293949475;5479.369872464717;5723.65658060501;5759.769609215585;5767.394423585463;5926.970365148147;5196.999155325303;5662.915056274242;5666.631668941505;5691.857703557444;5699.829005129853;5341.359036915595;5359.705216213146;5526.281583394133;5563.360213575329;5569.248151499496;4734.881627959328;5174.9482505174465;5249.632525723163;5575.944240557543;5668.773249069991;5081.7713202905215;5115.56517696;5477.233187353694;5546.944530554642;5607.8037268697035 12:42:18 INFO - PROCESS | 1659 | |5;yui.html;633.3666333666333;737.5169264131994;739.2755100001995;754.8406640611421;767.1867820110683;14735.934511362388;15331.386744530218;15516.534503964871;15535.844641553582;15553.300101495446;14161;14196.574102776933;14304.141751494892;14313.856861431383;14314.427422903098;887.3699673232013;923.229103698411;930.6556574067605;935.9719208423725;940.3981451870808;930.5765876526198;940.4404379394249;940.7083804020775;941.3128416256103;941.924646028316;988.1403179234077;989.3618615992681;992.1963209800085;994.3536701144242;994.9204063674889;981.1562056631292;984.3355734878928;984.6061575369873;984.9950750246239;989.4162444157981;721.9314165154302;722.3823630795699;724.022569531134;726.8691635505606;732.0264048815063;15213.163276019815;15216.163111028889;15249.542513724495;15252.618684532865;15292.311845966848;12131.454084566216;12315.945098195942;12320.630381088582;12363.62909112719;12450.439730212162;9982.450965196911;10001.449920254383;10013.49932503372;10570.048695617354;10626.14990800734;3400.336934297813;3421.469672200813;3457.3258214648154;3461.9653803461897;3501.352249833789;52669.73665131688;72230.916536252;73073;73960.6301968492;74828.62585687036;8707.695230666895;8789.813375194268;8816.823663526693;8892.3330750194;9050.592723327467;20292.492687682632;20295.898520507304;20341.796582034287;20506.179752810018;20683.689744653842;7533.886991695129;7564.394848412114;7606.619669016527;7646.617669116522;7956.562389068657;4049.817758200888;4060.553339132693;4149.398337241144;4205.642520385741;4416.646668266531;3492.353914525821;3844.3464611016193;4052.2705912935367;4053.2096241233266;4062.776547289868;585.8857522783102;591.340655169483;727.752564128199;736.9336759691654;737.3437640499959;508.12602324002654;508.48896858656815;524.4237264700687;545.9372172200168;571.5085026876883;6912.930870691229;8279.296259817984;8334.833303333899;8394.496330220207;8476;505.74965392130395;536.5305357811915;594.1355327997753;595.2945759275273;604.2567641800609;3942.9802850986134;3986.820593073319;4049.6152865477734;4056.472658554369;4065.9390109148385;92966.7736548979;97665.5350169748;97937;99160;99995.50006749904;83192;85087.87280318042;85531.57234213913;86017.56991215004;86307.56846215854;62334;63639.36360636427;64113.35886641167;64731;69036.999999999;8125.79685507867;8132;8237.46456480327;8321.95839020801;8356.331493480386;43159.78420108005;43304;43684.34473482964;45292;45697.54302456933;59950.10074848794;64033.679831600544;64088.3591164101;64295;65207;10286.742831429274;10519.78960420772;10690.518926648165;11141.498632561716;11231.775364492827;11433.542658293907;11966.64100076999;12106.152569320064;12286.078544109048;13139.34303284851;11330.60342887995;11346.319220846775;11513.963743263146;11807;12052.0960927929;8940.240079593308;9253.67612133572;11310.377929213972;11570.826437603275;11722;11247.156463265124;11265.436728163722;11573.537058517564;11635.941820290844;11644.126690498077;9483.905160948578;9606.66376676813;9833.754156146153;9947.502624868583;10147.137493313152;9725.270604704534;9732.464714440774;9792.216622670312;9815.067568580973;9980.550875210778 12:42:18 INFO - PROCESS | 1659 | __end_tp_report 12:42:18 INFO - PROCESS | 1659 | __start_cc_report 12:42:18 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1176 12:42:18 INFO - PROCESS | 1659 | __end_cc_report 12:42:18 INFO - PROCESS | 1659 | __startTimestamp1461613338537__endTimestamp 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | ------- Summary: start ------- 12:42:18 INFO - PROCESS | 1659 | Number of tests: 6 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5688.76 Median:5846.12 stddev:637.94 (10.9%) stddev-sans-first:613.78 12:42:18 INFO - PROCESS | 1659 | Values: 3386.0 3456.9 3461.3 3479.7 3530.8 5615.9 5673.6 5680.6 5684.3 5693.9 5629.0 5701.8 5710.5 5733.1 5735.3 5041.5 5070.8 5137.2 5148.4 5178.1 5054.6 5144.5 5185.0 5189.0 5208.5 5244.5 5252.7 5255.7 5256.1 5288.9 5201.4 5217.8 5235.7 5236.1 5268.9 5003.0 5036.6 5072.1 5144.7 5162.0 5563.6 5587.7 5597.7 5603.3 5606.0 6155.6 6228.5 6249.0 6249.2 6250.2 5893.9 6124.7 6163.8 6200.5 6295.5 4776.4 5767.6 5775.0 5788.4 5794.1 6243.1 6246.1 6249.2 6251.3 6269.8 5837.7 6031.5 6056.1 6144.4 6164.3 6271.9 6277.2 6286.5 6287.0 6287.7 5110.0 6123.0 6197.5 6209.0 6219.6 6046.1 6134.1 6136.5 6242.6 6256.8 4871.0 4882.9 4896.8 4900.5 4922.7 4377.9 4388.5 4391.5 4399.9 4462.7 5573.6 5644.4 5669.5 5670.3 5709.5 6104.7 6148.9 6163.0 6192.7 6323.9 5643.2 5675.2 5686.4 5714.3 5721.2 4466.7 5330.3 5347.9 5371.6 5401.8 6158.8 6185.2 6207.5 6227.6 6289.7 6284.1 6292.7 6311.1 6314.9 6322.0 6197.1 6199.4 6244.4 6269.8 6283.2 5987.1 6118.4 6131.4 6179.4 6185.4 6225.4 6229.5 6245.8 6247.0 6250.7 4835.6 6082.0 6083.0 6092.4 6095.1 5767.2 5934.5 6033.1 6084.8 6103.4 5827.4 5903.3 5921.0 5929.8 5935.6 6091.3 6091.3 6100.5 6115.8 6119.4 5687.5 5799.1 5846.1 5923.1 5944.9 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:61109.79 Median:35321.41 stddev:134129.82 (379.7%) stddev-sans-first:134428.93 12:42:18 INFO - PROCESS | 1659 | Values: 513.9 531.3 532.1 547.5 566.5 31251.7 39857.6 40984.8 41002.8 41005.7 44570.8 45589.9 45767.3 45781.8 45880.5 8604.6 9649.0 9902.3 9917.0 9939.0 8692.3 11401.8 11660.3 11667.2 11713.9 11641.0 11800.2 11804.1 11807.9 11852.8 8540.2 11377.7 11531.8 11598.8 11654.2 11525.1 11675.2 11677.0 11679.9 11686.3 41116.6 41528.0 41536.8 41545.8 41605.4 3280.1 3435.0 3462.8 3572.2 3586.3 28738.0 28961.9 29038.5 29804.4 30214.7 2740.1 2747.2 2749.2 2801.4 2848.7 360175.0 360689.0 361196.0 361514.0 361594.0 34181.7 35164.0 35274.0 35368.8 65481.0 35726.9 35764.3 35908.1 35976.5 36045.6 13888.9 16010.1 16226.5 16340.9 16364.0 12226.7 12428.5 12500.0 13193.9 13653.7 7020.6 8159.9 8268.1 8274.9 8303.0 3342.6 3414.3 3420.5 3425.1 3451.2 935.9 939.8 964.9 994.3 1111.9 25418.1 29500.3 30028.8 30160.7 30683.5 1107.4 1125.5 1179.8 1200.2 1219.1 13506.0 15356.1 15658.5 15673.6 15722.8 677941.2 682689.0 687238.6 689760.0 693315.0 62532.4 63324.0 63436.0 63447.0 63591.0 1342.2 1486.5 1507.7 1510.4 1576.3 29340.6 29346.9 29612.0 29684.0 30449.0 49288.8 49576.0 50007.0 50049.7 50184.0 384820.0 386808.0 387115.0 387389.0 388903.0 23300.4 26007.6 26496.7 26815.0 26867.0 43007.9 44139.6 44459.8 44950.8 45399.8 36944.0 37936.8 37972.6 38110.6 38745.4 37036.6 37802.7 37951.8 38465.9 38737.8 37195.3 37894.2 38048.9 38232.8 38526.0 31855.0 32903.5 33119.3 33237.8 33254.7 23510.6 23808.5 24176.4 24187.9 24235.5 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:36690.84 Median:14469.24 stddev:105983.50 (732.5%) stddev-sans-first:106245.64 12:42:18 INFO - PROCESS | 1659 | Values: 330.6 402.9 406.2 409.4 414.4 17681.0 19783.9 20074.5 20434.6 20675.9 20431.3 20761.7 21820.8 21889.9 21906.5 5873.4 6178.9 6187.4 6188.0 6196.8 5876.8 6039.0 6101.0 6103.8 6144.0 5917.7 6046.9 6087.4 6091.6 6097.5 6010.4 6070.9 6097.6 6109.9 6110.7 5307.4 5359.6 5377.3 5378.9 5384.5 26303.6 26657.3 27081.0 27084.3 27088.7 2559.1 2595.4 2634.9 2710.7 3073.7 14833.6 14890.4 15122.3 15124.7 15622.5 1693.3 1736.8 1752.1 1759.3 1814.9 118781.8 121437.4 122673.4 122783.0 123318.0 10569.5 10713.3 10732.4 11056.6 11641.5 13246.4 14127.9 15037.0 15090.3 15116.9 8967.1 8986.7 9040.2 9042.9 9114.2 9051.0 9065.9 9080.6 9118.4 9167.4 3955.5 4645.7 4744.5 4753.3 4789.8 1952.6 2000.8 2008.7 2023.8 2039.7 839.9 844.6 1031.5 1036.8 1053.1 19714.6 20138.9 20389.7 21549.0 21668.3 896.6 945.2 947.0 965.5 973.8 10936.8 11088.3 11126.4 11162.9 11163.6 597488.0 622686.0 639751.0 654990.0 658908.0 98159.0 138117.0 138633.2 144358.0 149149.0 1028.4 1042.2 1056.0 1059.6 1064.5 18128.6 18227.8 19004.0 19022.7 19440.0 53800.7 53806.7 55781.0 55816.4 56971.1 81699.6 83274.0 83402.6 83465.0 83729.6 8175.0 8322.0 8336.3 8375.5 8384.2 6814.2 8072.5 12506.6 12527.9 12784.4 10518.5 11008.7 11218.0 11270.3 11333.6 9904.8 10479.6 10555.3 10678.8 10728.7 9561.2 11213.2 11436.5 11562.1 11601.9 8123.3 8128.9 8381.4 8399.4 8487.3 10439.7 11184.3 11460.2 11526.4 11544.9 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5712.92 Median:2742.44 stddev:3212.87 (117.2%) stddev-sans-first:3205.22 12:42:18 INFO - PROCESS | 1659 | Values: 1359.6 1418.7 1581.4 1727.3 1892.3 7801.0 8595.9 8814.5 8822.4 8825.5 8434.6 8679.2 8699.6 8699.7 8727.1 2059.9 2413.5 2437.8 2442.0 2445.5 1987.6 2450.2 2471.8 2474.4 2484.0 2430.4 2452.6 2464.0 2473.4 2476.8 2431.7 2434.6 2456.1 2460.8 2481.0 2434.9 2453.8 2462.4 2463.6 2465.1 8614.0 8686.7 8867.0 8879.2 8893.0 5257.8 5417.5 5420.7 5453.9 5466.5 3121.8 3470.2 3499.2 3595.4 3614.8 1032.8 1039.8 1041.8 1050.9 1052.1 11753.4 11893.8 11902.1 11915.9 11945.5 4565.3 4645.9 4686.2 4720.8 4724.6 9277.4 9288.9 9300.5 9304.3 9311.4 5281.7 5324.9 5378.2 5395.2 5402.9 4465.9 4632.8 4842.9 4905.0 4994.1 3797.0 3809.5 3869.2 3909.0 3928.2 1120.7 1218.1 1226.2 1231.4 1246.9 665.2 771.7 810.8 952.1 964.6 5700.8 5772.1 5946.9 5960.2 6032.3 478.1 498.7 499.3 500.0 650.2 3787.2 3950.3 4020.8 4052.7 4071.0 10236.7 10777.2 10828.4 10935.7 10955.2 10799.2 10855.2 10884.0 10884.5 10908.1 7788.3 8170.7 8289.3 8294.7 8301.8 5673.6 6007.2 6007.3 6056.4 6257.9 9474.4 9611.5 10058.0 10064.5 10081.1 10068.1 10128.2 10195.5 10225.3 10240.5 7313.3 7446.3 7447.5 7448.7 7449.5 8074.4 8341.5 8457.4 8468.9 8482.1 7289.7 7473.6 7506.7 7542.1 7695.2 7351.4 7373.8 7421.6 7499.6 7505.0 6956.4 7122.1 7129.2 7149.0 7230.6 4959.9 4986.2 4999.7 5098.7 5189.8 4953.9 5010.1 5083.2 5157.4 5290.3 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4674.15 Median:5165.33 stddev:1467.61 (28.4%) stddev-sans-first:1434.81 12:42:18 INFO - PROCESS | 1659 | Values: 316.1 318.7 323.9 325.7 327.5 4724.0 5352.5 5383.1 5409.5 5410.3 4665.9 4805.3 5312.9 5345.3 5349.6 3943.4 3958.4 4062.9 4168.5 4176.3 3177.9 4112.0 4135.3 4158.9 4167.1 3365.6 4006.7 4078.0 4108.9 4133.8 3820.5 3882.1 4112.4 4115.0 4130.8 3554.4 3608.0 3613.4 3619.9 3629.4 3988.9 5128.9 5134.7 5154.3 5175.3 4128.5 4546.2 4658.6 4681.0 4758.1 4874.5 5116.8 5610.7 5619.7 5645.2 3014.9 3975.3 3991.4 4001.5 4014.9 5155.7 5715.7 5810.3 5846.9 5889.7 3534.7 3953.3 4022.6 4035.0 4043.0 3433.7 3887.3 4136.2 4147.6 4152.3 4512.9 4677.5 4686.7 4719.2 4736.4 4517.5 5595.3 5634.7 5635.0 5644.7 1321.0 1414.6 1496.2 1501.6 1504.5 786.4 842.9 975.1 1005.6 1019.5 4408.0 5497.4 5516.0 5520.9 5529.3 4789.7 5367.5 5488.2 5494.8 5513.8 4400.4 4770.8 5212.9 5216.6 5279.8 3147.0 3268.8 3338.2 3343.5 3355.4 6031.2 6410.0 6575.3 6586.1 6655.6 5790.9 6201.3 6459.3 6551.7 6575.7 5353.4 6081.4 6595.0 6618.0 6639.6 6045.2 6314.9 6330.1 6362.7 6367.4 5958.4 6254.0 6274.5 6300.7 6320.2 5632.5 5817.0 6130.6 6541.9 6615.6 3027.8 3942.0 3972.3 3997.5 4013.8 4957.5 5432.7 5838.4 5854.5 5871.8 5479.4 5723.7 5759.8 5767.4 5927.0 5197.0 5662.9 5666.6 5691.9 5699.8 5341.4 5359.7 5526.3 5563.4 5569.2 4734.9 5174.9 5249.6 5575.9 5668.8 5081.8 5115.6 5477.2 5546.9 5607.8 12:42:18 INFO - PROCESS | 1659 | 12:42:18 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17891.95 Median:30277.18 stddev:25267.01 (83.5%) stddev-sans-first:25304.66 12:42:18 INFO - PROCESS | 1659 | Values: 633.4 737.5 739.3 754.8 767.2 14735.9 15331.4 15516.5 15535.8 15553.3 14161.0 14196.6 14304.1 14313.9 14314.4 887.4 923.2 930.7 936.0 940.4 930.6 940.4 940.7 941.3 941.9 988.1 989.4 992.2 994.4 994.9 981.2 984.3 984.6 985.0 989.4 721.9 722.4 724.0 726.9 732.0 15213.2 15216.2 15249.5 15252.6 15292.3 12131.5 12315.9 12320.6 12363.6 12450.4 9982.5 10001.4 10013.5 10570.0 10626.1 3400.3 3421.5 3457.3 3462.0 3501.4 52669.7 72230.9 73073.0 73960.6 74828.6 8707.7 8789.8 8816.8 8892.3 9050.6 20292.5 20295.9 20341.8 20506.2 20683.7 7533.9 7564.4 7606.6 7646.6 7956.6 4049.8 4060.6 4149.4 4205.6 4416.6 3492.4 3844.3 4052.3 4053.2 4062.8 585.9 591.3 727.8 736.9 737.3 508.1 508.5 524.4 545.9 571.5 6912.9 8279.3 8334.8 8394.5 8476.0 505.7 536.5 594.1 595.3 604.3 3943.0 3986.8 4049.6 4056.5 4065.9 92966.8 97665.5 97937.0 99160.0 99995.5 83192.0 85087.9 85531.6 86017.6 86307.6 62334.0 63639.4 64113.4 64731.0 69037.0 8125.8 8132.0 8237.5 8322.0 8356.3 43159.8 43304.0 43684.3 45292.0 45697.5 59950.1 64033.7 64088.4 64295.0 65207.0 10286.7 10519.8 10690.5 11141.5 11231.8 11433.5 11966.6 12106.2 12286.1 13139.3 11330.6 11346.3 11514.0 11807.0 12052.1 8940.2 9253.7 11310.4 11570.8 11722.0 11247.2 11265.4 11573.5 11635.9 11644.1 9483.9 9606.7 9833.8 9947.5 10147.1 9725.3 9732.5 9792.2 9815.1 9980.6 12:42:18 INFO - PROCESS | 1659 | -------- Summary: end -------- 12:42:18 INFO - PROCESS | 1659 | 12:42:19 INFO - TEST-INFO | 1659: exit 0 12:42:19 INFO - TEST-OK | dromaeo_css | took 1114341ms 12:42:19 INFO - TEST-START | kraken 12:42:19 INFO - operating with platform_type : mac_ 12:42:19 INFO - Initialising browser for kraken test... 12:42:19 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpA9PjWD/profile http://localhost:49270/getInfo.html) 12:42:21 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 12:42:21 INFO - PROCESS | 1669 | colorDepth:24 12:42:21 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 12:42:21 INFO - PROCESS | 1669 | __metrics 12:42:21 INFO - PROCESS | 1669 | 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" 12:42:21 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:42:21 INFO - TEST-INFO | 1669: exit 0 12:42:21 INFO - Browser initialized. 12:42:21 INFO - Running cycle 1/1 for kraken test... 12:42:21 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpA9PjWD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:33 INFO - PROCESS | 1671 | RSS: Main: 211746816 12:42:33 INFO - PROCESS | 1671 | 12:42:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:37 INFO - PROCESS | 1671 | RSS: Main: 270249984 12:42:37 INFO - PROCESS | 1671 | 12:42:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 12:42:40 INFO - PROCESS | 1671 | RSS: Main: 284377088 12:42:40 INFO - PROCESS | 1671 | 12:42:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 12:42:44 INFO - PROCESS | 1671 | RSS: Main: 375009280 12:42:44 INFO - PROCESS | 1671 | 12:42:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 12:42:47 INFO - PROCESS | 1671 | RSS: Main: 279969792 12:42:47 INFO - PROCESS | 1671 | 12:42:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:50 INFO - PROCESS | 1671 | RSS: Main: 280121344 12:42:50 INFO - PROCESS | 1671 | 12:42:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:53 INFO - PROCESS | 1671 | RSS: Main: 317325312 12:42:53 INFO - PROCESS | 1671 | 12:42:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:56 INFO - PROCESS | 1671 | RSS: Main: 318697472 12:42:56 INFO - PROCESS | 1671 | 12:42:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 12:43:00 INFO - PROCESS | 1671 | RSS: Main: 317571072 12:43:00 INFO - PROCESS | 1671 | 12:43:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:02 INFO - PROCESS | 1671 | RSS: Main: 307900416 12:43:02 INFO - PROCESS | 1671 | 12:43:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:05 INFO - PROCESS | 1671 | RSS: Main: 314793984 12:43:05 INFO - PROCESS | 1671 | 12:43:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:07 INFO - PROCESS | 1671 | RSS: Main: 317837312 12:43:07 INFO - PROCESS | 1671 | 12:43:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:11 INFO - PROCESS | 1671 | RSS: Main: 322908160 12:43:11 INFO - PROCESS | 1671 | 12:43:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:14 INFO - PROCESS | 1671 | RSS: Main: 320737280 12:43:14 INFO - PROCESS | 1671 | 12:43:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 12:43:17 INFO - PROCESS | 1671 | RSS: Main: 384704512 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | __start_tp_report 12:43:17 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 12:43:17 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 12:43:17 INFO - PROCESS | 1671 | |i|pagename|runs| 12:43:17 INFO - PROCESS | 1671 | |0;ai-astar;115;115;109;114;115;115;116;114;104;116 12:43:17 INFO - PROCESS | 1671 | |1;audio-beat-detection;155;125;131;189;147;174;159;122;197;150 12:43:17 INFO - PROCESS | 1671 | |2;audio-dft;185;186;186;187;185;183;185;186;183;182 12:43:17 INFO - PROCESS | 1671 | |3;audio-fft;70;79;73;151;61;56;56;78;137;56 12:43:17 INFO - PROCESS | 1671 | |4;audio-oscillator;89;89;144;78;110;130;161;80;79;134 12:43:17 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;89;85;87;86;88;86;90;87;89;130 12:43:17 INFO - PROCESS | 1671 | |6;imaging-darkroom;94;94;94;94;95;93;94;94;94;94 12:43:17 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;84;86;85;85;85;83;85;84;85 12:43:17 INFO - PROCESS | 1671 | |8;json-parse-financial;60;64;65;64;61;62;64;82;58;56 12:43:17 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;63;66;63;74;78;71;70;70;67;67 12:43:17 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;81;81;75;80;80;79;96;107;84;83 12:43:17 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;136;137;136;135;133;135;135;148;165;154 12:43:17 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;167;168;167;200;167;168;165;167;170;158 12:43:17 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;68;68;68;65;93;96;97;98;77 12:43:17 INFO - PROCESS | 1671 | __end_tp_report 12:43:17 INFO - PROCESS | 1671 | __start_cc_report 12:43:17 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2056 12:43:17 INFO - PROCESS | 1671 | __end_cc_report 12:43:17 INFO - PROCESS | 1671 | __startTimestamp1461613397002__endTimestamp 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | ------- Summary: start ------- 12:43:17 INFO - PROCESS | 1671 | Number of tests: 14 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:3.83 (3.3%) stddev-sans-first:4.01 12:43:17 INFO - PROCESS | 1671 | Values: 115.0 115.0 109.0 114.0 115.0 115.0 116.0 114.0 104.0 116.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:154.90 Median:157.00 stddev:25.71 (16.4%) stddev-sans-first:27.27 12:43:17 INFO - PROCESS | 1671 | Values: 155.0 125.0 131.0 189.0 147.0 174.0 159.0 122.0 197.0 150.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:1.62 (0.9%) stddev-sans-first:1.72 12:43:17 INFO - PROCESS | 1671 | Values: 185.0 186.0 186.0 187.0 185.0 183.0 185.0 186.0 183.0 182.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:81.70 Median:65.50 stddev:34.16 (52.2%) stddev-sans-first:35.97 12:43:17 INFO - PROCESS | 1671 | Values: 70.0 79.0 73.0 151.0 61.0 56.0 56.0 78.0 137.0 56.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:78.50 stddev:30.73 (39.1%) stddev-sans-first:31.69 12:43:17 INFO - PROCESS | 1671 | Values: 89.0 89.0 144.0 78.0 110.0 130.0 161.0 80.0 79.0 134.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:87.50 stddev:13.55 (15.5%) stddev-sans-first:14.34 12:43:17 INFO - PROCESS | 1671 | Values: 89.0 85.0 87.0 86.0 88.0 86.0 90.0 87.0 89.0 130.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 12:43:17 INFO - PROCESS | 1671 | Values: 94.0 94.0 94.0 94.0 95.0 93.0 94.0 94.0 94.0 94.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.84 (1.0%) stddev-sans-first:0.87 12:43:17 INFO - PROCESS | 1671 | Values: 84.0 84.0 86.0 85.0 85.0 85.0 83.0 85.0 84.0 85.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:64.00 stddev:7.09 (11.1%) stddev-sans-first:7.40 12:43:17 INFO - PROCESS | 1671 | Values: 60.0 64.0 65.0 64.0 61.0 62.0 64.0 82.0 58.0 56.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:70.00 stddev:4.72 (6.7%) stddev-sans-first:4.50 12:43:17 INFO - PROCESS | 1671 | Values: 63.0 66.0 63.0 74.0 78.0 71.0 70.0 70.0 67.0 67.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:81.00 stddev:9.58 (11.8%) stddev-sans-first:10.07 12:43:17 INFO - PROCESS | 1671 | Values: 81.0 81.0 75.0 80.0 80.0 79.0 96.0 107.0 84.0 83.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:136.50 stddev:10.70 (7.8%) stddev-sans-first:11.17 12:43:17 INFO - PROCESS | 1671 | Values: 136.0 137.0 136.0 135.0 133.0 135.0 135.0 148.0 165.0 154.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:167.50 stddev:11.12 (6.6%) stddev-sans-first:11.75 12:43:17 INFO - PROCESS | 1671 | Values: 167.0 168.0 167.0 200.0 167.0 168.0 165.0 167.0 170.0 158.0 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.80 Median:85.00 stddev:14.33 (16.9%) stddev-sans-first:14.55 12:43:17 INFO - PROCESS | 1671 | Values: 68.0 68.0 68.0 68.0 65.0 93.0 96.0 97.0 98.0 77.0 12:43:17 INFO - PROCESS | 1671 | -------- Summary: end -------- 12:43:17 INFO - PROCESS | 1671 | 12:43:17 INFO - TEST-INFO | 1671: exit 0 12:43:17 INFO - TEST-OK | kraken | took 58649ms 12:43:17 INFO - SUITE-END | took 1172s 12:43:18 INFO - Completed test suite (00:19:33) 12:43:18 INFO - javascript benchmark 12:43:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3385.9661403385962, 3456.9135771605693, 3461.325041616885, 3479.6520347965193, 3530.7704999175053, 5615.943840561598, 5673.631213971094, 5680.630759000673, 5684.317881854184, 5693.8576535586635, 5629.043062679344, 5701.800436984707, 5710.514606258473, 5733.082706766918, 5735.283089613798, 5041.546260836524, 5070.8225212117395, 5137.152369858969, 5148.407933087687, 5178.14560597501, 5054.595632349422, 5144.511271429208, 5185.040767458014, 5188.974055129719, 5208.531232189102, 5244.475552444744, 5252.6848389096585, 5255.710935898524, 5256.080185967459, 5288.867778305535, 5201.375834899798, 5217.765200565984, 5235.73821308933, 5236.1098613235845, 5268.894622107575, 5002.974985125087, 5036.622253330978, 5072.061668591323, 5144.6655967362, 5161.974190129025, 5563.554915606742, 5587.74855131516, 5597.6641401515635, 5603.299587551556, 5606.0, 6155.630662160284, 6228.470580000705, 6248.968755156195, 6249.156363890862, 6250.218722659668, 5893.941060589425, 6124.724387402578, 6163.753449862, 6200.503959683259, 6295.496360291166, 4776.4268287805335, 5767.596268261182, 5774.97112514439, 5788.421157884262, 5794.130880367937, 6243.125962365272, 6246.063090536456, 6249.218847644044, 6251.343608920998, 6269.780557680459, 5837.737301821343, 6031.517478601701, 6056.091586262096, 6144.3717414885095, 6164.260288765287, 6271.874562508724, 6277.24673039229, 6286.465650419674, 6287.025511045796, 6287.748490060347, 5110.0, 6122.969385153046, 6197.5351848611535, 6208.975519039398, 6219.564630475914, 6046.123312119718, 6134.069235337315, 6136.549713441251, 6242.625442473466, 6256.843578910563, 4871.0014447038275, 4882.902341953141, 4896.779644915988, 4900.460949295574, 4922.655414121049, 4377.890552736207, 4388.495323037828, 4391.5388884166705, 4399.912001759947, 4462.732236065846, 5573.554115670737, 5644.435556444405, 5669.461401166883, 5670.262865827416, 5709.543236541067, 6104.732011601605, 6148.893199224182, 6163.013917773134, 6192.690365481708, 6323.936760632335, 5643.209950606918, 5675.233843431167, 5686.402927692616, 5714.342850572238, 5721.199032135504, 4466.665000125004, 5330.253764472977, 5347.946520534822, 5371.57027437804, 5401.756920938567, 6158.815235542851, 6185.165002724664, 6207.472364849038, 6227.564070515021, 6289.7484100635465, 6284.120223168668, 6292.685365731603, 6311.084892690626, 6314.873702526015, 6322.0, 6197.101420294122, 6199.442050215322, 6244.406781355764, 6269.811905642838, 6283.214598175228, 5987.101934709828, 6118.388161184024, 6131.386861313833, 6179.443850053337, 6185.350538193425, 6225.439710426083, 6229.50163986889, 6245.781397651061, 6247.0, 6250.656213908097, 4835.637327200404, 6081.969590152021, 6082.9695851520455, 6092.421219984094, 6095.116208149705, 5767.221425107557, 5934.525237980864, 6033.064874944391, 6084.847878803065, 6103.389661034039, 5827.358990511125, 5903.291605007426, 5921.0, 5929.822105336847, 5935.614185077956, 6091.329953705001, 6091.329953705177, 6100.481459076028, 6115.816525504242, 6119.418655227746, 5687.516561092188, 5799.130130480462, 5846.123081537803, 5923.141144534105, 5944.851378715567], "value": 5649.892740489636, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.9280500729899, 531.2933798048596, 532.1219987021415, 547.5209191957039, 566.4618612318299, 31251.68748312519, 39857.601423985754, 40984.79507602465, 41002.7949860251, 41005.71954243661, 44570.777146114306, 45589.86025349368, 45767.313490297674, 45781.771091144496, 45880.54119458804, 8604.569771511431, 9649.035096490348, 9902.306838521306, 9917.0, 9938.950305248463, 8692.261157801595, 11401.828972565418, 11660.300381977066, 11667.183297169202, 11713.94143029288, 11641.01051410631, 11800.232984856, 11804.055675545937, 11807.94096029523, 11852.7629447411, 8540.188682075193, 11377.658670239905, 11531.769364612703, 11598.76802463946, 11654.24247423919, 11525.077993760478, 11675.182737208399, 11677.007454366389, 11679.88320116805, 11686.298822070617, 41116.58883411157, 41528.0, 41536.792316038525, 41545.79227103845, 41605.375919361235, 3280.1471617379416, 3435.0210190095954, 3462.7576069675133, 3572.1962558424407, 3586.33652774235, 28737.994170203943, 28961.855190723913, 29038.537941994564, 29804.403911921858, 30214.697853021407, 2740.066636829288, 2747.175847245818, 2749.175247425764, 2801.3976994946183, 2848.686644469107, 360175.0, 360689.0, 361196.0, 361513.9999999974, 361594.0, 34181.658183418345, 35164.0, 35274.0, 35368.82315588457, 65481.0, 35726.92819215428, 35764.28471430557, 35908.10229744225, 35976.46035309473, 36045.63954360423, 13888.930555347159, 16010.119443430724, 16226.513204603882, 16340.918295408685, 16364.0, 12226.694332641755, 12428.502859885684, 12500.0, 13193.934030329787, 13653.726925461435, 7020.5787652741, 8159.877601835978, 8268.131846156188, 8274.917250827535, 8303.00363956329, 3342.565466489341, 3414.3171365726953, 3420.538227339327, 3425.0648383663647, 3451.2062225687732, 935.8736570563022, 939.7838497145694, 964.9228061755043, 994.3487016004384, 1111.9499622517005, 25418.110366137098, 29500.262493437407, 30028.84985575058, 30160.698393015788, 30683.539746903814, 1107.3909349857547, 1125.5441546173813, 1179.817128345108, 1200.2198570928792, 1219.1344145656683, 13506.0, 15356.07863528192, 15658.45195418155, 15673.608159796097, 15722.828346830918, 677941.2205877977, 682689.0, 687238.5638071777, 689760.0, 693315.0, 62532.37467625357, 63324.0, 63436.0, 63447.0, 63591.0, 1342.1812694256496, 1486.5020218226798, 1507.705997330532, 1510.4335874047233, 1576.2985471465317, 29340.55989160207, 29346.853265733534, 29611.96358127455, 29684.0, 30449.0, 49288.75355623199, 49576.0, 50007.0, 50049.74975125101, 50184.0, 384820.0, 386808.0, 387115.0, 387389.0, 388903.0, 23300.417489562897, 26007.609885852107, 26496.735032649427, 26815.0, 26867.0, 43007.92480187895, 44139.55860441483, 44459.777701111285, 44950.77524612356, 45399.77300113478, 36944.0, 37936.81031594824, 37972.62027379801, 38110.618893811814, 38745.4188187183, 37036.62963370332, 37802.676906308145, 37951.81024094862, 38465.88437635957, 38737.80631096826, 37195.25609487741, 37894.242115156994, 38048.85853424402, 38232.80883595564, 38526.0, 31855.0, 32903.506447402826, 33119.337613248084, 33237.833810830794, 33254.66745332516, 23510.647340290252, 23808.52382952366, 24176.395590110387, 24187.911543980914, 24235.51528969446], "value": 18583.98761138765, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.55210190192287, 402.8509451502944, 406.24906652328445, 409.3900088867586, 414.3660199894161, 17681.0, 19783.901080494576, 20074.49813754655, 20434.591308173847, 20675.896620516913, 20431.284905028326, 20761.688574671392, 21820.78179218211, 21889.890550547265, 21906.452338691503, 5873.353931067569, 6178.938210617904, 6187.381261873822, 6188.0, 6196.81409557714, 5876.794312199074, 6039.003564411867, 6101.0, 6103.81688549342, 6144.0, 5917.733701983421, 6046.939530604682, 6087.360827113156, 6091.634501929899, 6097.51219902409, 6010.429009244137, 6070.908936365936, 6097.634141951475, 6109.938900611026, 6110.694465276719, 5307.363116426053, 5359.571234301286, 5377.3009508764, 5378.8924221515745, 5384.542313903323, 26303.605445918325, 26657.333566660796, 27081.0, 27084.322891927663, 27088.729112708817, 2559.078731656602, 2595.390083330416, 2634.8814303356394, 2710.675277046972, 3073.661897191307, 14833.629159271104, 14890.404383824634, 15122.319495622723, 15124.697506049817, 15622.453214137564, 1693.2888186961507, 1736.792928914405, 1752.0976697001054, 1759.3138675916402, 1814.872958892878, 118781.81218187793, 121437.39281303625, 122673.38663306716, 122783.0, 123318.0, 10569.471526423647, 10713.321669582605, 10732.409717465614, 11056.613018544313, 11641.53433862636, 13246.40391182399, 14127.85872141286, 15037.022593531385, 15090.320935557926, 15116.924415377853, 8967.148120928501, 8986.68546600866, 9040.231580315616, 9042.86435703465, 9114.179723824887, 9051.0, 9065.864012039825, 9080.636774528944, 9118.40730352525, 9167.404118732262, 3955.5253369595257, 4645.651576131803, 4744.454387745384, 4753.263244197155, 4789.784459699322, 1952.589956109233, 2000.789917058717, 2008.7187793708893, 2023.787502312236, 2039.7348344715092, 839.9458679357367, 844.6156998565638, 1031.5203430404897, 1036.8133735927606, 1053.0781324083578, 19714.6199766015, 20138.899305503674, 20389.694154587694, 21549.0, 21668.349949501226, 896.6278994217457, 945.1824172091092, 946.9763255918657, 965.5268918230157, 973.7516933181993, 10936.781264374828, 11088.33469991803, 11126.38804865724, 11162.944185279184, 11163.609273675382, 597488.0, 622686.0, 639751.0, 654990.0, 658908.0, 98159.01840981499, 138117.0, 138633.2273354527, 144358.0, 149149.0, 1028.3675539543126, 1042.1714736784274, 1055.9788804223872, 1059.5708737961138, 1064.5369264370024, 18128.637427251644, 18227.817721823143, 19004.049797509793, 19022.7146592804, 19440.0, 53800.73099634477, 53806.73096634492, 55781.0, 55816.44183558112, 56971.14543281937, 81699.59150204211, 83274.0, 83402.58298708468, 83465.0, 83729.58135209528, 8175.0, 8322.001359836859, 8336.291415229773, 8375.497470151811, 8384.203500667427, 6814.2163651180745, 8072.515649061075, 12506.624801255977, 12527.87472125267, 12784.424700888296, 10518.474076296001, 11008.724781880197, 11218.049464393416, 11270.323780573191, 11333.603323883624, 9904.752381190528, 10479.58081676755, 10555.313904596176, 10678.83981740259, 10728.678139655824, 9561.187299079656, 11213.215074944676, 11436.485358159069, 11562.080119432694, 11601.941990289995, 8123.268905798506, 8128.878066828884, 8381.413301068867, 8399.412041157304, 8487.321014318717, 10439.686809395731, 11184.328940263611, 11460.19778615489, 11526.423678816192, 11544.884551154717], "value": 9848.057655705568, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.6396954806976, 1418.7020725647612, 1581.3832605283942, 1727.3263427263369, 1892.271475481939, 7800.98587183666, 8595.91404085959, 8814.515201663906, 8822.382433229677, 8825.51459669718, 8434.578271086451, 8679.21887030167, 8699.608517616707, 8699.695510657128, 8727.127287271269, 2059.907304171308, 2413.52936177445, 2437.756224377557, 2442.0476014354417, 2445.4986727720775, 1987.6322880267126, 2450.1669432392982, 2471.789897858684, 2474.3690358958443, 2483.950320993579, 2430.3924018995253, 2452.619843924195, 2464.0143942423083, 2473.369290830836, 2476.826622136451, 2431.7203521595065, 2434.5739495588377, 2456.0666946560373, 2460.8031357491445, 2481.04479775286, 2434.91477798278, 2453.8036957043396, 2462.384403899025, 2463.556559819232, 2465.1125594785863, 8614.009388920293, 8686.652533898636, 8866.955665221698, 8879.245264152554, 8892.955535222347, 5257.842264732064, 5417.51242388183, 5420.674759514442, 5453.863653408657, 5466.535344495723, 3121.799131612208, 3470.201853573667, 3499.195185107414, 3595.406757884946, 3614.8373323200517, 1032.7521394865253, 1039.8232300508932, 1041.7656027393853, 1050.8633877595864, 1052.131991107332, 11753.412329383582, 11893.762124757457, 11902.107341949388, 11915.880841191563, 11945.522179112739, 4565.315202719618, 4645.9303110453375, 4686.179918514246, 4720.763961801897, 4724.645651576146, 9277.443353398818, 9288.860667089999, 9300.488473134043, 9304.302177336727, 9311.394759340621, 5281.656692314988, 5324.893502129936, 5378.2470454136455, 5395.190721391744, 5402.945970540244, 4465.866024019285, 4632.814687412466, 4842.927356089662, 4904.950950490521, 4994.076095922266, 3797.0, 3809.504764380613, 3869.1681288523105, 3909.042284640281, 3928.2339943710704, 1120.6664069756964, 1218.141210446633, 1226.2213494430923, 1231.4150778380197, 1246.8690787467367, 665.1552528289143, 771.7298945369076, 810.7770363150181, 952.1240458777941, 964.628617982082, 5700.800471983461, 5772.134179873053, 5946.85132871673, 5960.165576819249, 6032.276126864805, 478.0963978081429, 498.6559274100859, 499.3284032975642, 500.0124753611588, 650.2457149706318, 3787.1857550626746, 3950.288947989332, 4020.819063142107, 4052.7163098582814, 4070.9619047142787, 10236.744081397876, 10777.191710621735, 10828.350298982086, 10935.726606834734, 10955.233133680566, 10799.244052916221, 10855.185861060452, 10884.0, 10884.455777211107, 10908.072813810757, 7788.260115289038, 8170.714025009096, 8289.253967142866, 8294.709685160991, 8301.8339633207, 5673.631213971079, 6007.158997740233, 6007.339192688888, 6056.424639659225, 6257.937420625735, 9474.431534107976, 9611.519424028911, 10058.04448577384, 10064.547095380873, 10081.092701656886, 10068.144207742424, 10128.240381971234, 10195.541200646137, 10225.284230103822, 10240.48797560134, 7313.268673132644, 7446.329830315298, 7447.478676492594, 7448.739294124896, 7449.47853650261, 8074.434789564674, 8341.499510029418, 8457.36569757283, 8468.915310846813, 8482.109378515168, 7289.725185390858, 7473.551586904803, 7506.699732010658, 7542.132654744774, 7695.153533111241, 7351.3751331137455, 7373.815654608463, 7421.554706717614, 7499.5875226863045, 7505.024346834876, 6956.373926346653, 7122.145342558926, 7129.215786263403, 7148.964255178899, 7230.566166030055, 4959.876003099807, 4986.227134794112, 4999.65002449825, 5098.668586541863, 5189.844304670866, 4953.925691114564, 5010.073136469765, 5083.186690129562, 5157.406898206755, 5290.312259406252], "value": 4520.674831478529, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.1054216567115, 318.6717680788788, 323.9012101309101, 325.6694455128044, 327.5414419812264, 4723.952760472394, 5352.545033672138, 5383.057964856149, 5409.513143817056, 5410.269613602168, 4665.883352916178, 4805.303231031498, 5312.893742125155, 5345.33183352081, 5349.598780091489, 3943.4479172915817, 3958.44581758554, 4062.8577999770014, 4168.457241082937, 4176.2900306947895, 3177.8728850845937, 4111.999999999985, 4135.255653982282, 4158.8544400945975, 4167.145735124307, 3365.5961284645755, 4006.679465642756, 4077.961019490255, 4108.931677763788, 4133.751974881502, 3820.4842346283176, 3882.145927895844, 4112.362583799516, 4114.958850411487, 4130.793460326971, 3554.36021516127, 3607.981960090209, 3613.3676606594004, 3619.9276014479565, 3629.4192929131214, 3988.940165897514, 5128.9230661540105, 5134.691918484866, 5154.278401023859, 5175.327207463006, 4128.483939507561, 4546.249868771649, 4658.557437043475, 4680.953190468086, 4758.072175925696, 4874.488178741217, 5116.797552575142, 5610.69141197234, 5619.7471113799975, 5645.237892884449, 3014.9246268843235, 3975.3043217436834, 3991.4012898065234, 4001.4998125234347, 4014.8594799181888, 5155.71643559604, 5715.657060576337, 5810.273715785527, 5846.883062338729, 5889.676067816311, 3534.7274981006817, 3953.347697629916, 4022.577629348935, 4034.959650403517, 4043.0, 3433.6738009889023, 3887.3391523441082, 4136.234796562646, 4147.624256409456, 4152.314868046798, 4512.887177820515, 4677.532246775295, 4686.695364801277, 4719.244920812722, 4736.389627526658, 4517.480489743656, 5595.328560572757, 5634.661920284796, 5635.0, 5644.661320320712, 1320.9933950330378, 1414.6251243420502, 1496.1995332497063, 1501.6095815088156, 1504.5411149599477, 786.3944762532932, 842.928351090152, 975.0834215837232, 1005.5726316315537, 1019.4698756646502, 4408.0, 5497.422770609109, 5515.972420137874, 5520.944790552124, 5529.281193444826, 4789.664723469394, 5367.5169234769055, 5488.204210389471, 5494.8076817312, 5513.807016754475, 4400.44994375703, 4770.809167633256, 5212.895742085213, 5216.556592689664, 5279.841604751864, 3147.0, 3268.8202148881564, 3338.2322065924973, 3343.5151902973935, 3355.3660376604735, 6031.215941927521, 6410.0, 6575.342465753482, 6586.110875031905, 6655.6339401332425, 5790.942090579125, 6201.317855035943, 6459.257185423642, 6551.672416379162, 6575.671216439159, 5353.437889021597, 6081.391860813883, 6594.967025164843, 6617.981515637776, 6639.634820084845, 6045.1864440668505, 6314.936850631619, 6330.145430367026, 6362.6818659067785, 6367.363263673596, 5958.433948774859, 6253.968730156502, 6274.529410294337, 6300.7479700811455, 6320.209973753281, 5632.493075623213, 5816.970915145566, 6130.570860039755, 6541.934580654323, 6615.6030638161865, 3027.833469159217, 3941.9605803941595, 3972.2849887020616, 3997.5402828675074, 4013.799310034545, 4957.454679985127, 5432.728363581884, 5838.4453476919625, 5854.531637469077, 5871.7651293949475, 5479.369872464717, 5723.65658060501, 5759.769609215585, 5767.394423585463, 5926.970365148147, 5196.999155325303, 5662.915056274242, 5666.631668941505, 5691.857703557444, 5699.829005129853, 5341.359036915595, 5359.705216213146, 5526.281583394133, 5563.360213575329, 5569.248151499496, 4734.881627959328, 5174.9482505174465, 5249.632525723163, 5575.944240557543, 5668.773249069991, 5081.7713202905215, 5115.56517696, 5477.233187353694, 5546.944530554642, 5607.8037268697035], "value": 4218.600950717585, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.3666333666333, 737.5169264131994, 739.2755100001995, 754.8406640611421, 767.1867820110683, 14735.934511362388, 15331.386744530218, 15516.534503964871, 15535.844641553582, 15553.300101495446, 14161.0, 14196.574102776933, 14304.141751494892, 14313.856861431383, 14314.427422903098, 887.3699673232013, 923.229103698411, 930.6556574067605, 935.9719208423725, 940.3981451870808, 930.5765876526198, 940.4404379394249, 940.7083804020775, 941.3128416256103, 941.924646028316, 988.1403179234077, 989.3618615992681, 992.1963209800085, 994.3536701144242, 994.9204063674889, 981.1562056631292, 984.3355734878928, 984.6061575369873, 984.9950750246239, 989.4162444157981, 721.9314165154302, 722.3823630795699, 724.022569531134, 726.8691635505606, 732.0264048815063, 15213.163276019815, 15216.163111028889, 15249.542513724495, 15252.618684532865, 15292.311845966848, 12131.454084566216, 12315.945098195942, 12320.630381088582, 12363.62909112719, 12450.439730212162, 9982.450965196911, 10001.449920254383, 10013.49932503372, 10570.048695617354, 10626.14990800734, 3400.336934297813, 3421.469672200813, 3457.3258214648154, 3461.9653803461897, 3501.352249833789, 52669.73665131688, 72230.916536252, 73073.0, 73960.6301968492, 74828.62585687036, 8707.695230666895, 8789.813375194268, 8816.823663526693, 8892.3330750194, 9050.592723327467, 20292.492687682632, 20295.898520507304, 20341.796582034287, 20506.179752810018, 20683.689744653842, 7533.886991695129, 7564.394848412114, 7606.619669016527, 7646.617669116522, 7956.562389068657, 4049.817758200888, 4060.553339132693, 4149.398337241144, 4205.642520385741, 4416.646668266531, 3492.353914525821, 3844.3464611016193, 4052.2705912935367, 4053.2096241233266, 4062.776547289868, 585.8857522783102, 591.340655169483, 727.752564128199, 736.9336759691654, 737.3437640499959, 508.12602324002654, 508.48896858656815, 524.4237264700687, 545.9372172200168, 571.5085026876883, 6912.930870691229, 8279.296259817984, 8334.833303333899, 8394.496330220207, 8476.0, 505.74965392130395, 536.5305357811915, 594.1355327997753, 595.2945759275273, 604.2567641800609, 3942.9802850986134, 3986.820593073319, 4049.6152865477734, 4056.472658554369, 4065.9390109148385, 92966.7736548979, 97665.5350169748, 97937.0, 99160.0, 99995.50006749904, 83192.0, 85087.87280318042, 85531.57234213913, 86017.56991215004, 86307.56846215854, 62334.0, 63639.36360636427, 64113.35886641167, 64731.0, 69036.999999999, 8125.79685507867, 8132.0, 8237.46456480327, 8321.95839020801, 8356.331493480386, 43159.78420108005, 43304.0, 43684.34473482964, 45292.0, 45697.54302456933, 59950.10074848794, 64033.679831600544, 64088.3591164101, 64295.0, 65207.0, 10286.742831429274, 10519.78960420772, 10690.518926648165, 11141.498632561716, 11231.775364492827, 11433.542658293907, 11966.64100076999, 12106.152569320064, 12286.078544109048, 13139.34303284851, 11330.60342887995, 11346.319220846775, 11513.963743263146, 11807.0, 12052.0960927929, 8940.240079593308, 9253.67612133572, 11310.377929213972, 11570.826437603275, 11722.0, 11247.156463265124, 11265.436728163722, 11573.537058517564, 11635.941820290844, 11644.126690498077, 9483.905160948578, 9606.66376676813, 9833.754156146153, 9947.502624868583, 10147.137493313152, 9725.270604704534, 9732.464714440774, 9792.216622670312, 9815.067568580973, 9980.550875210778], "value": 6889.737986772212, "unit": "score"}], "name": "dromaeo_css", "value": 7170.088422732888, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 109.0, 114.0, 115.0, 115.0, 116.0, 114.0, 104.0, 116.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 131.0, 189.0, 147.0, 174.0, 159.0, 122.0, 197.0, 150.0], "value": 154.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 187.0, 185.0, 183.0, 185.0, 186.0, 183.0, 182.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 73.0, 151.0, 61.0, 56.0, 56.0, 78.0, 137.0, 56.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 144.0, 78.0, 110.0, 130.0, 161.0, 80.0, 79.0, 134.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 86.0, 88.0, 86.0, 90.0, 87.0, 89.0, 130.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 85.0, 85.0, 85.0, 83.0, 85.0, 84.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 65.0, 64.0, 61.0, 62.0, 64.0, 82.0, 58.0, 56.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 63.0, 74.0, 78.0, 71.0, 70.0, 70.0, 67.0, 67.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 75.0, 80.0, 80.0, 79.0, 96.0, 107.0, 84.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 136.0, 135.0, 133.0, 135.0, 135.0, 148.0, 165.0, 154.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 167.0, 200.0, 167.0, 168.0, 165.0, 167.0, 170.0, 158.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 65.0, 93.0, 96.0, 97.0, 98.0, 77.0], "value": 79.8, "unit": "score"}], "name": "kraken", "value": 1522.4, "alertThreshold": 2.0}]} 12:43:18 INFO - Return code: 0 12:43:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:43:18 ERROR - # TBPL SUCCESS # 12:43:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:43:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:43:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:43:18 INFO - 2016-04-25 12:43:18.292 screenresolution[1675:10029] starting screenresolution argv=screenresolution get 12:43:18 INFO - 2016-04-25 12:43:18.294 screenresolution[1675:10029] Display 0: 1600x1200x32@60 12:43:18 INFO - 2016-04-25 12:43:18.300 screenresolution[1676:10033] starting screenresolution argv=screenresolution list 12:43:18 INFO - Available Modes on Display 0 12:43:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:43:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:43:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:43:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:43:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:43:18 INFO - Intel Iris: 12:43:18 INFO - Chipset Model: Intel Iris 12:43:18 INFO - Type: GPU 12:43:18 INFO - Bus: Built-In 12:43:18 INFO - VRAM (Dynamic, Max): 1536 MB 12:43:18 INFO - Vendor: Intel (0x8086) 12:43:18 INFO - Device ID: 0x0a2e 12:43:18 INFO - Revision ID: 0x0009 12:43:18 INFO - Displays: 12:43:18 INFO - Display: 12:43:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:43:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:43:18 INFO - Display Serial Number: 1600x1200 60 12:43:18 INFO - Main Display: Yes 12:43:18 INFO - Mirror: Off 12:43:18 INFO - Online: Yes 12:43:18 INFO - Rotation: Supported 12:43:18 INFO - Return code: 0 12:43:18 INFO - Running post-action listener: _resource_record_post_action 12:43:18 INFO - Running post-run listener: _resource_record_post_run 12:43:19 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 37930496; Write bytes: 655485952; Read time: 396; Write time: 2382 12:43:19 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 353161728; Write bytes: 348911616; Read time: 27843; Write time: 1093 12:43:19 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 36107776; Write bytes: 296768512; Read time: 376; Write time: 1254 12:43:19 INFO - Running post-run listener: _upload_blobber_files 12:43:19 INFO - Blob upload gear active. 12:43:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:43:19 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 12:43:19 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'] 12:43:19 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 12:43:20 INFO - (blobuploader) - INFO - Open directory for files ... 12:43:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:43:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:43:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:21 INFO - (blobuploader) - INFO - Done attempting. 12:43:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:43:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:43:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:22 INFO - (blobuploader) - INFO - Done attempting. 12:43:22 INFO - (blobuploader) - INFO - Iteration through files over. 12:43:22 INFO - Return code: 0 12:43:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:43:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:43:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d6655dbe57b5926c8bc62052e79532cd00a763570cb3fd19a168554e005fa452174b7ce0b8e447d693405b34e185adfe2dc72498771a8f203877f3b98548216", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87619cdd4781cddcb9f3c40a26534e60f5902334e5bf6b3eaa4b9049b7f25dd70a8a6499db16eb840cc3795ff26e9072ba314be5a63c3f96995a2d47d5e4fb0c"} 12:43:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:43:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:43:22 INFO - Contents: 12:43:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d6655dbe57b5926c8bc62052e79532cd00a763570cb3fd19a168554e005fa452174b7ce0b8e447d693405b34e185adfe2dc72498771a8f203877f3b98548216", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87619cdd4781cddcb9f3c40a26534e60f5902334e5bf6b3eaa4b9049b7f25dd70a8a6499db16eb840cc3795ff26e9072ba314be5a63c3f96995a2d47d5e4fb0c"} 12:43:22 INFO - Running post-run listener: copy_logs_to_upload_dir 12:43:22 INFO - Copying logs to upload dir... 12:43:22 INFO - mkdir: /builds/slave/test/build/upload/logs 12:43:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.293138 ========= master_lag: 0.72 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-25 12:43:22.854149) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 12:43:22.858110) ========= 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.5H7489PY6m/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.MNmm6DEHQp/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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/2d6655dbe57b5926c8bc62052e79532cd00a763570cb3fd19a168554e005fa452174b7ce0b8e447d693405b34e185adfe2dc72498771a8f203877f3b98548216", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87619cdd4781cddcb9f3c40a26534e60f5902334e5bf6b3eaa4b9049b7f25dd70a8a6499db16eb840cc3795ff26e9072ba314be5a63c3f96995a2d47d5e4fb0c"} 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.010271 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/2d6655dbe57b5926c8bc62052e79532cd00a763570cb3fd19a168554e005fa452174b7ce0b8e447d693405b34e185adfe2dc72498771a8f203877f3b98548216", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/87619cdd4781cddcb9f3c40a26534e60f5902334e5bf6b3eaa4b9049b7f25dd70a8a6499db16eb840cc3795ff26e9072ba314be5a63c3f96995a2d47d5e4fb0c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 12:43:22.897219) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 12:43:22.897603) ========= 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.5H7489PY6m/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.MNmm6DEHQp/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.004986 ========= master_lag: 2.74 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-25 12:43:25.642163) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 12:43:25.642460) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 12:43:25.660633) ========= ========= Total master_lag: 3.81 =========