builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0187 starttime: 1461863621.73 results: success (0) buildid: 20160428082046 builduid: a96491546afd4d6ba94baefc565624e6 revision: 070a730a51d3bd2e923f81da9e10633b4d926143 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 10:13:41.729603) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 10:13:41.730023) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 34 secs) (at 2016-04-28 10:13:41.730308) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 34 secs) (at 2016-04-28 10:14:16.515364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-28 10:14:16.515625) ========= 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.XaL7R6InJW/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.JiOB4vJp9D/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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-28 10:14:17-- 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% 122M=0s 2016-04-28 10:14:17 (122 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.456480 ========= master_lag: 2.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-28 10:14:18.999947) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 10:14:19.000275) ========= 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.XaL7R6InJW/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.JiOB4vJp9D/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.028596 ========= master_lag: 1.52 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 10:14:20.551843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-28 10:14:20.552161) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 070a730a51d3bd2e923f81da9e10633b4d926143 --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 070a730a51d3bd2e923f81da9e10633b4d926143 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XaL7R6InJW/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.JiOB4vJp9D/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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-28 10:14:20,651 truncating revision to first 12 chars 2016-04-28 10:14:20,651 Setting DEBUG logging. 2016-04-28 10:14:20,651 attempt 1/10 2016-04-28 10:14:20,652 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/070a730a51d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 10:14:21,607 unpacking tar archive at: mozilla-aurora-070a730a51d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.205327 ========= master_lag: 4.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-28 10:14:26.071938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 10:14:26.072227) ========= script_repo_revision: 070a730a51d3bd2e923f81da9e10633b4d926143 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 10:14:26.072608) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-28 10:14:26.072877) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-28 10:14:27.300507) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-28 10:14:27.300769) ========= /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.XaL7R6InJW/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.JiOB4vJp9D/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:14:27 INFO - MultiFileLogger online at 20160428 10:14:27 in /builds/slave/test 10:14:27 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 10:14:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:14:27 INFO - {'append_to_log': False, 10:14:27 INFO - 'base_work_dir': '/builds/slave/test', 10:14:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:14:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:14:27 INFO - 'branch': 'Mozilla-Aurora', 10:14:27 INFO - 'buildbot_json_path': 'buildprops.json', 10:14:27 INFO - 'config_files': ('talos/mac_config.py',), 10:14:27 INFO - 'default_actions': ('clobber', 10:14:27 INFO - 'read-buildbot-config', 10:14:27 INFO - 'download-and-extract', 10:14:27 INFO - 'populate-webroot', 10:14:27 INFO - 'create-virtualenv', 10:14:27 INFO - 'install', 10:14:27 INFO - 'run-tests'), 10:14:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:14:27 INFO - 'download_minidump_stackwalk': True, 10:14:27 INFO - 'download_symbols': 'ondemand', 10:14:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:14:27 INFO - 'tooltool.py': '/tools/tooltool.py', 10:14:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:14:27 INFO - '/tools/misc-python/virtualenv.py')}, 10:14:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:14:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:14:27 INFO - 'installer_path': 'installer.exe', 10:14:27 INFO - 'log_level': 'info', 10:14:27 INFO - 'log_name': 'talos', 10:14:27 INFO - 'log_to_console': True, 10:14:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:14:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:14:27 INFO - 'opt_config_files': (), 10:14:27 INFO - 'pip_index': False, 10:14:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:27 INFO - 'cmd': ('bash', 10:14:27 INFO - '-c', 10:14:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:27 INFO - 'enabled': True, 10:14:27 INFO - 'halt_on_failure': False, 10:14:27 INFO - 'name': 'check_screen_resolution'},), 10:14:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:27 INFO - 'cmd': ('bash', 10:14:27 INFO - '-c', 10:14:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:27 INFO - 'enabled': True, 10:14:27 INFO - 'halt_on_failure': False, 10:14:27 INFO - 'name': 'check_screen_resolution'},), 10:14:27 INFO - 'run_cmd_checks_enabled': True, 10:14:27 INFO - 'sps_profile': False, 10:14:27 INFO - 'sps_profile_interval': 0, 10:14:27 INFO - 'suite': 'dromaeojs', 10:14:27 INFO - 'system_bits': '32', 10:14:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:14:27 INFO - 'title': 't-yosemite-r7-0187', 10:14:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:14:27 INFO - 'use_talos_json': True, 10:14:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:14:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:14:27 INFO - 'work_dir': 'build'} 10:14:27 INFO - ##### 10:14:27 INFO - ##### Running clobber step. 10:14:27 INFO - ##### 10:14:27 INFO - Running pre-action listener: _resource_record_pre_action 10:14:27 INFO - Running main action method: clobber 10:14:27 INFO - rmtree: /builds/slave/test/build 10:14:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:14:29 INFO - Running post-action listener: _resource_record_post_action 10:14:29 INFO - ##### 10:14:29 INFO - ##### Running read-buildbot-config step. 10:14:29 INFO - ##### 10:14:29 INFO - Running pre-action listener: _resource_record_pre_action 10:14:29 INFO - Running main action method: read_buildbot_config 10:14:29 INFO - Using buildbot properties: 10:14:29 INFO - { 10:14:29 INFO - "project": "", 10:14:29 INFO - "product": "firefox", 10:14:29 INFO - "script_repo_revision": "production", 10:14:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:14:29 INFO - "repository": "", 10:14:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:14:29 INFO - "buildid": "20160428082046", 10:14:29 INFO - "pgo_build": "False", 10:14:29 INFO - "basedir": "/builds/slave/test", 10:14:29 INFO - "buildnumber": 300, 10:14:29 INFO - "slavename": "t-yosemite-r7-0187", 10:14:29 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:14:29 INFO - "platform": "yosemite_r7", 10:14:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:14:29 INFO - "branch": "mozilla-aurora", 10:14:29 INFO - "repo_path": "releases/mozilla-aurora", 10:14:29 INFO - "revision": "070a730a51d3bd2e923f81da9e10633b4d926143", 10:14:29 INFO - "stage_platform": "macosx64", 10:14:29 INFO - "builduid": "a96491546afd4d6ba94baefc565624e6", 10:14:29 INFO - "slavebuilddir": "test" 10:14:29 INFO - } 10:14:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:14:29 INFO - Running post-action listener: _resource_record_post_action 10:14:29 INFO - ##### 10:14:29 INFO - ##### Running download-and-extract step. 10:14:29 INFO - ##### 10:14:29 INFO - Running pre-action listener: _resource_record_pre_action 10:14:29 INFO - Running main action method: download_and_extract 10:14:29 INFO - mkdir: /builds/slave/test/build/tests 10:14:29 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')]} 10:14:29 INFO - https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:14:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:14:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:14:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:30 INFO - Downloaded 1199 bytes. 10:14:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:14:30 INFO - Using the following test package requirements: 10:14:30 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:14:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:14:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:14:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'jsshell-mac.zip'], 10:14:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:14:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:14:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:14:30 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:14:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:14:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:14:30 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:14:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:30 INFO - https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:14:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:14:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:14:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:32 INFO - Downloaded 18110489 bytes. 10:14:32 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'] 10:14:32 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 10:14:35 INFO - Return code: 0 10:14:35 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 10:14:35 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')]} 10:14:35 INFO - https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:14:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:14:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:14:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:39 INFO - Downloaded 18110489 bytes. 10:14:39 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'] 10:14:39 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 10:14:43 INFO - Return code: 0 10:14: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')]} 10:14:43 INFO - https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:14:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:14:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:14:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:45 INFO - Downloaded 10840145 bytes. 10:14:45 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'] 10:14:45 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 10:14:45 INFO - Return code: 0 10:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:45 INFO - https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:14:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:14:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:14:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:14:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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 10:14:49 INFO - Downloaded 114075512 bytes. 10:14:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:14:49 INFO - mkdir: /builds/slave/test/properties 10:14:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:14:49 INFO - Writing to file /builds/slave/test/properties/build_url 10:14:49 INFO - Contents: 10:14:49 INFO - build_url:https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:14:50 INFO - Running post-action listener: _resource_record_post_action 10:14:50 INFO - Running post-action listener: set_extra_try_arguments 10:14:50 INFO - ##### 10:14:50 INFO - ##### Running populate-webroot step. 10:14:50 INFO - ##### 10:14:50 INFO - Running pre-action listener: _resource_record_pre_action 10:14:50 INFO - Running main action method: populate_webroot 10:14:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:14:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:14:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:14:50 INFO - '--tppagecycles', 10:14:50 INFO - '7'], 10:14:50 INFO - 'tests': ['tsvgm']}}, 10:14:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:14:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:14:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:14:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:14:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:14:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:14:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['damp', 'tps']}, 10:14:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'tests': ['damp', 'tps']}, 10:14:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['dromaeo_dom']}, 10:14:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:14:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['a11yr', 10:14:50 INFO - 'ts_paint', 10:14:50 INFO - 'tpaint', 10:14:50 INFO - 'sessionrestore', 10:14:50 INFO - 'sessionrestore_no_auto_restore', 10:14:50 INFO - 'tabpaint']}, 10:14:50 INFO - 'other-e10s': {'tests': ['a11yr', 10:14:50 INFO - 'ts_paint', 10:14:50 INFO - 'tpaint', 10:14:50 INFO - 'sessionrestore', 10:14:50 INFO - 'sessionrestore_no_auto_restore', 10:14:50 INFO - 'tabpaint']}, 10:14:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:14:50 INFO - 'ts_paint', 10:14:50 INFO - 'tpaint', 10:14:50 INFO - 'sessionrestore', 10:14:50 INFO - 'sessionrestore_no_auto_restore', 10:14:50 INFO - 'tabpaint']}, 10:14:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:14:50 INFO - 'ts_paint', 10:14:50 INFO - 'tpaint', 10:14:50 INFO - 'sessionrestore', 10:14:50 INFO - 'sessionrestore_no_auto_restore', 10:14:50 INFO - 'tabpaint']}, 10:14:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['a11yr', 10:14:50 INFO - 'ts_paint', 10:14:50 INFO - 'tpaint', 10:14:50 INFO - 'sessionrestore', 10:14:50 INFO - 'sessionrestore_no_auto_restore', 10:14:50 INFO - 'tabpaint']}, 10:14:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['a11yr', 10:14:50 INFO - 'ts_paint', 10:14:50 INFO - 'tpaint', 10:14:50 INFO - 'sessionrestore', 10:14:50 INFO - 'sessionrestore_no_auto_restore', 10:14:50 INFO - 'tabpaint']}, 10:14:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['tsvgx', 10:14:50 INFO - 'tsvgr_opacity', 10:14:50 INFO - 'tart', 10:14:50 INFO - 'tscrollx', 10:14:50 INFO - 'cart']}, 10:14:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:14:50 INFO - 'tsvgr_opacity', 10:14:50 INFO - 'tart', 10:14:50 INFO - 'tscrollx', 10:14:50 INFO - 'cart']}, 10:14:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'talos_options': ['--disable-e10s'], 10:14:50 INFO - 'tests': ['tp5o']}, 10:14:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'tests': ['tp5o']}, 10:14:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'talos_options': ['--disable-e10s', 10:14:50 INFO - '--xperf_path', 10:14:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:14:50 INFO - 'tests': ['tp5n']}, 10:14:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:50 INFO - 'talos_options': ['--xperf_path', 10:14:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:14:50 INFO - 'tests': ['tp5n']}}, 10:14:50 INFO - 'talos.zip': {'path': '', 10:14:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:14:50 INFO - Running post-action listener: _resource_record_post_action 10:14:50 INFO - ##### 10:14:50 INFO - ##### Running create-virtualenv step. 10:14:50 INFO - ##### 10:14:50 INFO - Running pre-action listener: _resource_record_pre_action 10:14:50 INFO - Running main action method: create_virtualenv 10:14:50 INFO - Creating virtualenv /builds/slave/test/build/venv 10:14:50 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 10:14:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:14:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:14:50 INFO - Using real prefix '/tools/python27' 10:14:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:14:51 INFO - Installing distribute.............................................................................................................................................................................................done. 10:14:54 INFO - Installing pip.................done. 10:14:54 INFO - Return code: 0 10:14:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:14:54 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')]} 10:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:54 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')]} 10:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:54 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:14:54 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 10:14:54 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 10:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:54 INFO - 'HOME': '/Users/cltbld', 10:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:54 INFO - 'LOGNAME': 'cltbld', 10:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:54 INFO - 'MOZ_NO_REMOTE': '1', 10:14:54 INFO - 'NO_EM_RESTART': '1', 10:14:54 INFO - 'PAGER': '/bin/cat', 10:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:54 INFO - 'PWD': '/builds/slave/test', 10:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:54 INFO - 'SHELL': '/bin/bash', 10:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:14:54 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:54 INFO - 'USER': 'cltbld', 10:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:54 INFO - 'XPC_FLAGS': '0x0', 10:14:54 INFO - 'XPC_SERVICE_NAME': '0', 10:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:54 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 10:14:54 INFO - Cleaning up... 10:14:54 INFO - Return code: 0 10:14:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:14:54 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')]} 10:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:54 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')]} 10:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:54 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:14:54 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 10:14:54 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 10:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:54 INFO - 'HOME': '/Users/cltbld', 10:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:54 INFO - 'LOGNAME': 'cltbld', 10:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:54 INFO - 'MOZ_NO_REMOTE': '1', 10:14:54 INFO - 'NO_EM_RESTART': '1', 10:14:54 INFO - 'PAGER': '/bin/cat', 10:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:54 INFO - 'PWD': '/builds/slave/test', 10:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:54 INFO - 'SHELL': '/bin/bash', 10:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:14:54 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:54 INFO - 'USER': 'cltbld', 10:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:54 INFO - 'XPC_FLAGS': '0x0', 10:14:54 INFO - 'XPC_SERVICE_NAME': '0', 10:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:54 INFO - Downloading/unpacking psutil>=0.7.1 10:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:14:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:14:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:14:58 INFO - Installing collected packages: psutil 10:14:58 INFO - Running setup.py install for psutil 10:14:58 INFO - building 'psutil._psutil_osx' extension 10:14:58 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 10:14:58 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 10:14:58 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 10:14:58 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 10:14:59 INFO - building 'psutil._psutil_posix' extension 10:14:59 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 10:14:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:14:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:14:59 INFO - ^ 10:14:59 INFO - 1 warning generated. 10:14:59 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 10:14:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:14:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:14:59 INFO - Successfully installed psutil 10:14:59 INFO - Cleaning up... 10:14:59 INFO - Return code: 0 10:14:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:14:59 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')]} 10:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:59 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')]} 10:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:59 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:14:59 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 10:14:59 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 10:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:59 INFO - 'HOME': '/Users/cltbld', 10:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:59 INFO - 'LOGNAME': 'cltbld', 10:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:59 INFO - 'MOZ_NO_REMOTE': '1', 10:14:59 INFO - 'NO_EM_RESTART': '1', 10:14:59 INFO - 'PAGER': '/bin/cat', 10:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:59 INFO - 'PWD': '/builds/slave/test', 10:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:59 INFO - 'SHELL': '/bin/bash', 10:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:14:59 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:59 INFO - 'USER': 'cltbld', 10:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:59 INFO - 'XPC_FLAGS': '0x0', 10:14:59 INFO - 'XPC_SERVICE_NAME': '0', 10:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:14: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 10:14: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 10:14: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 10:14: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 10:14: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 10:14: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 10:15:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:15:02 INFO - Installing collected packages: mozsystemmonitor 10:15:02 INFO - Running setup.py install for mozsystemmonitor 10:15:02 INFO - Successfully installed mozsystemmonitor 10:15:02 INFO - Cleaning up... 10:15:02 INFO - Return code: 0 10:15:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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')]} 10:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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', '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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:15: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:15: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 blobuploader==1.2.4 10:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:02 INFO - 'HOME': '/Users/cltbld', 10:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:02 INFO - 'LOGNAME': 'cltbld', 10:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:02 INFO - 'MOZ_NO_REMOTE': '1', 10:15:02 INFO - 'NO_EM_RESTART': '1', 10:15:02 INFO - 'PAGER': '/bin/cat', 10:15:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:02 INFO - 'PWD': '/builds/slave/test', 10:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:02 INFO - 'SHELL': '/bin/bash', 10:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:02 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:02 INFO - 'USER': 'cltbld', 10:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:02 INFO - 'XPC_FLAGS': '0x0', 10:15:02 INFO - 'XPC_SERVICE_NAME': '0', 10:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:15:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:15:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15:06 INFO - Downloading docopt-0.6.1.tar.gz 10:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:15:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:15:06 INFO - Running setup.py install for blobuploader 10:15:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:15:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:15:06 INFO - Running setup.py install for requests 10:15:06 INFO - Running setup.py install for docopt 10:15:07 INFO - Successfully installed blobuploader requests docopt 10:15:07 INFO - Cleaning up... 10:15:07 INFO - Return code: 0 10:15:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:07 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:15:07 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 10:15:07 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 10:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:07 INFO - 'HOME': '/Users/cltbld', 10:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:07 INFO - 'LOGNAME': 'cltbld', 10:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:07 INFO - 'MOZ_NO_REMOTE': '1', 10:15:07 INFO - 'NO_EM_RESTART': '1', 10:15:07 INFO - 'PAGER': '/bin/cat', 10:15:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:07 INFO - 'PWD': '/builds/slave/test', 10:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:07 INFO - 'SHELL': '/bin/bash', 10:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:07 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:07 INFO - 'USER': 'cltbld', 10:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:07 INFO - 'XPC_FLAGS': '0x0', 10:15:07 INFO - 'XPC_SERVICE_NAME': '0', 10:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:07 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-h6J6bF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:07 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-psBFhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:07 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-8Sqh1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:07 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-v6fFrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:07 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-5rVlRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:07 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-wO5iYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-CxBmog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-gaH94D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-4ltKXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-WRb4rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-TMNgWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-jsYcr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-LsDlC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Ww7dL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:08 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-YAwxSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:09 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-KX5frW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:09 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-wRrX7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:15:09 INFO - Running setup.py install for manifestparser 10:15:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:15:09 INFO - Running setup.py install for mozcrash 10:15:09 INFO - Running setup.py install for mozdebug 10:15:09 INFO - Running setup.py install for mozdevice 10:15:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:15:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:15:09 INFO - Running setup.py install for mozfile 10:15:09 INFO - Running setup.py install for mozhttpd 10:15:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Running setup.py install for mozinfo 10:15:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Running setup.py install for mozInstall 10:15:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Running setup.py install for mozleak 10:15:10 INFO - Running setup.py install for mozlog 10:15:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Running setup.py install for moznetwork 10:15:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:15:10 INFO - Running setup.py install for mozprocess 10:15:10 INFO - Running setup.py install for mozprofile 10:15:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:15:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:15:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:15:11 INFO - Running setup.py install for mozrunner 10:15:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:15:11 INFO - Running setup.py install for mozscreenshot 10:15:11 INFO - Running setup.py install for moztest 10:15:11 INFO - Running setup.py install for mozversion 10:15:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:15:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:15:11 INFO - Cleaning up... 10:15:11 INFO - Return code: 0 10:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:11 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')]} 10:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:11 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')]} 10:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:11 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:15:11 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 10:15:11 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 10:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:11 INFO - 'HOME': '/Users/cltbld', 10:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:11 INFO - 'LOGNAME': 'cltbld', 10:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:11 INFO - 'MOZ_NO_REMOTE': '1', 10:15:11 INFO - 'NO_EM_RESTART': '1', 10:15:11 INFO - 'PAGER': '/bin/cat', 10:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:11 INFO - 'PWD': '/builds/slave/test', 10:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:11 INFO - 'SHELL': '/bin/bash', 10:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:11 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:11 INFO - 'USER': 'cltbld', 10:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:11 INFO - 'XPC_FLAGS': '0x0', 10:15:11 INFO - 'XPC_SERVICE_NAME': '0', 10:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Rs1HY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-KIFijU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-VLVUEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-juL2RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-RV1ki6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-CYaAqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-vEsBA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-e_PBfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Zrnt5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:12 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)) 10:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-JifI9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-cRSjEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-leSB3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-EBWliP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-pwSW1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Z7rRAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-rSDgkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:13 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)) 10:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:13 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-NOkGaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:13 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)) 10:15:13 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)) 10:15:13 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)) 10:15:13 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)) 10:15:13 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)) 10:15:13 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)) 10:15:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:13 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 10:15:13 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 10:15:13 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 10:15:13 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 10:15:13 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 10:15:13 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 10:15:16 INFO - Downloading blessings-1.6.tar.gz 10:15:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:15:16 INFO - Installing collected packages: blessings 10:15:16 INFO - Running setup.py install for blessings 10:15:16 INFO - Successfully installed blessings 10:15:16 INFO - Cleaning up... 10:15:16 INFO - Return code: 0 10:15:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:15:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:16 INFO - Reading from file tmpfile_stdout 10:15:16 INFO - Current package versions: 10:15:16 INFO - blessings == 1.6 10:15:16 INFO - blobuploader == 1.2.4 10:15:16 INFO - docopt == 0.6.1 10:15:16 INFO - manifestparser == 1.1 10:15:16 INFO - mozInstall == 1.12 10:15:16 INFO - mozcrash == 0.17 10:15:16 INFO - mozdebug == 0.1 10:15:16 INFO - mozdevice == 0.48 10:15:16 INFO - mozfile == 1.2 10:15:16 INFO - mozhttpd == 0.7 10:15:16 INFO - mozinfo == 0.9 10:15:16 INFO - mozleak == 0.1 10:15:16 INFO - mozlog == 3.1 10:15:16 INFO - moznetwork == 0.27 10:15:16 INFO - mozprocess == 0.22 10:15:16 INFO - mozprofile == 0.28 10:15:16 INFO - mozrunner == 6.11 10:15:16 INFO - mozscreenshot == 0.1 10:15:16 INFO - mozsystemmonitor == 0.0 10:15:16 INFO - moztest == 0.7 10:15:16 INFO - mozversion == 1.4 10:15:16 INFO - psutil == 3.1.1 10:15:16 INFO - requests == 1.2.3 10:15:16 INFO - wsgiref == 0.1.2 10:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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')]} 10:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:16 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:15:16 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 10:15:16 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 10:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:16 INFO - 'HOME': '/Users/cltbld', 10:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:16 INFO - 'LOGNAME': 'cltbld', 10:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:16 INFO - 'MOZ_NO_REMOTE': '1', 10:15:16 INFO - 'NO_EM_RESTART': '1', 10:15:16 INFO - 'PAGER': '/bin/cat', 10:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:16 INFO - 'PWD': '/builds/slave/test', 10:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:16 INFO - 'SHELL': '/bin/bash', 10:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:16 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:16 INFO - 'USER': 'cltbld', 10:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:16 INFO - 'XPC_FLAGS': '0x0', 10:15:16 INFO - 'XPC_SERVICE_NAME': '0', 10:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 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)) 10:15:17 INFO - Cleaning up... 10:15:17 INFO - Return code: 0 10:15:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:17 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:15:17 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 10:15:17 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 10:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:17 INFO - 'HOME': '/Users/cltbld', 10:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:17 INFO - 'LOGNAME': 'cltbld', 10:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:17 INFO - 'MOZ_NO_REMOTE': '1', 10:15:17 INFO - 'NO_EM_RESTART': '1', 10:15:17 INFO - 'PAGER': '/bin/cat', 10:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:17 INFO - 'PWD': '/builds/slave/test', 10:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:17 INFO - 'SHELL': '/bin/bash', 10:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:17 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:17 INFO - 'USER': 'cltbld', 10:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:17 INFO - 'XPC_FLAGS': '0x0', 10:15:17 INFO - 'XPC_SERVICE_NAME': '0', 10:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:17 INFO - Downloading/unpacking jsonschema 10:15:17 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 10:15:17 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 10:15:17 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 10:15:17 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 10:15:17 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 10:15:17 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 10:15:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:15:19 INFO - Downloading/unpacking functools32 (from jsonschema) 10:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - Downloading functools32-3.2.3-2.tar.gz 10:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:15:20 INFO - warning: no files found matching '*.txt' 10:15:20 INFO - no previously-included directories found matching 'build' 10:15:20 INFO - no previously-included directories found matching 'dist' 10:15:20 INFO - no previously-included directories found matching '.git*' 10:15:20 INFO - Installing collected packages: jsonschema, functools32 10:15:20 INFO - Running setup.py install for functools32 10:15:20 INFO - warning: no files found matching '*.txt' 10:15:20 INFO - no previously-included directories found matching 'build' 10:15:20 INFO - no previously-included directories found matching 'dist' 10:15:20 INFO - no previously-included directories found matching '.git*' 10:15:20 INFO - Successfully installed jsonschema functools32 10:15:20 INFO - Cleaning up... 10:15:20 INFO - Return code: 0 10:15:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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')]} 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:20 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 0x1069d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069e0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3e1574780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, '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.XaL7R6InJW/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.JiOB4vJp9D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 10:15:20 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 10:15:20 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 10:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:20 INFO - 'HOME': '/Users/cltbld', 10:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:20 INFO - 'LOGNAME': 'cltbld', 10:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:20 INFO - 'MOZ_NO_REMOTE': '1', 10:15:20 INFO - 'NO_EM_RESTART': '1', 10:15:20 INFO - 'PAGER': '/bin/cat', 10:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:20 INFO - 'PWD': '/builds/slave/test', 10:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:20 INFO - 'SHELL': '/bin/bash', 10:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:20 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:20 INFO - 'USER': 'cltbld', 10:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:20 INFO - 'XPC_FLAGS': '0x0', 10:15:20 INFO - 'XPC_SERVICE_NAME': '0', 10:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:20 INFO - Downloading/unpacking flake8 10:15:20 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 10:15:20 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 10:15:20 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 10:15:20 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 10:15:20 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 10:15:20 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 10:15:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:15:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:15:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 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 10:15:23 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 10:15:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:15:24 INFO - no previously-included directories found matching 'docs/_build' 10:15:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:15:24 INFO - Running setup.py install for pep8 10:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:15:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:15:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:15:24 INFO - no previously-included directories found matching 'docs/_build' 10:15:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:15:24 INFO - Cleaning up... 10:15:24 INFO - Return code: 0 10:15:24 INFO - Running post-action listener: _resource_record_post_action 10:15:24 INFO - Running post-action listener: _start_resource_monitoring 10:15:24 INFO - Starting resource monitoring. 10:15:24 INFO - ##### 10:15:24 INFO - ##### Running install step. 10:15:24 INFO - ##### 10:15:24 INFO - Running pre-action listener: _resource_record_pre_action 10:15:24 INFO - Running main action method: install 10:15:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:25 INFO - Reading from file tmpfile_stdout 10:15:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:15:25 INFO - Reading from file tmpfile_stdout 10:15:25 INFO - Output received: 10:15:25 INFO - Usage: mozinstall [options] installer 10:15:25 INFO - Options: 10:15:25 INFO - -h, --help show this help message and exit 10:15:25 INFO - -d DEST, --destination=DEST 10:15:25 INFO - Directory to install application into. [default: 10:15:25 INFO - "/builds/slave/test"] 10:15:25 INFO - --app=APP Application being installed. [default: firefox] 10:15:25 INFO - mkdir: /builds/slave/test/build/application 10:15:25 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'] 10:15:25 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 10:15:56 INFO - Reading from file tmpfile_stdout 10:15:56 INFO - Output received: 10:15:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:15:56 INFO - Running post-action listener: _resource_record_post_action 10:15:56 INFO - ##### 10:15:56 INFO - ##### Running run-tests step. 10:15:56 INFO - ##### 10:15:56 INFO - Running pre-action listener: _resource_record_pre_action 10:15:56 INFO - Running main action method: run_tests 10:15:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:56 INFO - 2016-04-28 10:15:56.584 screenresolution[1646:8352] starting screenresolution argv=screenresolution get 10:15:56 INFO - 2016-04-28 10:15:56.587 screenresolution[1646:8352] Display 0: 1600x1200x32@60 10:15:56 INFO - 2016-04-28 10:15:56.593 screenresolution[1647:8354] starting screenresolution argv=screenresolution list 10:15:56 INFO - Available Modes on Display 0 10:15:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:56 INFO - Intel Iris: 10:15:56 INFO - Chipset Model: Intel Iris 10:15:56 INFO - Type: GPU 10:15:56 INFO - Bus: Built-In 10:15:56 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:56 INFO - Vendor: Intel (0x8086) 10:15:56 INFO - Device ID: 0x0a2e 10:15:56 INFO - Revision ID: 0x0009 10:15:56 INFO - Displays: 10:15:56 INFO - Display: 10:15:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:56 INFO - Display Serial Number: 1600x1200 60 10:15:56 INFO - Main Display: Yes 10:15:56 INFO - Mirror: Off 10:15:56 INFO - Online: Yes 10:15:56 INFO - Rotation: Supported 10:15:56 INFO - Return code: 0 10:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:15:56 INFO - Python 2.7.3 10:15:56 INFO - Return code: 0 10:15:56 INFO - grabbing minidump binary from tooltool 10:15:56 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')]} 10:15:56 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 0x10686fe00>, '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 0x1066d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10686ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:15:56 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 10:15:56 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 10:15:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:15:56 INFO - Return code: 0 10:15:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:15:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:15:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:15:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:15:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:15:58 INFO - Return code: 0 10:15:58 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/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--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 10:15:58 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/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0187 --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 10:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XaL7R6InJW/Render', 10:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:58 INFO - 'HOME': '/Users/cltbld', 10:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:58 INFO - 'LOGNAME': 'cltbld', 10:15:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:15:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:58 INFO - 'MOZ_NO_REMOTE': '1', 10:15:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:15:58 INFO - 'NO_EM_RESTART': '1', 10:15:58 INFO - 'PAGER': '/bin/cat', 10:15:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:58 INFO - 'PWD': '/builds/slave/test', 10:15:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:58 INFO - 'SHELL': '/bin/bash', 10:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JiOB4vJp9D/Listeners', 10:15:58 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 10:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:58 INFO - 'USER': 'cltbld', 10:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:58 INFO - 'XPC_FLAGS': '0x0', 10:15:58 INFO - 'XPC_SERVICE_NAME': '0', 10:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:58 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/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--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 10:15:58 INFO - mozversion application_buildid: 20160428082046 10:15:58 INFO - mozversion application_changeset: 070a730a51d3bd2e923f81da9e10633b4d926143 10:15:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:15:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:15:58 INFO - mozversion application_name: Firefox 10:15:58 INFO - mozversion application_remotingname: firefox-dev 10:15:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:15:58 INFO - mozversion application_vendor: Mozilla 10:15:58 INFO - mozversion application_version: 48.0a2 10:15:58 INFO - mozversion platform_buildid: 20160428082046 10:15:58 INFO - mozversion platform_changeset: 070a730a51d3bd2e923f81da9e10633b4d926143 10:15:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:15:58 INFO - mozversion platform_version: 48.0a2 10:15:58 INFO - using testdate: 1461863758 10:15:58 INFO - actual date: 1461863758 10:15:58 INFO - starting webserver on 'localhost:49275' 10:15:58 INFO - SUITE-START | Running 2 tests 10:15:58 INFO - TEST-START | dromaeo_css 10:15:58 INFO - operating with platform_type : mac_ 10:15:58 INFO - Initialising browser for dromaeo_css test... 10:15:58 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpO_gbF5/profile http://localhost:49275/getInfo.html) 10:16:00 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 10:16:00 INFO - PROCESS | 1665 | colorDepth:24 10:16:00 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 10:16:00 INFO - PROCESS | 1665 | __metrics 10:16:00 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:16:00 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:16:01 INFO - TEST-INFO | 1665: exit 0 10:16:01 INFO - Browser initialized. 10:16:01 INFO - Running cycle 1/1 for dromaeo_css test... 10:16:01 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpO_gbF5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:13 INFO - PROCESS | 1668 | RSS: Main: 211476480 10:16:13 INFO - PROCESS | 1668 | 10:17:04 INFO - PROCESS | 1668 | 1461863824637 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] 10:19:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:19:16 INFO - PROCESS | 1668 | RSS: Main: 310726656 10:19:16 INFO - PROCESS | 1668 | 10:22:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:22:19 INFO - PROCESS | 1668 | RSS: Main: 357543936 10:22:19 INFO - PROCESS | 1668 | 10:25:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:25:22 INFO - PROCESS | 1668 | RSS: Main: 360554496 10:25:22 INFO - PROCESS | 1668 | 10:28:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:28:25 INFO - PROCESS | 1668 | RSS: Main: 361250816 10:28:25 INFO - PROCESS | 1668 | 10:31:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:31:29 INFO - PROCESS | 1668 | RSS: Main: 331546624 10:31:29 INFO - PROCESS | 1668 | 10:34:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 10:34:32 INFO - PROCESS | 1668 | RSS: Main: 369917952 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | __start_tp_report 10:34:32 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 10:34:32 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 10:34:32 INFO - PROCESS | 1668 | |i|pagename|runs| 10:34:32 INFO - PROCESS | 1668 | |0;dojo.html;2815.88736450542;3453.551038365012;3471.9305613887723;3479.478078288255;3520.4895290182926;5708.600397972144;5711.086226203803;5714.371419143891;5715.714214289284;5754.16564598133;5682.403347648499;5726.770929162829;5736.196932429463;5742.54059675226;5743.224664670268;5090.10923088458;5131.255967884655;5201.973990130044;5211;5242.161254199329;5218.13900706383;5230.712310822903;5234.057869583474;5237.711925844077;5238.895222095575;5168.534831865131;5234.084035293828;5235.973820130913;5238.4499627539135;5240;4806.485956923558;4885.828995985124;5271.65734227274;5273.894522109536;5285.127953887604;5056.974715126437;5065.493450654942;5089.1348470759685;5195.1687729963405;5198.246254293106;5531;5555.944440555583;5587.078132108198;5602.38373778884;5639.830805075813;6220.657863817488;6224.595401298901;6239.875202495925;6240.781572644935;6242.625442473466;6087.6956152192215;6199.225096862892;6234.937650623481;6279.780207692709;6288.308286088527;5762.279715035621;5787.276590426197;5793.449622285876;5808.6224395414165;5829.154772557998;6255.437010669061;6277.843053923689;6278.089676996809;6281.152044474029;6283;5819.243498345188;5900;6033.456988870935;6117.908231376533;6157.076438534255;6263.154474146023;6298.0552917062805;6301.495880329654;6301.905471417932;6312.621242725451;6168.938310616926;6194.845128871724;6231.937680623227;6239.438450539473;6248.843778905472;6056.091586262008;6135.937063216329;6141.977005061692;6285.214348206474;6299.149614802035;4834.371531700856;4856.635752318591;4874.2444921037295;4875.341828853059;4877.561019508261;3771.906147217276;4431.069475410135;4433.512313645497;4483.103379324083;4498.977505112453;5675.262215911905;5708.229389032427;5708.229389032511;5724.79963201286;5743.9138412923;6135.294441139237;6226.750929962751;6300.873982520324;6309.369063093745;6315.684215789192;5597.80407685729;5625.746841392147;5631.831045068655;5634.859128521738;5638.407967163471;5334.0665383557725;5342.465753424626;5345;5420.539254163361;5427.83716488506;6274.856824689819;6324.430801227912;6331.176946996861;6332.303446620868;6339.366063393716;6306.936930630635;6332.810015699536;6335.176427064452;6341.4292713656;6362.045693146065;6236.095766113979;6275.246970363585;6320.841978950379;6337.968310158604;6343.207099112611;6191.102290167991;6201.1938448003275;6220.968895155495;6222.968885155546;6230.59501132425;6271.529635277281;6319.8104056878365;6329.398707122816;6341.55609107358;6345.524085693499;5978.641281523122;6095.299040610386;6101.908471372844;6148.52435415506;6154.076888466766;6127.380013378952;6131.540134489842;6136.115092106622;6147.170132032118;6159.599626024294;5950.940490595039;5964.731587078505;5971.28344598651;5972.402759723993;6003.759849606142;5777.508911742549;5944.316403613467;6008.519318454601;6055.848603784764;6119.350229749377;5962.850928726816;5976.551758618034;5982.042873140276;6007.158997740233;6025.096235564701 10:34:32 INFO - PROCESS | 1668 | |1;ext.html;512.4081685653072;538.7171734839209;566.2214455124206;596.9344719675374;620.9037599172127;26456.867715661418;30785.692143078562;30822.691773082264;30830;30895.38209235814;31969.200769980762;32594.83702581484;38554.4216836747;39646.8017659912;39708.808735737904;9319.76700582484;9530.618775248982;9560.426374417522;9574.99462556432;9591.856122158173;9487.71536853892;9650.034996500364;9651.710448686516;9656.565454554528;9667.95166024169;8368.832623347529;8568.143185681445;10328.122109620692;11338.943305283463;11344.376059316734;11283.887161128365;11425.657230283105;11429.542818287258;11443.25618834773;11446.885531144666;8416.074231834493;11162.665120046411;11331.206815522917;11344.319340839576;11344.943275283571;31439;32053.358932821448;32145.6785432145;36337.091572710626;37582.8120859394;3209.5988001499813;3312.1719570107475;3385.9322813543836;3525.1715846775987;3545.822708864572;22303.55392892133;26663.20010399691;26696;27177.728222717717;27326.316842078908;2655.9867200664066;2754.256350785277;2807.2841425436586;2871.3682989742224;2882.351470919047;451137;454139;454240.72879635723;456780;457132.7143364295;57887;57995.710021449624;58108.709456453296;58158.418415816144;58201.70899145562;62132.378676213564;62232.377676223565;62288;62331;62425.68787156126;17042.74435883463;17313.134343282785;17342.913285433493;17349.21928513195;17370.39203627867;13651.590452286448;13662;13707.177569345871;13714.86285137136;13725.725485490235;13314.201147931155;13692.65768355779;14875.404983800743;14902.552923412315;15449.613759656098;6086.1268875535325;6275.215598050244;6326.145970294044;6336.936630633727;6353.791335375606;955.2930831185012;998.9850152247722;1105.9004689577816;1109.1902910874944;1166.6733314671906;29036.70963290382;29228.68470918814;29681.703182968326;29882;29894.25264368408;1082.231615552966;1131.7453572946185;1199.220506670671;1210.9273443593413;1268.5750273658243;13241.933790330986;14233.28833558318;15095.773563396559;15162.924185379003;15206.467773628092;610447;643674;646147;647574;652006;60036.69981650064;60664.78670426655;60776.08835867466;60868.695656521435;60945.39054609486;1335.3256605414203;1528.6178455386153;1536.869366103849;1564.2647955460916;1580.6364536156748;29163.979260725773;29335.853320734113;29486.852565737034;30147.397052058397;30402.93589724349;53768.462315376346;54226.45773542372;54674.26628668471;54760.17859732187;54822;625933;629986;630660;631781;631917;31319.84340078285;31493.370132597676;33122.33755324928;42293.78853105715;42741;40608.60805224646;41385.79307103445;42006.579934200265;43202.895724906084;44331.77834110809;32119;32934.505982409806;34012.82993585016;36948;37076.44385334276;36556.63443365532;36684.81657591695;37282.06794830151;37395.43906841454;37441;35486.935391938285;36031.45952810658;36530.90407287786;37067.443988339655;37192.62807372;26950.32624184411;31283.530747038356;32393.704251830604;32555.837220813744;32627.021189364357;22547.88726056359;22812.543749125256;23955;25015.74984250207;25150.496990060463 10:34:32 INFO - PROCESS | 1668 | |2;jquery.html;328.2696001396891;400.84567441535;402.3602472069409;404.2037186742118;410.67966985751104;15300.491972425625;17291.308347666094;19617.705734413994;20434.897825510852;20441.897790511044;16304.695624350054;19740.901295493502;20177.798222017776;20189.798102019013;20204.69692954607;4036.8183431745642;5524.668519888799;5732.398098199692;5733.913991290134;5735.168400581913;4515.638748900096;6406.743730250784;6425.678716064202;6431.131797207364;6439.871202575969;6099.9085013724825;6304.148939893124;6377.202849643794;6427.100205971168;6439.35606439357;6398.71205795738;6404.711787969506;6412.39082287182;6412.487001039905;6428.871422571546;5286.233496143038;5575.107982722745;5587.161925711135;5590.748416321276;5597.188407680904;20046.997650117435;25142.86857091417;26142.6078608821;26363;26415.735842641712;2228.810551103158;2244.86530808152;2391.2228525729206;2450.497647982177;2563.218218443374;14022.789658155136;15046;15168.696626067416;15256;15677.686446271126;1664.585102662739;1666.7666526686273;1698.8980661160247;1745.4763570928671;1778.7776527934009;111978.88021119765;124195;135752.9637055445;135770;142016.28991855078;11553.30680159193;11572.305661660328;11744.58893938708;11749.29504229749;11853.88146118545;15146.16696081704;15260.31328590216;15275.847241527665;15283.847161528465;15313.157776322409;7679.308862202429;8716.520591367536;8781.385303028854;8920.776980575407;9025.323100767337;7631;8705.564721763887;8967.10328967098;8975.50634715097;9087.273018158663;4526.773661316921;4601.665517000032;4615.630749540029;4622.007852829249;4681;1677.9496615101566;1855.7844611779037;1925.4993701637675;1978.881267123977;2031.0657097735207;823.3577809308749;972.3048020665258;975.5219942228399;1023.4115383654429;1032.6488993742164;19347.613047738967;20169.193232270838;20432.795672043387;20441.79558204399;20495.28266510665;968.8788901387327;972.9367591106698;980.352967041749;982.4105536677936;1037.8547003419528;9210.263178945668;10701.625443109453;10713.946430267955;10725.41010244444;10921.344719316867;601471;611405;611838.2834489063;630983;632677;99951;132613.010804838;135037;138872;141143;984.4831463481729;1012.8075665623655;1021.0759262867117;1025.1644909398817;1025.3335332034237;14672.486462974171;17584.472465826046;18045.458636240935;18142.72785908186;18287.359942401952;51702.707432312985;52506.47493525016;53690.73154634202;54826.725866372006;54929.90140197254;79699.60150199212;80104;81168.18831811607;81774;82690.58654706688;7903.802404939923;7993.800154996172;8210.745123121327;8308.459950103224;8349.874751878848;9283.442993420416;12302.446389912655;12976.416061277454;13380.799288010883;13408.731825363633;11006.504707288352;11373.260738052;11602.129840262183;11688.532458701897;11748.88251117478;11037.33775973412;11180.72048198769;11522.884771152181;11527.193096483166;11748.412579371168;11484.540618375506;11693.76612467763;11697.181197316106;11730.354830484406;11763.353015583883;8244.793880153044;8439.746807595537;8468.957655211685;8502.617382217939;8729;10722.89277107219;10934.781304374028;11584.594539191088;11773.587924422603;11777.175597708421 10:34:32 INFO - PROCESS | 1668 | |3;mootools.html;1419.6025112968373;1594.8644365228952;1609.4769200009996;1859.6373707127116;1971.3100414854803;7940.841183176341;8721.302295816335;8936;8938.821223575525;8965.686200982967;8570.100139485343;8786.86819697705;8798.120187981198;8808;8845.557722113901;2426.4661774409647;2449.6815413996246;2460.5694003549397;2461.384653836541;2466.235467005225;2163.6105501009893;2366.4557151854997;2418.4558474343307;2472.814538909589;2485.3041148478455;2158.1691048946113;2412.9276121716375;2444.340028192387;2457.103157347573;2475.2945410557995;2413;2430.1980346485707;2445.8410203336816;2445.9143929962543;2462.384403899025;2428.8299819012677;2438.5854404751235;2442.719087304947;2448.24104527597;2451.3871532116973;8701.695440659547;8849.380543361967;8952.955235223848;8953.194212520839;8953.776155596162;5259.658122222043;5494.09347457669;5529.4470552944385;5529.972350138264;5536;3491.8952431427097;3666.1934374437583;3704.3147017801793;3710.9257814843822;3724.5344331958504;1025.492381271268;1053.4258828938248;1053.778706471642;1064.393295821382;1085.403028334413;11775.05799536035;11860;11878.584249551224;11878.58424955131;11889.167758256925;4612.400387949545;4625.32932724753;4730;4734.976325118352;4772.045590881838;8016.7194148204535;9273.304502162366;9292.907070929205;9306.302027347976;9325.720228393158;4599.839005634787;5341.332333458317;5382.838514844561;5386.703731294738;5393.919091213635;4411.360352748898;4496.64026877849;4525.230710779176;4947.975260123676;4967.205247160437;3829.5404551454003;3877.3796192609047;3899.1032062625754;3936.232434675268;3952.8023598819946;1233.6545767185203;1244.9564265250672;1247.6756043428775;1260.9621711348675;1261.6215135459327;800.7277525641193;923.4920793563513;937.4796987671771;941.0730430525927;962.0283513651123;5876.177335173079;5913.940860591339;6090.665013424215;6159.753609855646;6222.004479283293;921.893982192043;928.4522131942186;928.6935311347239;989.1641562879355;1010.2726037253166;3748.8687895923513;4089.3048181809163;4116.732412393185;4128.948872815859;4171.874843754692;10387.220958427997;10853.240273180802;10983.725406864893;11003.33979961205;11011.83482247767;10691.946540267405;10777.137828973664;10910.290831096112;10915.672529824118;10946.4526773661;7821.256980586835;8279.793005174799;8280.171982801792;8371.246587807127;8383.916160838435;5769.74036168365;5866.735996880236;5900.380460051632;6019.879602408015;6097.878042439215;8299.875501867356;10149.492525373555;10171.23715721309;10293.125084367912;10301.536430860479;9803.558839852367;10102.79794404093;10315.27793054479;10498.580056797642;10500.842487362543;5562.471565201299;6756.155480564929;6913.688884000131;7108.751193708195;7123.109611298588;8317.625706843328;8320.251177394064;8394.0766515682;8415.368847336353;8418.242358187792;7278.636068196463;7402.222766609429;7446.180920098893;7472.589007604634;7520.135184453858;7250.709971601288;7301.379382752313;7346.5592064476305;7433.70265189408;7484.251574842472;7064.32888875556;7124.786256412316;7143.392811611071;7146.642667866482;7154.67803948834;4873.6832105913;4952.975235123801;4989.176785830297;5075.390953085654;5093.7707803148205;5026.547610715053;5030.0945829751;5088.389393272831;5119.078565858182;5273.6044796639635 10:34:32 INFO - PROCESS | 1668 | |4;prototype.html;280.1874563765082;319.4186580423629;320.86363295599364;326.8659849461721;332.6174898866302;5049.823256186034;5286.365636123665;5331.3069300990865;5337.679739215645;5357.901630165817;4949.653524253304;5225.817096401626;5266.736663166846;5345.732713364336;5350.143976963686;3834.7699138051735;3917.3536366499493;3972.860949866755;4072.8370865165357;4099.323611604082;3740.2706472237924;3836.5204349456317;4066.3365495600283;4123.9381409278885;4138.586141385852;3551.9112022199392;3960.6633436157963;4057.573954734755;4072.328065869143;4078.6940979426513;3880.553736320317;3906.609339066099;4085.59144085592;4103.651189648884;4125.360569111793;3097.860596273151;3150.7951983121025;3279.852051781881;3619.60184379718;3629.364861149288;4305;4318.373835793793;4659.13291967666;5153.093355458504;5180.559652429548;3542.3446662367546;4297.914041719148;4419.762466509383;4702.623790096818;4727.645426592985;4450.977745111286;4638;5577.3028371453565;5616.606837521375;5616.691081990489;3151.5272709093592;3895.519702513055;3913.98043009786;3920.2355540669582;3922.529051605648;5354.5180933715765;5491.972540137313;5917.615355001954;5930.97034514829;5949.167116603679;3449.619762363961;3783.1487915219404;3886.455896174538;3910.569837317893;3919.9608003920166;3357.362101200813;3983.581723918947;4020.5175378954714;4030.1939612077704;4033.7176397651883;4259.190753756776;4366.104948485553;4567.2007398705755;4593.839215627504;4609.907801844011;5087.720175390315;5338.332708411449;5572.3870374258795;5619.690916999604;5679.545636349082;1391.4990603382773;1429.9285035748378;1447.8769304609016;1453.556665217119;1459.8613131752466;800.6437135474774;902.2421166220406;923.9168474837162;939.2204470289644;954.3823219642778;4961.627877909099;5638.85902852432;5640.71796410186;5653.689047102366;5701.2588363513305;5246.291750613618;6125.816225513242;6226.40849119333;6245.6252624842655;6290.308066112724;4899.755012249373;5369.301990741179;5629.802956896489;5647.040003199384;5675.347335056439;2426.017462927517;2776.514110030777;3134.3104517006223;3259.788113772597;3324.1357247115925;5423.691548155622;6121.173641558422;6219.595726277777;6551.65691033337;6561.770338038145;6376.681165941684;6393.296737358887;6414.486841052704;6430.260520040161;6433.774817881445;6458.67706614658;6523.879739084817;6551.148350714377;6592.538522303488;6625.536212465081;5931.288245410578;6013.759449621966;6186.710916960753;6189.133521307111;6200.534959877937;5312.654677445954;5839.854003649943;6104.175936248727;6164.3835616438;6190.442860142609;5698.4016678249845;6183.474404675653;6492.610443373413;6528.812847696075;6573.408393244631;3468.4565368410813;4175.519815221289;4178.247915375262;4180.958190418057;4183.267928112629;5453.592029365559;5713.171590119493;5986.2217911671205;6225.968870155621;6257.124002639725;4802.463211772199;5414.478910110102;5779.8555036124435;5790.334111577223;6075.665838378932;5696.322728317684;5703.429657034263;5720.427957204412;5722.484976352148;5732.226149469769;4283.764392958417;5252.65857719247;5557.3331200256225;5574.355565108384;5597.082042570299;4952.059108769322;5365.651232669864;5566.944330556805;5616.887662246814;5625.606207565431;4657.925768992449;5200.609954253522;5457.372402173801;5615.382307946041;5651.92613403452 10:34:32 INFO - PROCESS | 1668 | |5;yui.html;681.9590824550527;734.1740541890373;762.0436352377765;764.1632412508302;764.7438108233741;14511.782323265146;14882.776758348633;14886.181260030695;14894.553163405115;14906.403743850233;15083.6983260335;15828.920855395707;15831.97092189007;15843.20783960803;15925.362985480568;676.0569006236298;742.4283301857566;891.9821603567925;931.4737173496986;944.4144630329206;948.9572969216401;953.0659953245823;955.0354142316276;955.9904400955971;962.9807403851919;1000.334777373046;1002.0080120680547;1002.4336250018753;1002.458672316948;1008.6167256442578;768.7155752371602;967.7241986034016;1000.1448761309085;1004.0260946881514;1008.7074748322977;738.5790099643152;739.5599618227146;741.0033504935852;746.7423738810102;750.5871770526244;14967.401303947829;15047.548573542812;15066.397344106223;15067.09597424158;15095;12445.502179912794;12607.306598137098;12697.174683645533;12797.424115914806;13047.543335983195;8994.505302208376;9448.763780905532;9916.305858589902;10214.846777298346;10335.328203666737;3540.7167426606065;3550.0592343029116;3576.535050443452;3579.4451859961746;3649.6715295623258;81160;81355.18644813536;81986;82641.58679206626;82679.759803603;9747.268954828416;9832.557534910946;9939.453330066772;10039.04629060238;10042.899571004342;21375;21487.892560537097;21790.89104554467;21839.890800546214;21853.344399668036;7727.961360193163;7785.455018148676;7796.415268854858;7859.292663660298;7942.960285198653;4064.837406503707;4112.670986321087;4123.814428350732;4309.827606895752;4353.390525326457;3619.6380361963593;3667.6149004354693;3677.8712745053795;3689.4834723138783;3692.409214525663;580.4796386365609;675.1594265139933;676.4115219758842;686.9450443964571;698.6087790837147;447.19951287195556;549.8707803666135;561.7668667503023;618.9071639254148;622.7602373086395;7271.527350722186;7743.148253692089;8324.458910170819;8363.330933525303;8599.269062129664;599.3616798109999;601.3775742106899;620.683451439769;623.2396476298908;649.8960166373357;3752.7935963521722;3882.9611703883165;3934.6655534279334;4020.5175378954714;4127.587241275908;87235.25529489375;87802.56098719593;88601.55699221592;89603.65594516088;90530.09469905349;71894;72584.54830903458;73284;73467.26532734711;74068;63756;64050.35949640444;64298.10658520378;65345;65799.67100164469;7567.697292108474;7605.163432022584;7726.497777644435;7784.260495252942;7806.453548251795;32212.55043523;41029.589704102575;41038.1792364157;41738.58261417469;42048;56388.71805640945;58399;58657;58834.11748823857;59511;10395.220358472994;10402.323848949794;10417.374957502574;10761.677149685522;11000.614978476035;10953.945230273797;11248.662540123809;11314.886851131383;11424.543018279175;11527.135464840338;10532.89467105319;10572;10601.946990265;10820;10922.235443518877;10302.578893959764;10749.677509674722;10770.623028193975;10784.083352914777;10870.836937446104;10379.325343852626;10622.20333474977;10664.986826251494;10666.20003499756;10736.30214036085;9058.818823623358;9147.039560846015;9155.542222888696;9199.080091990747;9448.527573621153;7630.465867389229;7720.57536835457;7791.844163116819;7794.844103118019;7939.761807145795 10:34:32 INFO - PROCESS | 1668 | __end_tp_report 10:34:32 INFO - PROCESS | 1668 | __start_cc_report 10:34:32 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1370 10:34:32 INFO - PROCESS | 1668 | __end_cc_report 10:34:32 INFO - PROCESS | 1668 | __startTimestamp1461864872174__endTimestamp 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | ------- Summary: start ------- 10:34:32 INFO - PROCESS | 1668 | Number of tests: 6 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5736.06 Median:5971.28 stddev:663.24 (11.1%) stddev-sans-first:624.46 10:34:32 INFO - PROCESS | 1668 | Values: 2815.9 3453.6 3471.9 3479.5 3520.5 5708.6 5711.1 5714.4 5715.7 5754.2 5682.4 5726.8 5736.2 5742.5 5743.2 5090.1 5131.3 5202.0 5211.0 5242.2 5218.1 5230.7 5234.1 5237.7 5238.9 5168.5 5234.1 5236.0 5238.4 5240.0 4806.5 4885.8 5271.7 5273.9 5285.1 5057.0 5065.5 5089.1 5195.2 5198.2 5531.0 5555.9 5587.1 5602.4 5639.8 6220.7 6224.6 6239.9 6240.8 6242.6 6087.7 6199.2 6234.9 6279.8 6288.3 5762.3 5787.3 5793.4 5808.6 5829.2 6255.4 6277.8 6278.1 6281.2 6283.0 5819.2 5900.0 6033.5 6117.9 6157.1 6263.2 6298.1 6301.5 6301.9 6312.6 6168.9 6194.8 6231.9 6239.4 6248.8 6056.1 6135.9 6142.0 6285.2 6299.1 4834.4 4856.6 4874.2 4875.3 4877.6 3771.9 4431.1 4433.5 4483.1 4499.0 5675.3 5708.2 5708.2 5724.8 5743.9 6135.3 6226.8 6300.9 6309.4 6315.7 5597.8 5625.7 5631.8 5634.9 5638.4 5334.1 5342.5 5345.0 5420.5 5427.8 6274.9 6324.4 6331.2 6332.3 6339.4 6306.9 6332.8 6335.2 6341.4 6362.0 6236.1 6275.2 6320.8 6338.0 6343.2 6191.1 6201.2 6221.0 6223.0 6230.6 6271.5 6319.8 6329.4 6341.6 6345.5 5978.6 6095.3 6101.9 6148.5 6154.1 6127.4 6131.5 6136.1 6147.2 6159.6 5950.9 5964.7 5971.3 5972.4 6003.8 5777.5 5944.3 6008.5 6055.8 6119.4 5962.9 5976.6 5982.0 6007.2 6025.1 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:69877.37 Median:18217.25 stddev:155669.71 (854.5%) stddev-sans-first:156019.28 10:34:32 INFO - PROCESS | 1668 | Values: 512.4 538.7 566.2 596.9 620.9 26456.9 30785.7 30822.7 30830.0 30895.4 31969.2 32594.8 38554.4 39646.8 39708.8 9319.8 9530.6 9560.4 9575.0 9591.9 9487.7 9650.0 9651.7 9656.6 9668.0 8368.8 8568.1 10328.1 11338.9 11344.4 11283.9 11425.7 11429.5 11443.3 11446.9 8416.1 11162.7 11331.2 11344.3 11344.9 31439.0 32053.4 32145.7 36337.1 37582.8 3209.6 3312.2 3385.9 3525.2 3545.8 22303.6 26663.2 26696.0 27177.7 27326.3 2656.0 2754.3 2807.3 2871.4 2882.4 451137.0 454139.0 454240.7 456780.0 457132.7 57887.0 57995.7 58108.7 58158.4 58201.7 62132.4 62232.4 62288.0 62331.0 62425.7 17042.7 17313.1 17342.9 17349.2 17370.4 13651.6 13662.0 13707.2 13714.9 13725.7 13314.2 13692.7 14875.4 14902.6 15449.6 6086.1 6275.2 6326.1 6336.9 6353.8 955.3 999.0 1105.9 1109.2 1166.7 29036.7 29228.7 29681.7 29882.0 29894.3 1082.2 1131.7 1199.2 1210.9 1268.6 13241.9 14233.3 15095.8 15162.9 15206.5 610447.0 643674.0 646147.0 647574.0 652006.0 60036.7 60664.8 60776.1 60868.7 60945.4 1335.3 1528.6 1536.9 1564.3 1580.6 29164.0 29335.9 29486.9 30147.4 30402.9 53768.5 54226.5 54674.3 54760.2 54822.0 625933.0 629986.0 630660.0 631781.0 631917.0 31319.8 31493.4 33122.3 42293.8 42741.0 40608.6 41385.8 42006.6 43202.9 44331.8 32119.0 32934.5 34012.8 36948.0 37076.4 36556.6 36684.8 37282.1 37395.4 37441.0 35486.9 36031.5 36530.9 37067.4 37192.6 26950.3 31283.5 32393.7 32555.8 32627.0 22547.9 22812.5 23955.0 25015.7 25150.5 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:36117.09 Median:2236.84 stddev:103311.58 (4618.6%) stddev-sans-first:103566.45 10:34:32 INFO - PROCESS | 1668 | Values: 328.3 400.8 402.4 404.2 410.7 15300.5 17291.3 19617.7 20434.9 20441.9 16304.7 19740.9 20177.8 20189.8 20204.7 4036.8 5524.7 5732.4 5733.9 5735.2 4515.6 6406.7 6425.7 6431.1 6439.9 6099.9 6304.1 6377.2 6427.1 6439.4 6398.7 6404.7 6412.4 6412.5 6428.9 5286.2 5575.1 5587.2 5590.7 5597.2 20047.0 25142.9 26142.6 26363.0 26415.7 2228.8 2244.9 2391.2 2450.5 2563.2 14022.8 15046.0 15168.7 15256.0 15677.7 1664.6 1666.8 1698.9 1745.5 1778.8 111978.9 124195.0 135753.0 135770.0 142016.3 11553.3 11572.3 11744.6 11749.3 11853.9 15146.2 15260.3 15275.8 15283.8 15313.2 7679.3 8716.5 8781.4 8920.8 9025.3 7631.0 8705.6 8967.1 8975.5 9087.3 4526.8 4601.7 4615.6 4622.0 4681.0 1677.9 1855.8 1925.5 1978.9 2031.1 823.4 972.3 975.5 1023.4 1032.6 19347.6 20169.2 20432.8 20441.8 20495.3 968.9 972.9 980.4 982.4 1037.9 9210.3 10701.6 10713.9 10725.4 10921.3 601471.0 611405.0 611838.3 630983.0 632677.0 99951.0 132613.0 135037.0 138872.0 141143.0 984.5 1012.8 1021.1 1025.2 1025.3 14672.5 17584.5 18045.5 18142.7 18287.4 51702.7 52506.5 53690.7 54826.7 54929.9 79699.6 80104.0 81168.2 81774.0 82690.6 7903.8 7993.8 8210.7 8308.5 8349.9 9283.4 12302.4 12976.4 13380.8 13408.7 11006.5 11373.3 11602.1 11688.5 11748.9 11037.3 11180.7 11522.9 11527.2 11748.4 11484.5 11693.8 11697.2 11730.4 11763.4 8244.8 8439.7 8469.0 8502.6 8729.0 10722.9 10934.8 11584.6 11773.6 11777.2 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5721.07 Median:4960.09 stddev:3182.94 (64.2%) stddev-sans-first:3175.46 10:34:32 INFO - PROCESS | 1668 | Values: 1419.6 1594.9 1609.5 1859.6 1971.3 7940.8 8721.3 8936.0 8938.8 8965.7 8570.1 8786.9 8798.1 8808.0 8845.6 2426.5 2449.7 2460.6 2461.4 2466.2 2163.6 2366.5 2418.5 2472.8 2485.3 2158.2 2412.9 2444.3 2457.1 2475.3 2413.0 2430.2 2445.8 2445.9 2462.4 2428.8 2438.6 2442.7 2448.2 2451.4 8701.7 8849.4 8953.0 8953.2 8953.8 5259.7 5494.1 5529.4 5530.0 5536.0 3491.9 3666.2 3704.3 3710.9 3724.5 1025.5 1053.4 1053.8 1064.4 1085.4 11775.1 11860.0 11878.6 11878.6 11889.2 4612.4 4625.3 4730.0 4735.0 4772.0 8016.7 9273.3 9292.9 9306.3 9325.7 4599.8 5341.3 5382.8 5386.7 5393.9 4411.4 4496.6 4525.2 4948.0 4967.2 3829.5 3877.4 3899.1 3936.2 3952.8 1233.7 1245.0 1247.7 1261.0 1261.6 800.7 923.5 937.5 941.1 962.0 5876.2 5913.9 6090.7 6159.8 6222.0 921.9 928.5 928.7 989.2 1010.3 3748.9 4089.3 4116.7 4128.9 4171.9 10387.2 10853.2 10983.7 11003.3 11011.8 10691.9 10777.1 10910.3 10915.7 10946.5 7821.3 8279.8 8280.2 8371.2 8383.9 5769.7 5866.7 5900.4 6019.9 6097.9 8299.9 10149.5 10171.2 10293.1 10301.5 9803.6 10102.8 10315.3 10498.6 10500.8 5562.5 6756.2 6913.7 7108.8 7123.1 8317.6 8320.3 8394.1 8415.4 8418.2 7278.6 7402.2 7446.2 7472.6 7520.1 7250.7 7301.4 7346.6 7433.7 7484.3 7064.3 7124.8 7143.4 7146.6 7154.7 4873.7 4953.0 4989.2 5075.4 5093.8 5026.5 5030.1 5088.4 5119.1 5273.6 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4679.48 Median:5276.55 stddev:1507.39 (28.6%) stddev-sans-first:1475.01 10:34:32 INFO - PROCESS | 1668 | Values: 280.2 319.4 320.9 326.9 332.6 5049.8 5286.4 5331.3 5337.7 5357.9 4949.7 5225.8 5266.7 5345.7 5350.1 3834.8 3917.4 3972.9 4072.8 4099.3 3740.3 3836.5 4066.3 4123.9 4138.6 3551.9 3960.7 4057.6 4072.3 4078.7 3880.6 3906.6 4085.6 4103.7 4125.4 3097.9 3150.8 3279.9 3619.6 3629.4 4305.0 4318.4 4659.1 5153.1 5180.6 3542.3 4297.9 4419.8 4702.6 4727.6 4451.0 4638.0 5577.3 5616.6 5616.7 3151.5 3895.5 3914.0 3920.2 3922.5 5354.5 5492.0 5917.6 5931.0 5949.2 3449.6 3783.1 3886.5 3910.6 3920.0 3357.4 3983.6 4020.5 4030.2 4033.7 4259.2 4366.1 4567.2 4593.8 4609.9 5087.7 5338.3 5572.4 5619.7 5679.5 1391.5 1429.9 1447.9 1453.6 1459.9 800.6 902.2 923.9 939.2 954.4 4961.6 5638.9 5640.7 5653.7 5701.3 5246.3 6125.8 6226.4 6245.6 6290.3 4899.8 5369.3 5629.8 5647.0 5675.3 2426.0 2776.5 3134.3 3259.8 3324.1 5423.7 6121.2 6219.6 6551.7 6561.8 6376.7 6393.3 6414.5 6430.3 6433.8 6458.7 6523.9 6551.1 6592.5 6625.5 5931.3 6013.8 6186.7 6189.1 6200.5 5312.7 5839.9 6104.2 6164.4 6190.4 5698.4 6183.5 6492.6 6528.8 6573.4 3468.5 4175.5 4178.2 4181.0 4183.3 5453.6 5713.2 5986.2 6226.0 6257.1 4802.5 5414.5 5779.9 5790.3 6075.7 5696.3 5703.4 5720.4 5722.5 5732.2 4283.8 5252.7 5557.3 5574.4 5597.1 4952.1 5365.7 5566.9 5616.9 5625.6 4657.9 5200.6 5457.4 5615.4 5651.9 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17304.52 Median:28484.60 stddev:24116.48 (84.7%) stddev-sans-first:24151.83 10:34:32 INFO - PROCESS | 1668 | Values: 682.0 734.2 762.0 764.2 764.7 14511.8 14882.8 14886.2 14894.6 14906.4 15083.7 15828.9 15832.0 15843.2 15925.4 676.1 742.4 892.0 931.5 944.4 949.0 953.1 955.0 956.0 963.0 1000.3 1002.0 1002.4 1002.5 1008.6 768.7 967.7 1000.1 1004.0 1008.7 738.6 739.6 741.0 746.7 750.6 14967.4 15047.5 15066.4 15067.1 15095.0 12445.5 12607.3 12697.2 12797.4 13047.5 8994.5 9448.8 9916.3 10214.8 10335.3 3540.7 3550.1 3576.5 3579.4 3649.7 81160.0 81355.2 81986.0 82641.6 82679.8 9747.3 9832.6 9939.5 10039.0 10042.9 21375.0 21487.9 21790.9 21839.9 21853.3 7728.0 7785.5 7796.4 7859.3 7943.0 4064.8 4112.7 4123.8 4309.8 4353.4 3619.6 3667.6 3677.9 3689.5 3692.4 580.5 675.2 676.4 686.9 698.6 447.2 549.9 561.8 618.9 622.8 7271.5 7743.1 8324.5 8363.3 8599.3 599.4 601.4 620.7 623.2 649.9 3752.8 3883.0 3934.7 4020.5 4127.6 87235.3 87802.6 88601.6 89603.7 90530.1 71894.0 72584.5 73284.0 73467.3 74068.0 63756.0 64050.4 64298.1 65345.0 65799.7 7567.7 7605.2 7726.5 7784.3 7806.5 32212.6 41029.6 41038.2 41738.6 42048.0 56388.7 58399.0 58657.0 58834.1 59511.0 10395.2 10402.3 10417.4 10761.7 11000.6 10953.9 11248.7 11314.9 11424.5 11527.1 10532.9 10572.0 10601.9 10820.0 10922.2 10302.6 10749.7 10770.6 10784.1 10870.8 10379.3 10622.2 10665.0 10666.2 10736.3 9058.8 9147.0 9155.5 9199.1 9448.5 7630.5 7720.6 7791.8 7794.8 7939.8 10:34:32 INFO - PROCESS | 1668 | -------- Summary: end -------- 10:34:32 INFO - PROCESS | 1668 | 10:34:32 INFO - TEST-INFO | 1668: exit 0 10:34:32 INFO - TEST-OK | dromaeo_css | took 1114464ms 10:34:32 INFO - TEST-START | kraken 10:34:32 INFO - operating with platform_type : mac_ 10:34:32 INFO - Initialising browser for kraken test... 10:34:32 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpQ_heEH/profile http://localhost:49275/getInfo.html) 10:34:34 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 10:34:34 INFO - PROCESS | 1674 | colorDepth:24 10:34:34 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 10:34:34 INFO - PROCESS | 1674 | __metrics 10:34:34 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:34:34 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:34:35 INFO - TEST-INFO | 1674: exit 0 10:34:35 INFO - Browser initialized. 10:34:35 INFO - Running cycle 1/1 for kraken test... 10:34:35 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpQ_heEH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:34:47 INFO - PROCESS | 1676 | RSS: Main: 211480576 10:34:47 INFO - PROCESS | 1676 | 10:34:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 10:34:51 INFO - PROCESS | 1676 | RSS: Main: 269475840 10:34:51 INFO - PROCESS | 1676 | 10:34:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 10:34:54 INFO - PROCESS | 1676 | RSS: Main: 284196864 10:34:54 INFO - PROCESS | 1676 | 10:34:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 10:34:58 INFO - PROCESS | 1676 | RSS: Main: 371257344 10:34:58 INFO - PROCESS | 1676 | 10:35:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 10:35:01 INFO - PROCESS | 1676 | RSS: Main: 278700032 10:35:01 INFO - PROCESS | 1676 | 10:35:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:35:03 INFO - PROCESS | 1676 | RSS: Main: 279248896 10:35:03 INFO - PROCESS | 1676 | 10:35:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 10:35:07 INFO - PROCESS | 1676 | RSS: Main: 317722624 10:35:07 INFO - PROCESS | 1676 | 10:35:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 10:35:10 INFO - PROCESS | 1676 | RSS: Main: 318472192 10:35:10 INFO - PROCESS | 1676 | 10:35:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 10:35:14 INFO - PROCESS | 1676 | RSS: Main: 316801024 10:35:14 INFO - PROCESS | 1676 | 10:35:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:35:16 INFO - PROCESS | 1676 | RSS: Main: 307277824 10:35:16 INFO - PROCESS | 1676 | 10:35:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:35:18 INFO - PROCESS | 1676 | RSS: Main: 315330560 10:35:18 INFO - PROCESS | 1676 | 10:35:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:35:21 INFO - PROCESS | 1676 | RSS: Main: 316952576 10:35:21 INFO - PROCESS | 1676 | 10:35:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:35:25 INFO - PROCESS | 1676 | RSS: Main: 322179072 10:35:25 INFO - PROCESS | 1676 | 10:35:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:35:28 INFO - PROCESS | 1676 | RSS: Main: 320094208 10:35:28 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 10:35:30 INFO - PROCESS | 1676 | RSS: Main: 384745472 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | __start_tp_report 10:35:30 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 10:35:30 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 10:35:30 INFO - PROCESS | 1676 | |i|pagename|runs| 10:35:30 INFO - PROCESS | 1676 | |0;ai-astar;118;115;108;114;113;114;115;114;102;115 10:35:30 INFO - PROCESS | 1676 | |1;audio-beat-detection;153;147;190;185;121;142;118;154;183;148 10:35:30 INFO - PROCESS | 1676 | |2;audio-dft;185;187;184;184;183;183;185;184;191;253 10:35:30 INFO - PROCESS | 1676 | |3;audio-fft;69;81;72;155;62;80;84;87;139;56 10:35:30 INFO - PROCESS | 1676 | |4;audio-oscillator;96;96;143;77;80;80;125;78;77;126 10:35:30 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;84;88;85;87;84;87;85;86;84 10:35:30 INFO - PROCESS | 1676 | |6;imaging-darkroom;92;94;94;93;92;93;93;93;92;93 10:35:30 INFO - PROCESS | 1676 | |7;imaging-desaturate;86;84;95;131;85;84;83;86;87;87 10:35:30 INFO - PROCESS | 1676 | |8;json-parse-financial;59;63;65;64;64;65;64;81;58;57 10:35:30 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;64;70;73;79;73;69;69;68;69 10:35:30 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;81;80;80;80;81;81;81;80;81;89 10:35:30 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;140;136;135;124;156;150;153;146;189;154 10:35:30 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;168;164;167;165;215;178;168;166;169;174 10:35:30 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;57;84;97;94;94;69;67;67;68;67 10:35:30 INFO - PROCESS | 1676 | __end_tp_report 10:35:30 INFO - PROCESS | 1676 | __start_cc_report 10:35:30 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2003 10:35:30 INFO - PROCESS | 1676 | __end_cc_report 10:35:30 INFO - PROCESS | 1676 | __startTimestamp1461864930771__endTimestamp 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | ------- Summary: start ------- 10:35:30 INFO - PROCESS | 1676 | Number of tests: 14 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.50 stddev:4.54 (4.0%) stddev-sans-first:4.41 10:35:30 INFO - PROCESS | 1676 | Values: 118.0 115.0 108.0 114.0 113.0 114.0 115.0 114.0 102.0 115.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:154.10 Median:153.50 stddev:25.19 (16.4%) stddev-sans-first:26.72 10:35:30 INFO - PROCESS | 1676 | Values: 153.0 147.0 190.0 185.0 121.0 142.0 118.0 154.0 183.0 148.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:191.90 Median:185.00 stddev:21.60 (11.7%) stddev-sans-first:22.77 10:35:30 INFO - PROCESS | 1676 | Values: 185.0 187.0 184.0 184.0 183.0 183.0 185.0 184.0 191.0 253.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:88.50 Median:76.00 stddev:32.55 (42.8%) stddev-sans-first:33.75 10:35:30 INFO - PROCESS | 1676 | Values: 69.0 81.0 72.0 155.0 62.0 80.0 84.0 87.0 139.0 56.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:79.00 stddev:24.67 (31.2%) stddev-sans-first:26.15 10:35:30 INFO - PROCESS | 1676 | Values: 96.0 96.0 143.0 77.0 80.0 80.0 125.0 78.0 77.0 126.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.49 (1.7%) stddev-sans-first:1.51 10:35:30 INFO - PROCESS | 1676 | Values: 87.0 84.0 88.0 85.0 87.0 84.0 87.0 85.0 86.0 84.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 10:35:30 INFO - PROCESS | 1676 | Values: 92.0 94.0 94.0 93.0 92.0 93.0 93.0 93.0 92.0 93.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:86.00 stddev:14.51 (16.9%) stddev-sans-first:15.29 10:35:30 INFO - PROCESS | 1676 | Values: 86.0 84.0 95.0 131.0 85.0 84.0 83.0 86.0 87.0 87.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:6.68 (10.4%) stddev-sans-first:6.84 10:35:30 INFO - PROCESS | 1676 | Values: 59.0 63.0 65.0 64.0 64.0 65.0 64.0 81.0 58.0 57.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:4.60 (6.6%) stddev-sans-first:4.19 10:35:30 INFO - PROCESS | 1676 | Values: 63.0 64.0 70.0 73.0 79.0 73.0 69.0 69.0 68.0 69.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:2.72 (3.4%) stddev-sans-first:2.88 10:35:30 INFO - PROCESS | 1676 | Values: 81.0 80.0 80.0 80.0 81.0 81.0 81.0 80.0 81.0 89.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:151.50 stddev:17.53 (11.6%) stddev-sans-first:18.34 10:35:30 INFO - PROCESS | 1676 | Values: 140.0 136.0 135.0 124.0 156.0 150.0 153.0 146.0 189.0 154.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:168.50 stddev:15.22 (9.0%) stddev-sans-first:16.02 10:35:30 INFO - PROCESS | 1676 | Values: 168.0 164.0 167.0 165.0 215.0 178.0 168.0 166.0 169.0 174.0 10:35:30 INFO - PROCESS | 1676 | 10:35:30 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:76.50 stddev:14.41 (18.8%) stddev-sans-first:13.46 10:35:30 INFO - PROCESS | 1676 | Values: 57.0 84.0 97.0 94.0 94.0 69.0 67.0 67.0 68.0 67.0 10:35:30 INFO - PROCESS | 1676 | -------- Summary: end -------- 10:35:30 INFO - PROCESS | 1676 | 10:35:31 INFO - TEST-INFO | 1676: exit 0 10:35:31 INFO - TEST-OK | kraken | took 58843ms 10:35:31 INFO - SUITE-END | took 1173s 10:35:31 INFO - Completed test suite (00:19:33) 10:35:31 INFO - javascript benchmark 10:35:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2815.88736450542, 3453.551038365012, 3471.9305613887723, 3479.478078288255, 3520.4895290182926, 5708.600397972144, 5711.086226203803, 5714.371419143891, 5715.714214289284, 5754.16564598133, 5682.403347648499, 5726.770929162829, 5736.196932429463, 5742.54059675226, 5743.224664670268, 5090.10923088458, 5131.255967884655, 5201.973990130044, 5211.0, 5242.161254199329, 5218.13900706383, 5230.712310822903, 5234.057869583474, 5237.711925844077, 5238.895222095575, 5168.534831865131, 5234.084035293828, 5235.973820130913, 5238.4499627539135, 5240.0, 4806.485956923558, 4885.828995985124, 5271.65734227274, 5273.894522109536, 5285.127953887604, 5056.974715126437, 5065.493450654942, 5089.1348470759685, 5195.1687729963405, 5198.246254293106, 5531.0, 5555.944440555583, 5587.078132108198, 5602.38373778884, 5639.830805075813, 6220.657863817488, 6224.595401298901, 6239.875202495925, 6240.781572644935, 6242.625442473466, 6087.6956152192215, 6199.225096862892, 6234.937650623481, 6279.780207692709, 6288.308286088527, 5762.279715035621, 5787.276590426197, 5793.449622285876, 5808.6224395414165, 5829.154772557998, 6255.437010669061, 6277.843053923689, 6278.089676996809, 6281.152044474029, 6283.0, 5819.243498345188, 5900.0, 6033.456988870935, 6117.908231376533, 6157.076438534255, 6263.154474146023, 6298.0552917062805, 6301.495880329654, 6301.905471417932, 6312.621242725451, 6168.938310616926, 6194.845128871724, 6231.937680623227, 6239.438450539473, 6248.843778905472, 6056.091586262008, 6135.937063216329, 6141.977005061692, 6285.214348206474, 6299.149614802035, 4834.371531700856, 4856.635752318591, 4874.2444921037295, 4875.341828853059, 4877.561019508261, 3771.906147217276, 4431.069475410135, 4433.512313645497, 4483.103379324083, 4498.977505112453, 5675.262215911905, 5708.229389032427, 5708.229389032511, 5724.79963201286, 5743.9138412923, 6135.294441139237, 6226.750929962751, 6300.873982520324, 6309.369063093745, 6315.684215789192, 5597.80407685729, 5625.746841392147, 5631.831045068655, 5634.859128521738, 5638.407967163471, 5334.0665383557725, 5342.465753424626, 5345.0, 5420.539254163361, 5427.83716488506, 6274.856824689819, 6324.430801227912, 6331.176946996861, 6332.303446620868, 6339.366063393716, 6306.936930630635, 6332.810015699536, 6335.176427064452, 6341.4292713656, 6362.045693146065, 6236.095766113979, 6275.246970363585, 6320.841978950379, 6337.968310158604, 6343.207099112611, 6191.102290167991, 6201.1938448003275, 6220.968895155495, 6222.968885155546, 6230.59501132425, 6271.529635277281, 6319.8104056878365, 6329.398707122816, 6341.55609107358, 6345.524085693499, 5978.641281523122, 6095.299040610386, 6101.908471372844, 6148.52435415506, 6154.076888466766, 6127.380013378952, 6131.540134489842, 6136.115092106622, 6147.170132032118, 6159.599626024294, 5950.940490595039, 5964.731587078505, 5971.28344598651, 5972.402759723993, 6003.759849606142, 5777.508911742549, 5944.316403613467, 6008.519318454601, 6055.848603784764, 6119.350229749377, 5962.850928726816, 5976.551758618034, 5982.042873140276, 6007.158997740233, 6025.096235564701], "value": 5691.636182736562, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.4081685653072, 538.7171734839209, 566.2214455124206, 596.9344719675374, 620.9037599172127, 26456.867715661418, 30785.692143078562, 30822.691773082264, 30830.0, 30895.38209235814, 31969.200769980762, 32594.83702581484, 38554.4216836747, 39646.8017659912, 39708.808735737904, 9319.76700582484, 9530.618775248982, 9560.426374417522, 9574.99462556432, 9591.856122158173, 9487.71536853892, 9650.034996500364, 9651.710448686516, 9656.565454554528, 9667.95166024169, 8368.832623347529, 8568.143185681445, 10328.122109620692, 11338.943305283463, 11344.376059316734, 11283.887161128365, 11425.657230283105, 11429.542818287258, 11443.25618834773, 11446.885531144666, 8416.074231834493, 11162.665120046411, 11331.206815522917, 11344.319340839576, 11344.943275283571, 31439.0, 32053.358932821448, 32145.6785432145, 36337.091572710626, 37582.8120859394, 3209.5988001499813, 3312.1719570107475, 3385.9322813543836, 3525.1715846775987, 3545.822708864572, 22303.55392892133, 26663.20010399691, 26696.0, 27177.728222717717, 27326.316842078908, 2655.9867200664066, 2754.256350785277, 2807.2841425436586, 2871.3682989742224, 2882.351470919047, 451137.0, 454139.0, 454240.72879635723, 456780.0, 457132.7143364295, 57887.0, 57995.710021449624, 58108.709456453296, 58158.418415816144, 58201.70899145562, 62132.378676213564, 62232.377676223565, 62288.0, 62331.0, 62425.68787156126, 17042.74435883463, 17313.134343282785, 17342.913285433493, 17349.21928513195, 17370.39203627867, 13651.590452286448, 13662.0, 13707.177569345871, 13714.86285137136, 13725.725485490235, 13314.201147931155, 13692.65768355779, 14875.404983800743, 14902.552923412315, 15449.613759656098, 6086.1268875535325, 6275.215598050244, 6326.145970294044, 6336.936630633727, 6353.791335375606, 955.2930831185012, 998.9850152247722, 1105.9004689577816, 1109.1902910874944, 1166.6733314671906, 29036.70963290382, 29228.68470918814, 29681.703182968326, 29882.0, 29894.25264368408, 1082.231615552966, 1131.7453572946185, 1199.220506670671, 1210.9273443593413, 1268.5750273658243, 13241.933790330986, 14233.28833558318, 15095.773563396559, 15162.924185379003, 15206.467773628092, 610447.0, 643674.0, 646147.0, 647574.0, 652006.0, 60036.69981650064, 60664.78670426655, 60776.08835867466, 60868.695656521435, 60945.39054609486, 1335.3256605414203, 1528.6178455386153, 1536.869366103849, 1564.2647955460916, 1580.6364536156748, 29163.979260725773, 29335.853320734113, 29486.852565737034, 30147.397052058397, 30402.93589724349, 53768.462315376346, 54226.45773542372, 54674.26628668471, 54760.17859732187, 54822.0, 625933.0, 629986.0, 630660.0, 631781.0, 631917.0, 31319.84340078285, 31493.370132597676, 33122.33755324928, 42293.78853105715, 42741.0, 40608.60805224646, 41385.79307103445, 42006.579934200265, 43202.895724906084, 44331.77834110809, 32119.0, 32934.505982409806, 34012.82993585016, 36948.0, 37076.44385334276, 36556.63443365532, 36684.81657591695, 37282.06794830151, 37395.43906841454, 37441.0, 35486.935391938285, 36031.45952810658, 36530.90407287786, 37067.443988339655, 37192.62807372, 26950.32624184411, 31283.530747038356, 32393.704251830604, 32555.837220813744, 32627.021189364357, 22547.88726056359, 22812.543749125256, 23955.0, 25015.74984250207, 25150.496990060463], "value": 19637.147118006367, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.2696001396891, 400.84567441535, 402.3602472069409, 404.2037186742118, 410.67966985751104, 15300.491972425625, 17291.308347666094, 19617.705734413994, 20434.897825510852, 20441.897790511044, 16304.695624350054, 19740.901295493502, 20177.798222017776, 20189.798102019013, 20204.69692954607, 4036.8183431745642, 5524.668519888799, 5732.398098199692, 5733.913991290134, 5735.168400581913, 4515.638748900096, 6406.743730250784, 6425.678716064202, 6431.131797207364, 6439.871202575969, 6099.9085013724825, 6304.148939893124, 6377.202849643794, 6427.100205971168, 6439.35606439357, 6398.71205795738, 6404.711787969506, 6412.39082287182, 6412.487001039905, 6428.871422571546, 5286.233496143038, 5575.107982722745, 5587.161925711135, 5590.748416321276, 5597.188407680904, 20046.997650117435, 25142.86857091417, 26142.6078608821, 26363.0, 26415.735842641712, 2228.810551103158, 2244.86530808152, 2391.2228525729206, 2450.497647982177, 2563.218218443374, 14022.789658155136, 15046.0, 15168.696626067416, 15256.0, 15677.686446271126, 1664.585102662739, 1666.7666526686273, 1698.8980661160247, 1745.4763570928671, 1778.7776527934009, 111978.88021119765, 124195.0, 135752.9637055445, 135770.0, 142016.28991855078, 11553.30680159193, 11572.305661660328, 11744.58893938708, 11749.29504229749, 11853.88146118545, 15146.16696081704, 15260.31328590216, 15275.847241527665, 15283.847161528465, 15313.157776322409, 7679.308862202429, 8716.520591367536, 8781.385303028854, 8920.776980575407, 9025.323100767337, 7631.0, 8705.564721763887, 8967.10328967098, 8975.50634715097, 9087.273018158663, 4526.773661316921, 4601.665517000032, 4615.630749540029, 4622.007852829249, 4681.0, 1677.9496615101566, 1855.7844611779037, 1925.4993701637675, 1978.881267123977, 2031.0657097735207, 823.3577809308749, 972.3048020665258, 975.5219942228399, 1023.4115383654429, 1032.6488993742164, 19347.613047738967, 20169.193232270838, 20432.795672043387, 20441.79558204399, 20495.28266510665, 968.8788901387327, 972.9367591106698, 980.352967041749, 982.4105536677936, 1037.8547003419528, 9210.263178945668, 10701.625443109453, 10713.946430267955, 10725.41010244444, 10921.344719316867, 601471.0, 611405.0, 611838.2834489063, 630983.0, 632677.0, 99951.0, 132613.010804838, 135037.0, 138872.0, 141143.0, 984.4831463481729, 1012.8075665623655, 1021.0759262867117, 1025.1644909398817, 1025.3335332034237, 14672.486462974171, 17584.472465826046, 18045.458636240935, 18142.72785908186, 18287.359942401952, 51702.707432312985, 52506.47493525016, 53690.73154634202, 54826.725866372006, 54929.90140197254, 79699.60150199212, 80104.0, 81168.18831811607, 81774.0, 82690.58654706688, 7903.802404939923, 7993.800154996172, 8210.745123121327, 8308.459950103224, 8349.874751878848, 9283.442993420416, 12302.446389912655, 12976.416061277454, 13380.799288010883, 13408.731825363633, 11006.504707288352, 11373.260738052, 11602.129840262183, 11688.532458701897, 11748.88251117478, 11037.33775973412, 11180.72048198769, 11522.884771152181, 11527.193096483166, 11748.412579371168, 11484.540618375506, 11693.76612467763, 11697.181197316106, 11730.354830484406, 11763.353015583883, 8244.793880153044, 8439.746807595537, 8468.957655211685, 8502.617382217939, 8729.0, 10722.89277107219, 10934.781304374028, 11584.594539191088, 11773.587924422603, 11777.175597708421], "value": 9765.126919804647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.6025112968373, 1594.8644365228952, 1609.4769200009996, 1859.6373707127116, 1971.3100414854803, 7940.841183176341, 8721.302295816335, 8936.0, 8938.821223575525, 8965.686200982967, 8570.100139485343, 8786.86819697705, 8798.120187981198, 8808.0, 8845.557722113901, 2426.4661774409647, 2449.6815413996246, 2460.5694003549397, 2461.384653836541, 2466.235467005225, 2163.6105501009893, 2366.4557151854997, 2418.4558474343307, 2472.814538909589, 2485.3041148478455, 2158.1691048946113, 2412.9276121716375, 2444.340028192387, 2457.103157347573, 2475.2945410557995, 2413.0, 2430.1980346485707, 2445.8410203336816, 2445.9143929962543, 2462.384403899025, 2428.8299819012677, 2438.5854404751235, 2442.719087304947, 2448.24104527597, 2451.3871532116973, 8701.695440659547, 8849.380543361967, 8952.955235223848, 8953.194212520839, 8953.776155596162, 5259.658122222043, 5494.09347457669, 5529.4470552944385, 5529.972350138264, 5536.0, 3491.8952431427097, 3666.1934374437583, 3704.3147017801793, 3710.9257814843822, 3724.5344331958504, 1025.492381271268, 1053.4258828938248, 1053.778706471642, 1064.393295821382, 1085.403028334413, 11775.05799536035, 11860.0, 11878.584249551224, 11878.58424955131, 11889.167758256925, 4612.400387949545, 4625.32932724753, 4730.0, 4734.976325118352, 4772.045590881838, 8016.7194148204535, 9273.304502162366, 9292.907070929205, 9306.302027347976, 9325.720228393158, 4599.839005634787, 5341.332333458317, 5382.838514844561, 5386.703731294738, 5393.919091213635, 4411.360352748898, 4496.64026877849, 4525.230710779176, 4947.975260123676, 4967.205247160437, 3829.5404551454003, 3877.3796192609047, 3899.1032062625754, 3936.232434675268, 3952.8023598819946, 1233.6545767185203, 1244.9564265250672, 1247.6756043428775, 1260.9621711348675, 1261.6215135459327, 800.7277525641193, 923.4920793563513, 937.4796987671771, 941.0730430525927, 962.0283513651123, 5876.177335173079, 5913.940860591339, 6090.665013424215, 6159.753609855646, 6222.004479283293, 921.893982192043, 928.4522131942186, 928.6935311347239, 989.1641562879355, 1010.2726037253166, 3748.8687895923513, 4089.3048181809163, 4116.732412393185, 4128.948872815859, 4171.874843754692, 10387.220958427997, 10853.240273180802, 10983.725406864893, 11003.33979961205, 11011.83482247767, 10691.946540267405, 10777.137828973664, 10910.290831096112, 10915.672529824118, 10946.4526773661, 7821.256980586835, 8279.793005174799, 8280.171982801792, 8371.246587807127, 8383.916160838435, 5769.74036168365, 5866.735996880236, 5900.380460051632, 6019.879602408015, 6097.878042439215, 8299.875501867356, 10149.492525373555, 10171.23715721309, 10293.125084367912, 10301.536430860479, 9803.558839852367, 10102.79794404093, 10315.27793054479, 10498.580056797642, 10500.842487362543, 5562.471565201299, 6756.155480564929, 6913.688884000131, 7108.751193708195, 7123.109611298588, 8317.625706843328, 8320.251177394064, 8394.0766515682, 8415.368847336353, 8418.242358187792, 7278.636068196463, 7402.222766609429, 7446.180920098893, 7472.589007604634, 7520.135184453858, 7250.709971601288, 7301.379382752313, 7346.5592064476305, 7433.70265189408, 7484.251574842472, 7064.32888875556, 7124.786256412316, 7143.392811611071, 7146.642667866482, 7154.67803948834, 4873.6832105913, 4952.975235123801, 4989.176785830297, 5075.390953085654, 5093.7707803148205, 5026.547610715053, 5030.0945829751, 5088.389393272831, 5119.078565858182, 5273.6044796639635], "value": 4618.143818089283, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.1874563765082, 319.4186580423629, 320.86363295599364, 326.8659849461721, 332.6174898866302, 5049.823256186034, 5286.365636123665, 5331.3069300990865, 5337.679739215645, 5357.901630165817, 4949.653524253304, 5225.817096401626, 5266.736663166846, 5345.732713364336, 5350.143976963686, 3834.7699138051735, 3917.3536366499493, 3972.860949866755, 4072.8370865165357, 4099.323611604082, 3740.2706472237924, 3836.5204349456317, 4066.3365495600283, 4123.9381409278885, 4138.586141385852, 3551.9112022199392, 3960.6633436157963, 4057.573954734755, 4072.328065869143, 4078.6940979426513, 3880.553736320317, 3906.609339066099, 4085.59144085592, 4103.651189648884, 4125.360569111793, 3097.860596273151, 3150.7951983121025, 3279.852051781881, 3619.60184379718, 3629.364861149288, 4305.0, 4318.373835793793, 4659.13291967666, 5153.093355458504, 5180.559652429548, 3542.3446662367546, 4297.914041719148, 4419.762466509383, 4702.623790096818, 4727.645426592985, 4450.977745111286, 4638.0, 5577.3028371453565, 5616.606837521375, 5616.691081990489, 3151.5272709093592, 3895.519702513055, 3913.98043009786, 3920.2355540669582, 3922.529051605648, 5354.5180933715765, 5491.972540137313, 5917.615355001954, 5930.97034514829, 5949.167116603679, 3449.619762363961, 3783.1487915219404, 3886.455896174538, 3910.569837317893, 3919.9608003920166, 3357.362101200813, 3983.581723918947, 4020.5175378954714, 4030.1939612077704, 4033.7176397651883, 4259.190753756776, 4366.104948485553, 4567.2007398705755, 4593.839215627504, 4609.907801844011, 5087.720175390315, 5338.332708411449, 5572.3870374258795, 5619.690916999604, 5679.545636349082, 1391.4990603382773, 1429.9285035748378, 1447.8769304609016, 1453.556665217119, 1459.8613131752466, 800.6437135474774, 902.2421166220406, 923.9168474837162, 939.2204470289644, 954.3823219642778, 4961.627877909099, 5638.85902852432, 5640.71796410186, 5653.689047102366, 5701.2588363513305, 5246.291750613618, 6125.816225513242, 6226.40849119333, 6245.6252624842655, 6290.308066112724, 4899.755012249373, 5369.301990741179, 5629.802956896489, 5647.040003199384, 5675.347335056439, 2426.017462927517, 2776.514110030777, 3134.3104517006223, 3259.788113772597, 3324.1357247115925, 5423.691548155622, 6121.173641558422, 6219.595726277777, 6551.65691033337, 6561.770338038145, 6376.681165941684, 6393.296737358887, 6414.486841052704, 6430.260520040161, 6433.774817881445, 6458.67706614658, 6523.879739084817, 6551.148350714377, 6592.538522303488, 6625.536212465081, 5931.288245410578, 6013.759449621966, 6186.710916960753, 6189.133521307111, 6200.534959877937, 5312.654677445954, 5839.854003649943, 6104.175936248727, 6164.3835616438, 6190.442860142609, 5698.4016678249845, 6183.474404675653, 6492.610443373413, 6528.812847696075, 6573.408393244631, 3468.4565368410813, 4175.519815221289, 4178.247915375262, 4180.958190418057, 4183.267928112629, 5453.592029365559, 5713.171590119493, 5986.2217911671205, 6225.968870155621, 6257.124002639725, 4802.463211772199, 5414.478910110102, 5779.8555036124435, 5790.334111577223, 6075.665838378932, 5696.322728317684, 5703.429657034263, 5720.427957204412, 5722.484976352148, 5732.226149469769, 4283.764392958417, 5252.65857719247, 5557.3331200256225, 5574.355565108384, 5597.082042570299, 4952.059108769322, 5365.651232669864, 5566.944330556805, 5616.887662246814, 5625.606207565431, 4657.925768992449, 5200.609954253522, 5457.372402173801, 5615.382307946041, 5651.92613403452], "value": 4206.86151295533, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.9590824550527, 734.1740541890373, 762.0436352377765, 764.1632412508302, 764.7438108233741, 14511.782323265146, 14882.776758348633, 14886.181260030695, 14894.553163405115, 14906.403743850233, 15083.6983260335, 15828.920855395707, 15831.97092189007, 15843.20783960803, 15925.362985480568, 676.0569006236298, 742.4283301857566, 891.9821603567925, 931.4737173496986, 944.4144630329206, 948.9572969216401, 953.0659953245823, 955.0354142316276, 955.9904400955971, 962.9807403851919, 1000.334777373046, 1002.0080120680547, 1002.4336250018753, 1002.458672316948, 1008.6167256442578, 768.7155752371602, 967.7241986034016, 1000.1448761309085, 1004.0260946881514, 1008.7074748322977, 738.5790099643152, 739.5599618227146, 741.0033504935852, 746.7423738810102, 750.5871770526244, 14967.401303947829, 15047.548573542812, 15066.397344106223, 15067.09597424158, 15095.0, 12445.502179912794, 12607.306598137098, 12697.174683645533, 12797.424115914806, 13047.543335983195, 8994.505302208376, 9448.763780905532, 9916.305858589902, 10214.846777298346, 10335.328203666737, 3540.7167426606065, 3550.0592343029116, 3576.535050443452, 3579.4451859961746, 3649.6715295623258, 81160.0, 81355.18644813536, 81986.0, 82641.58679206626, 82679.759803603, 9747.268954828416, 9832.557534910946, 9939.453330066772, 10039.04629060238, 10042.899571004342, 21375.0, 21487.892560537097, 21790.89104554467, 21839.890800546214, 21853.344399668036, 7727.961360193163, 7785.455018148676, 7796.415268854858, 7859.292663660298, 7942.960285198653, 4064.837406503707, 4112.670986321087, 4123.814428350732, 4309.827606895752, 4353.390525326457, 3619.6380361963593, 3667.6149004354693, 3677.8712745053795, 3689.4834723138783, 3692.409214525663, 580.4796386365609, 675.1594265139933, 676.4115219758842, 686.9450443964571, 698.6087790837147, 447.19951287195556, 549.8707803666135, 561.7668667503023, 618.9071639254148, 622.7602373086395, 7271.527350722186, 7743.148253692089, 8324.458910170819, 8363.330933525303, 8599.269062129664, 599.3616798109999, 601.3775742106899, 620.683451439769, 623.2396476298908, 649.8960166373357, 3752.7935963521722, 3882.9611703883165, 3934.6655534279334, 4020.5175378954714, 4127.587241275908, 87235.25529489375, 87802.56098719593, 88601.55699221592, 89603.65594516088, 90530.09469905349, 71894.0, 72584.54830903458, 73284.0, 73467.26532734711, 74068.0, 63756.0, 64050.35949640444, 64298.10658520378, 65345.0, 65799.67100164469, 7567.697292108474, 7605.163432022584, 7726.497777644435, 7784.260495252942, 7806.453548251795, 32212.55043523, 41029.589704102575, 41038.1792364157, 41738.58261417469, 42048.0, 56388.71805640945, 58399.0, 58657.0, 58834.11748823857, 59511.0, 10395.220358472994, 10402.323848949794, 10417.374957502574, 10761.677149685522, 11000.614978476035, 10953.945230273797, 11248.662540123809, 11314.886851131383, 11424.543018279175, 11527.135464840338, 10532.89467105319, 10572.0, 10601.946990265, 10820.0, 10922.235443518877, 10302.578893959764, 10749.677509674722, 10770.623028193975, 10784.083352914777, 10870.836937446104, 10379.325343852626, 10622.20333474977, 10664.986826251494, 10666.20003499756, 10736.30214036085, 9058.818823623358, 9147.039560846015, 9155.542222888696, 9199.080091990747, 9448.527573621153, 7630.465867389229, 7720.57536835457, 7791.844163116819, 7794.844103118019, 7939.761807145795], "value": 6769.471497542582, "unit": "score"}], "name": "dromaeo_css", "value": 7236.074162353169, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 108.0, 114.0, 113.0, 114.0, 115.0, 114.0, 102.0, 115.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 147.0, 190.0, 185.0, 121.0, 142.0, 118.0, 154.0, 183.0, 148.0], "value": 154.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 184.0, 183.0, 183.0, 185.0, 184.0, 191.0, 253.0], "value": 191.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 72.0, 155.0, 62.0, 80.0, 84.0, 87.0, 139.0, 56.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 143.0, 77.0, 80.0, 80.0, 125.0, 78.0, 77.0, 126.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 87.0, 84.0, 87.0, 85.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 94.0, 93.0, 92.0, 93.0, 93.0, 93.0, 92.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 95.0, 131.0, 85.0, 84.0, 83.0, 86.0, 87.0, 87.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 65.0, 64.0, 64.0, 65.0, 64.0, 81.0, 58.0, 57.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 70.0, 73.0, 79.0, 73.0, 69.0, 69.0, 68.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 80.0, 81.0, 81.0, 81.0, 80.0, 81.0, 89.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 135.0, 124.0, 156.0, 150.0, 153.0, 146.0, 189.0, 154.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 167.0, 165.0, 215.0, 178.0, 168.0, 166.0, 169.0, 174.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 84.0, 97.0, 94.0, 94.0, 69.0, 67.0, 67.0, 68.0, 67.0], "value": 76.4, "unit": "score"}], "name": "kraken", "value": 1527.7, "alertThreshold": 2.0}]} 10:35:32 INFO - Return code: 0 10:35:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:35:32 ERROR - # TBPL SUCCESS # 10:35:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:35:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:35:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:35:32 INFO - 2016-04-28 10:35:32.112 screenresolution[1680:10442] starting screenresolution argv=screenresolution get 10:35:32 INFO - 2016-04-28 10:35:32.114 screenresolution[1680:10442] Display 0: 1600x1200x32@60 10:35:32 INFO - 2016-04-28 10:35:32.120 screenresolution[1681:10445] starting screenresolution argv=screenresolution list 10:35:32 INFO - Available Modes on Display 0 10:35:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:35:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:35:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:35:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:35:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:35:32 INFO - Intel Iris: 10:35:32 INFO - Chipset Model: Intel Iris 10:35:32 INFO - Type: GPU 10:35:32 INFO - Bus: Built-In 10:35:32 INFO - VRAM (Dynamic, Max): 1536 MB 10:35:32 INFO - Vendor: Intel (0x8086) 10:35:32 INFO - Device ID: 0x0a2e 10:35:32 INFO - Revision ID: 0x0009 10:35:32 INFO - Displays: 10:35:32 INFO - Display: 10:35:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:35:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:35:32 INFO - Display Serial Number: 1600x1200 60 10:35:32 INFO - Main Display: Yes 10:35:32 INFO - Mirror: Off 10:35:32 INFO - Online: Yes 10:35:32 INFO - Rotation: Supported 10:35:32 INFO - Return code: 0 10:35:32 INFO - Running post-action listener: _resource_record_post_action 10:35:32 INFO - Running post-run listener: _resource_record_post_run 10:35:33 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 32128512; Write bytes: 652043264; Read time: 320; Write time: 2037 10:35:33 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346829312; Write bytes: 346101760; Read time: 26507; Write time: 678 10:35:33 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30371328; Write bytes: 295152640; Read time: 301; Write time: 1330 10:35:33 INFO - Running post-run listener: _upload_blobber_files 10:35:33 INFO - Blob upload gear active. 10:35:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:35:33 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 10:35:33 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'] 10:35:33 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 10:35:33 INFO - (blobuploader) - INFO - Open directory for files ... 10:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:35:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:35 INFO - (blobuploader) - INFO - Done attempting. 10:35:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:35:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:35:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:36 INFO - (blobuploader) - INFO - Done attempting. 10:35:36 INFO - (blobuploader) - INFO - Iteration through files over. 10:35:36 INFO - Return code: 0 10:35:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:35:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:35:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e65a32d2a8e63031e1a64ec92a6e14889705d80c33ca9f1ee72bc076739f0dba3011b487e1aafc7eda54fd5bf8107861c07b3fcc363fe2a81ea19e3e736f6a63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a3891f907b35ad81a466d5a07560c7662569b22b0b0c7eea6c38de853ab3b03268122bd67f751390c735cbc8416429b0cac22238da142e7fd0cb90587d7cc95"} 10:35:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:35:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:35:36 INFO - Contents: 10:35:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e65a32d2a8e63031e1a64ec92a6e14889705d80c33ca9f1ee72bc076739f0dba3011b487e1aafc7eda54fd5bf8107861c07b3fcc363fe2a81ea19e3e736f6a63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a3891f907b35ad81a466d5a07560c7662569b22b0b0c7eea6c38de853ab3b03268122bd67f751390c735cbc8416429b0cac22238da142e7fd0cb90587d7cc95"} 10:35:36 INFO - Running post-run listener: copy_logs_to_upload_dir 10:35:36 INFO - Copying logs to upload dir... 10:35:36 INFO - mkdir: /builds/slave/test/build/upload/logs 10:35:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.422440 ========= master_lag: 5.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-28 10:35:42.025813) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 10:35:42.033438) ========= 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.XaL7R6InJW/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.JiOB4vJp9D/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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/e65a32d2a8e63031e1a64ec92a6e14889705d80c33ca9f1ee72bc076739f0dba3011b487e1aafc7eda54fd5bf8107861c07b3fcc363fe2a81ea19e3e736f6a63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a3891f907b35ad81a466d5a07560c7662569b22b0b0c7eea6c38de853ab3b03268122bd67f751390c735cbc8416429b0cac22238da142e7fd0cb90587d7cc95"} build_url:https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010933 build_url: 'https://queue.taskcluster.net/v1/task/QP7hfUVPQdiaJOfhtLgeqg/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/e65a32d2a8e63031e1a64ec92a6e14889705d80c33ca9f1ee72bc076739f0dba3011b487e1aafc7eda54fd5bf8107861c07b3fcc363fe2a81ea19e3e736f6a63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a3891f907b35ad81a466d5a07560c7662569b22b0b0c7eea6c38de853ab3b03268122bd67f751390c735cbc8416429b0cac22238da142e7fd0cb90587d7cc95"}' ========= master_lag: 0.51 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 10:35:42.553629) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-28 10:35:42.553911) ========= 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.XaL7R6InJW/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.JiOB4vJp9D/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.005387 ========= master_lag: 5.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-28 10:35:48.004684) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 10:35:48.004961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 10:35:48.104145) ========= ========= Total master_lag: 19.12 =========