builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1461322598.61 results: success (0) buildid: 20160422030645 builduid: 5ea98b9a5e0d4c0ca9cdc63d3aa73f95 revision: dc62a0f70e53975f1ddbfe09933072736571a44d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 03:56:38.612718) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 03:56:38.613180) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 18 secs) (at 2016-04-22 03:56:38.613457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 18 secs) (at 2016-04-22 03:56:57.567876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 03:56:57.568395) ========= 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.WlQApPvhJv/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.VBEgAr4QSO/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-22 03:56:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.17M=0.001s 2016-04-22 03:56:58 (8.17 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.541091 ========= master_lag: 1.00 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 03:56:59.106158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-04-22 03:56:59.106452) ========= 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.WlQApPvhJv/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.VBEgAr4QSO/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.019962 ========= master_lag: 2.56 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-04-22 03:57:01.685679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-22 03:57:01.686063) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc62a0f70e53975f1ddbfe09933072736571a44d --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 dc62a0f70e53975f1ddbfe09933072736571a44d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WlQApPvhJv/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.VBEgAr4QSO/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-22 03:57:01,790 truncating revision to first 12 chars 2016-04-22 03:57:01,790 Setting DEBUG logging. 2016-04-22 03:57:01,790 attempt 1/10 2016-04-22 03:57:01,790 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc62a0f70e53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 03:57:02,852 unpacking tar archive at: mozilla-aurora-dc62a0f70e53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.313886 ========= master_lag: 0.89 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-22 03:57:03.886441) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 03:57:03.886961) ========= script_repo_revision: dc62a0f70e53975f1ddbfe09933072736571a44d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 03:57:03.887361) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-22 03:57:03.887648) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-22 03:57:05.234231) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-22 03:57:05.234487) ========= /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.WlQApPvhJv/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.VBEgAr4QSO/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:05 INFO - MultiFileLogger online at 20160422 03:57:05 in /builds/slave/test 03:57:05 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 03:57:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:57:05 INFO - {'append_to_log': False, 03:57:05 INFO - 'base_work_dir': '/builds/slave/test', 03:57:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:57:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:57:05 INFO - 'branch': 'Mozilla-Aurora', 03:57:05 INFO - 'buildbot_json_path': 'buildprops.json', 03:57:05 INFO - 'config_files': ('talos/mac_config.py',), 03:57:05 INFO - 'default_actions': ('clobber', 03:57:05 INFO - 'read-buildbot-config', 03:57:05 INFO - 'download-and-extract', 03:57:05 INFO - 'populate-webroot', 03:57:05 INFO - 'create-virtualenv', 03:57:05 INFO - 'install', 03:57:05 INFO - 'run-tests'), 03:57:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:57:05 INFO - 'download_minidump_stackwalk': True, 03:57:05 INFO - 'download_symbols': 'ondemand', 03:57:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:57:05 INFO - 'tooltool.py': '/tools/tooltool.py', 03:57:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:57:05 INFO - '/tools/misc-python/virtualenv.py')}, 03:57:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:57:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:57:05 INFO - 'installer_path': 'installer.exe', 03:57:05 INFO - 'log_level': 'info', 03:57:05 INFO - 'log_name': 'talos', 03:57:05 INFO - 'log_to_console': True, 03:57:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:57:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:57:05 INFO - 'opt_config_files': (), 03:57:05 INFO - 'pip_index': False, 03:57:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:57:05 INFO - 'cmd': ('bash', 03:57:05 INFO - '-c', 03:57:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:57:05 INFO - 'enabled': True, 03:57:05 INFO - 'halt_on_failure': False, 03:57:05 INFO - 'name': 'check_screen_resolution'},), 03:57:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:57:05 INFO - 'cmd': ('bash', 03:57:05 INFO - '-c', 03:57:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:57:05 INFO - 'enabled': True, 03:57:05 INFO - 'halt_on_failure': False, 03:57:05 INFO - 'name': 'check_screen_resolution'},), 03:57:05 INFO - 'run_cmd_checks_enabled': True, 03:57:05 INFO - 'sps_profile': False, 03:57:05 INFO - 'sps_profile_interval': 0, 03:57:05 INFO - 'suite': 'dromaeojs', 03:57:05 INFO - 'system_bits': '32', 03:57:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:57:05 INFO - 'title': 't-yosemite-r7-0033', 03:57:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:57:05 INFO - 'use_talos_json': True, 03:57:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:57:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:57:05 INFO - 'work_dir': 'build'} 03:57:05 INFO - ##### 03:57:05 INFO - ##### Running clobber step. 03:57:05 INFO - ##### 03:57:05 INFO - Running pre-action listener: _resource_record_pre_action 03:57:05 INFO - Running main action method: clobber 03:57:05 INFO - rmtree: /builds/slave/test/build 03:57:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:57:06 INFO - Running post-action listener: _resource_record_post_action 03:57:06 INFO - ##### 03:57:06 INFO - ##### Running read-buildbot-config step. 03:57:06 INFO - ##### 03:57:06 INFO - Running pre-action listener: _resource_record_pre_action 03:57:06 INFO - Running main action method: read_buildbot_config 03:57:06 INFO - Using buildbot properties: 03:57:06 INFO - { 03:57:06 INFO - "project": "", 03:57:06 INFO - "product": "firefox", 03:57:06 INFO - "script_repo_revision": "production", 03:57:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:57:06 INFO - "repository": "", 03:57:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:57:06 INFO - "buildid": "20160422030645", 03:57:06 INFO - "pgo_build": "False", 03:57:06 INFO - "basedir": "/builds/slave/test", 03:57:06 INFO - "buildnumber": 305, 03:57:06 INFO - "slavename": "t-yosemite-r7-0033", 03:57:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:57:06 INFO - "platform": "yosemite_r7", 03:57:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:57:06 INFO - "branch": "mozilla-aurora", 03:57:06 INFO - "repo_path": "releases/mozilla-aurora", 03:57:06 INFO - "revision": "dc62a0f70e53975f1ddbfe09933072736571a44d", 03:57:06 INFO - "stage_platform": "macosx64", 03:57:06 INFO - "builduid": "5ea98b9a5e0d4c0ca9cdc63d3aa73f95", 03:57:06 INFO - "slavebuilddir": "test" 03:57:06 INFO - } 03:57:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:57:06 INFO - Running post-action listener: _resource_record_post_action 03:57:06 INFO - ##### 03:57:06 INFO - ##### Running download-and-extract step. 03:57:06 INFO - ##### 03:57:06 INFO - Running pre-action listener: _resource_record_pre_action 03:57:06 INFO - Running main action method: download_and_extract 03:57:06 INFO - mkdir: /builds/slave/test/build/tests 03:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:06 INFO - https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:57:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:57:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:57:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:57:10 INFO - Downloaded 1277 bytes. 03:57:10 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:57:10 INFO - Using the following test package requirements: 03:57:10 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:57:10 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:57:10 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:57:10 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'jsshell-mac.zip'], 03:57:10 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:57:10 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:57:10 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:57:10 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:57:10 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:57:10 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:57:10 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:57:10 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:57:10 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:57:10 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')]} 03:57:10 INFO - https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:57:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:57:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:57:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:57:11 INFO - Downloaded 17917559 bytes. 03:57:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:57:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:57:14 INFO - Return code: 0 03:57:14 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:14 INFO - https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:57:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:57:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:57:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:57:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:57:16 INFO - Downloaded 17917559 bytes. 03:57:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:57:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:57:19 INFO - Return code: 0 03:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:19 INFO - https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:57:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:57:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:57:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:57:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:57:21 INFO - Downloaded 10845198 bytes. 03:57:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:57:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:57:21 INFO - Return code: 0 03:57:21 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')]} 03:57:21 INFO - https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:57:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:57:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:57:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:57:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:57:34 INFO - Downloaded 115753660 bytes. 03:57:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:57:34 INFO - mkdir: /builds/slave/test/properties 03:57:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:57:34 INFO - Writing to file /builds/slave/test/properties/build_url 03:57:34 INFO - Contents: 03:57:34 INFO - build_url:https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:57:35 INFO - Running post-action listener: _resource_record_post_action 03:57:35 INFO - Running post-action listener: set_extra_try_arguments 03:57:35 INFO - ##### 03:57:35 INFO - ##### Running populate-webroot step. 03:57:35 INFO - ##### 03:57:35 INFO - Running pre-action listener: _resource_record_pre_action 03:57:35 INFO - Running main action method: populate_webroot 03:57:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:57:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:57:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:57:35 INFO - '--tppagecycles', 03:57:35 INFO - '7'], 03:57:35 INFO - 'tests': ['tsvgm']}}, 03:57:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:57:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:57:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:57:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:57:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'tests': ['damp', 'tps']}, 03:57:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['damp', 'tps']}, 03:57:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:57:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:57:35 INFO - 'other': {'tests': ['a11yr', 03:57:35 INFO - 'ts_paint', 03:57:35 INFO - 'tpaint', 03:57:35 INFO - 'sessionrestore', 03:57:35 INFO - 'sessionrestore_no_auto_restore', 03:57:35 INFO - 'tabpaint']}, 03:57:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['a11yr', 03:57:35 INFO - 'ts_paint', 03:57:35 INFO - 'tpaint', 03:57:35 INFO - 'sessionrestore', 03:57:35 INFO - 'sessionrestore_no_auto_restore', 03:57:35 INFO - 'tabpaint']}, 03:57:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['a11yr', 03:57:35 INFO - 'ts_paint', 03:57:35 INFO - 'tpaint', 03:57:35 INFO - 'sessionrestore', 03:57:35 INFO - 'sessionrestore_no_auto_restore', 03:57:35 INFO - 'tabpaint']}, 03:57:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['a11yr', 03:57:35 INFO - 'ts_paint', 03:57:35 INFO - 'tpaint', 03:57:35 INFO - 'sessionrestore', 03:57:35 INFO - 'sessionrestore_no_auto_restore', 03:57:35 INFO - 'tabpaint']}, 03:57:35 INFO - 'other_l64': {'tests': ['a11yr', 03:57:35 INFO - 'ts_paint', 03:57:35 INFO - 'tpaint', 03:57:35 INFO - 'sessionrestore', 03:57:35 INFO - 'sessionrestore_no_auto_restore', 03:57:35 INFO - 'tabpaint']}, 03:57:35 INFO - 'other_nol64': {'tests': ['a11yr', 03:57:35 INFO - 'ts_paint', 03:57:35 INFO - 'tpaint', 03:57:35 INFO - 'sessionrestore', 03:57:35 INFO - 'sessionrestore_no_auto_restore', 03:57:35 INFO - 'tabpaint']}, 03:57:35 INFO - 'svgr': {'tests': ['tsvgx', 03:57:35 INFO - 'tsvgr_opacity', 03:57:35 INFO - 'tart', 03:57:35 INFO - 'tscrollx', 03:57:35 INFO - 'cart']}, 03:57:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['tsvgx', 03:57:35 INFO - 'tsvgr_opacity', 03:57:35 INFO - 'tart', 03:57:35 INFO - 'tscrollx', 03:57:35 INFO - 'cart']}, 03:57:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'tests': ['tp5o']}, 03:57:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'talos_options': ['--e10s'], 03:57:35 INFO - 'tests': ['tp5o']}, 03:57:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'talos_options': ['--xperf_path', 03:57:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:35 INFO - 'tests': ['tp5n']}, 03:57:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:35 INFO - 'talos_options': ['--e10s', 03:57:35 INFO - '--xperf_path', 03:57:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:35 INFO - 'tests': ['tp5n']}}, 03:57:35 INFO - 'talos.zip': {'path': '', 03:57:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:57:35 INFO - Running post-action listener: _resource_record_post_action 03:57:35 INFO - ##### 03:57:35 INFO - ##### Running create-virtualenv step. 03:57:35 INFO - ##### 03:57:35 INFO - Running pre-action listener: _resource_record_pre_action 03:57:35 INFO - Running main action method: create_virtualenv 03:57:35 INFO - Creating virtualenv /builds/slave/test/build/venv 03:57:35 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 03:57:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:57:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:57:35 INFO - Using real prefix '/tools/python27' 03:57:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:57:36 INFO - Installing distribute.............................................................................................................................................................................................done. 03:57:39 INFO - Installing pip.................done. 03:57:39 INFO - Return code: 0 03:57:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:39 INFO - 'HOME': '/Users/cltbld', 03:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:39 INFO - 'LOGNAME': 'cltbld', 03:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:39 INFO - 'MOZ_NO_REMOTE': '1', 03:57:39 INFO - 'NO_EM_RESTART': '1', 03:57:39 INFO - 'PAGER': '/bin/cat', 03:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:39 INFO - 'PWD': '/builds/slave/test', 03:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:39 INFO - 'SHELL': '/bin/bash', 03:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:57:39 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:39 INFO - 'USER': 'cltbld', 03:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:39 INFO - 'XPC_FLAGS': '0x0', 03:57:39 INFO - 'XPC_SERVICE_NAME': '0', 03:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:39 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 03:57:39 INFO - Cleaning up... 03:57:39 INFO - Return code: 0 03:57:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:39 INFO - 'HOME': '/Users/cltbld', 03:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:39 INFO - 'LOGNAME': 'cltbld', 03:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:39 INFO - 'MOZ_NO_REMOTE': '1', 03:57:39 INFO - 'NO_EM_RESTART': '1', 03:57:39 INFO - 'PAGER': '/bin/cat', 03:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:39 INFO - 'PWD': '/builds/slave/test', 03:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:39 INFO - 'SHELL': '/bin/bash', 03:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:57:39 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:39 INFO - 'USER': 'cltbld', 03:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:39 INFO - 'XPC_FLAGS': '0x0', 03:57:39 INFO - 'XPC_SERVICE_NAME': '0', 03:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:39 INFO - Downloading/unpacking psutil>=0.7.1 03:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:57:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:43 INFO - Installing collected packages: psutil 03:57:43 INFO - Running setup.py install for psutil 03:57:43 INFO - building 'psutil._psutil_osx' extension 03:57:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:57:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:57:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:57:43 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 03:57:43 INFO - building 'psutil._psutil_posix' extension 03:57:43 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 03:57:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:57:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:57:43 INFO - ^ 03:57:43 INFO - 1 warning generated. 03:57:43 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 03:57:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:43 INFO - Successfully installed psutil 03:57:43 INFO - Cleaning up... 03:57:44 INFO - Return code: 0 03:57:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:44 INFO - 'HOME': '/Users/cltbld', 03:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:44 INFO - 'LOGNAME': 'cltbld', 03:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:44 INFO - 'MOZ_NO_REMOTE': '1', 03:57:44 INFO - 'NO_EM_RESTART': '1', 03:57:44 INFO - 'PAGER': '/bin/cat', 03:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:44 INFO - 'PWD': '/builds/slave/test', 03:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:44 INFO - 'SHELL': '/bin/bash', 03:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:57:44 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:44 INFO - 'USER': 'cltbld', 03:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:44 INFO - 'XPC_FLAGS': '0x0', 03:57:44 INFO - 'XPC_SERVICE_NAME': '0', 03:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:57:46 INFO - Installing collected packages: mozsystemmonitor 03:57:46 INFO - Running setup.py install for mozsystemmonitor 03:57:46 INFO - Successfully installed mozsystemmonitor 03:57:46 INFO - Cleaning up... 03:57:47 INFO - Return code: 0 03:57:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:57:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:57:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:47 INFO - 'HOME': '/Users/cltbld', 03:57:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:47 INFO - 'LOGNAME': 'cltbld', 03:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:47 INFO - 'MOZ_NO_REMOTE': '1', 03:57:47 INFO - 'NO_EM_RESTART': '1', 03:57:47 INFO - 'PAGER': '/bin/cat', 03:57:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:47 INFO - 'PWD': '/builds/slave/test', 03:57:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:47 INFO - 'SHELL': '/bin/bash', 03:57:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:57:47 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:57:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:47 INFO - 'USER': 'cltbld', 03:57:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:47 INFO - 'XPC_FLAGS': '0x0', 03:57:47 INFO - 'XPC_SERVICE_NAME': '0', 03:57:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:47 INFO - Downloading/unpacking blobuploader==1.2.4 03:57:47 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 03:57:47 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 03:57:47 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 03:57:47 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 03:57:47 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 03:57:47 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 03:57:49 INFO - Downloading blobuploader-1.2.4.tar.gz 03:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:57:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:57:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:50 INFO - Downloading docopt-0.6.1.tar.gz 03:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:57:51 INFO - Installing collected packages: blobuploader, requests, docopt 03:57:51 INFO - Running setup.py install for blobuploader 03:57:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:57:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:57:51 INFO - Running setup.py install for requests 03:57:51 INFO - Running setup.py install for docopt 03:57:51 INFO - Successfully installed blobuploader requests docopt 03:57:51 INFO - Cleaning up... 03:57:51 INFO - Return code: 0 03:57:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:51 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 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:51 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 03:57:51 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 03:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:51 INFO - 'HOME': '/Users/cltbld', 03:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:51 INFO - 'LOGNAME': 'cltbld', 03:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:51 INFO - 'MOZ_NO_REMOTE': '1', 03:57:51 INFO - 'NO_EM_RESTART': '1', 03:57:51 INFO - 'PAGER': '/bin/cat', 03:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:51 INFO - 'PWD': '/builds/slave/test', 03:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:51 INFO - 'SHELL': '/bin/bash', 03:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:57:51 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:51 INFO - 'USER': 'cltbld', 03:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:51 INFO - 'XPC_FLAGS': '0x0', 03:57:51 INFO - 'XPC_SERVICE_NAME': '0', 03:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cWdLPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-EeHXnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-2EsTIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-owPCcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-h9gCsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-McVro5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-MU9g6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:52 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Uqm0_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OZPtqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-t6Oh72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-BsqqKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-7pOwGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-QTYdiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-vOeG4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nfmbvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-F3k4or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:53 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Jn5BO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:57:53 INFO - Running setup.py install for manifestparser 03:57:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:57:54 INFO - Running setup.py install for mozcrash 03:57:54 INFO - Running setup.py install for mozdebug 03:57:54 INFO - Running setup.py install for mozdevice 03:57:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:57:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:57:54 INFO - Running setup.py install for mozfile 03:57:54 INFO - Running setup.py install for mozhttpd 03:57:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:57:54 INFO - Running setup.py install for mozinfo 03:57:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Running setup.py install for mozInstall 03:57:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Running setup.py install for mozleak 03:57:55 INFO - Running setup.py install for mozlog 03:57:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Running setup.py install for moznetwork 03:57:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Running setup.py install for mozprocess 03:57:55 INFO - Running setup.py install for mozprofile 03:57:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:57:55 INFO - Running setup.py install for mozrunner 03:57:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:57:56 INFO - Running setup.py install for mozscreenshot 03:57:56 INFO - Running setup.py install for moztest 03:57:56 INFO - Running setup.py install for mozversion 03:57:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:57:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:57:56 INFO - Cleaning up... 03:57:56 INFO - Return code: 0 03:57:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:56 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 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:57:56 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 03:57:56 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 03:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:56 INFO - 'HOME': '/Users/cltbld', 03:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:56 INFO - 'LOGNAME': 'cltbld', 03:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:56 INFO - 'MOZ_NO_REMOTE': '1', 03:57:56 INFO - 'NO_EM_RESTART': '1', 03:57:56 INFO - 'PAGER': '/bin/cat', 03:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:56 INFO - 'PWD': '/builds/slave/test', 03:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:56 INFO - 'SHELL': '/bin/bash', 03:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:57:56 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:56 INFO - 'USER': 'cltbld', 03:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:56 INFO - 'XPC_FLAGS': '0x0', 03:57:56 INFO - 'XPC_SERVICE_NAME': '0', 03:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:56 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OSYfjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:56 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)) 03:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:56 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-J7c7ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JFus84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-37XKIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-SoYh0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-YpNyJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-90hwY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-P2QBRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JtL7zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-sMjqEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:57 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)) 03:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:57 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DE24Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lWT7t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wZw6hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-rrQirh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:58 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)) 03:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-KXgiOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:58 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)) 03:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-kWKA2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:58 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)) 03:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:58 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lh8_jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:58 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)) 03:57:58 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)) 03:57:58 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)) 03:57:58 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)) 03:57:58 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)) 03:57:58 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)) 03:57:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:01 INFO - Downloading blessings-1.6.tar.gz 03:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:58:01 INFO - Installing collected packages: blessings 03:58:01 INFO - Running setup.py install for blessings 03:58:01 INFO - Successfully installed blessings 03:58:01 INFO - Cleaning up... 03:58:01 INFO - Return code: 0 03:58:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:01 INFO - Reading from file tmpfile_stdout 03:58:01 INFO - Current package versions: 03:58:01 INFO - blessings == 1.6 03:58:01 INFO - blobuploader == 1.2.4 03:58:01 INFO - docopt == 0.6.1 03:58:01 INFO - manifestparser == 1.1 03:58:01 INFO - mozInstall == 1.12 03:58:01 INFO - mozcrash == 0.17 03:58:01 INFO - mozdebug == 0.1 03:58:01 INFO - mozdevice == 0.48 03:58:01 INFO - mozfile == 1.2 03:58:01 INFO - mozhttpd == 0.7 03:58:01 INFO - mozinfo == 0.9 03:58:01 INFO - mozleak == 0.1 03:58:01 INFO - mozlog == 3.1 03:58:01 INFO - moznetwork == 0.27 03:58:01 INFO - mozprocess == 0.22 03:58:01 INFO - mozprofile == 0.28 03:58:01 INFO - mozrunner == 6.11 03:58:01 INFO - mozscreenshot == 0.1 03:58:01 INFO - mozsystemmonitor == 0.0 03:58:01 INFO - moztest == 0.7 03:58:01 INFO - mozversion == 1.4 03:58:01 INFO - psutil == 3.1.1 03:58:01 INFO - requests == 1.2.3 03:58:01 INFO - wsgiref == 0.1.2 03:58:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:01 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 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:58:01 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 03:58:01 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 03:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:01 INFO - 'HOME': '/Users/cltbld', 03:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:01 INFO - 'LOGNAME': 'cltbld', 03:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:01 INFO - 'MOZ_NO_REMOTE': '1', 03:58:01 INFO - 'NO_EM_RESTART': '1', 03:58:01 INFO - 'PAGER': '/bin/cat', 03:58:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:01 INFO - 'PWD': '/builds/slave/test', 03:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:01 INFO - 'SHELL': '/bin/bash', 03:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:58:01 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:01 INFO - 'USER': 'cltbld', 03:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:01 INFO - 'XPC_FLAGS': '0x0', 03:58:01 INFO - 'XPC_SERVICE_NAME': '0', 03:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 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)) 03:58:01 INFO - Cleaning up... 03:58:01 INFO - Return code: 0 03:58:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:01 INFO - 'HOME': '/Users/cltbld', 03:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:01 INFO - 'LOGNAME': 'cltbld', 03:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:01 INFO - 'MOZ_NO_REMOTE': '1', 03:58:01 INFO - 'NO_EM_RESTART': '1', 03:58:01 INFO - 'PAGER': '/bin/cat', 03:58:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:01 INFO - 'PWD': '/builds/slave/test', 03:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:01 INFO - 'SHELL': '/bin/bash', 03:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:58:01 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:01 INFO - 'USER': 'cltbld', 03:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:01 INFO - 'XPC_FLAGS': '0x0', 03:58:01 INFO - 'XPC_SERVICE_NAME': '0', 03:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:02 INFO - Downloading/unpacking jsonschema 03:58: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 03:58: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 03:58: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 03:58: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 03:58: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 03:58: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 03:58:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:58:04 INFO - Downloading/unpacking functools32 (from jsonschema) 03:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:05 INFO - Downloading functools32-3.2.3-2.tar.gz 03:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:58:05 INFO - warning: no files found matching '*.txt' 03:58:05 INFO - no previously-included directories found matching 'build' 03:58:05 INFO - no previously-included directories found matching 'dist' 03:58:05 INFO - no previously-included directories found matching '.git*' 03:58:05 INFO - Installing collected packages: jsonschema, functools32 03:58:05 INFO - Running setup.py install for functools32 03:58:05 INFO - warning: no files found matching '*.txt' 03:58:05 INFO - no previously-included directories found matching 'build' 03:58:05 INFO - no previously-included directories found matching 'dist' 03:58:05 INFO - no previously-included directories found matching '.git*' 03:58:05 INFO - Successfully installed jsonschema functools32 03:58:05 INFO - Cleaning up... 03:58:05 INFO - Return code: 0 03:58:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:05 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 0x10233fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb35a58a890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, '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.WlQApPvhJv/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.VBEgAr4QSO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 03:58:05 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 03:58:05 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 03:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:05 INFO - 'HOME': '/Users/cltbld', 03:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:05 INFO - 'LOGNAME': 'cltbld', 03:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:05 INFO - 'MOZ_NO_REMOTE': '1', 03:58:05 INFO - 'NO_EM_RESTART': '1', 03:58:05 INFO - 'PAGER': '/bin/cat', 03:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:05 INFO - 'PWD': '/builds/slave/test', 03:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:05 INFO - 'SHELL': '/bin/bash', 03:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:58:05 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:05 INFO - 'USER': 'cltbld', 03:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:05 INFO - 'XPC_FLAGS': '0x0', 03:58:05 INFO - 'XPC_SERVICE_NAME': '0', 03:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:05 INFO - Downloading/unpacking flake8 03:58: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 03:58: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 03:58: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 03:58: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 03:58: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 03:58: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 03:58:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:58:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:58:08 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 03:58:08 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 03:58:08 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 03:58:08 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 03:58:08 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 03:58:08 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 03:58:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:58:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:58:10 INFO - no previously-included directories found matching 'docs/_build' 03:58:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:58:10 INFO - Running setup.py install for pep8 03:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:58:10 INFO - no previously-included directories found matching 'docs/_build' 03:58:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:58:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:58:10 INFO - Cleaning up... 03:58:10 INFO - Return code: 0 03:58:10 INFO - Running post-action listener: _resource_record_post_action 03:58:10 INFO - Running post-action listener: _start_resource_monitoring 03:58:10 INFO - Starting resource monitoring. 03:58:10 INFO - ##### 03:58:10 INFO - ##### Running install step. 03:58:10 INFO - ##### 03:58:10 INFO - Running pre-action listener: _resource_record_pre_action 03:58:10 INFO - Running main action method: install 03:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:10 INFO - Reading from file tmpfile_stdout 03:58:10 INFO - Detecting whether we're running mozinstall >=1.0... 03:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:58:10 INFO - Reading from file tmpfile_stdout 03:58:10 INFO - Output received: 03:58:10 INFO - Usage: mozinstall [options] installer 03:58:10 INFO - Options: 03:58:10 INFO - -h, --help show this help message and exit 03:58:10 INFO - -d DEST, --destination=DEST 03:58:10 INFO - Directory to install application into. [default: 03:58:10 INFO - "/builds/slave/test"] 03:58:10 INFO - --app=APP Application being installed. [default: firefox] 03:58:10 INFO - mkdir: /builds/slave/test/build/application 03:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:58:43 INFO - Reading from file tmpfile_stdout 03:58:43 INFO - Output received: 03:58:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:58:43 INFO - Running post-action listener: _resource_record_post_action 03:58:43 INFO - ##### 03:58:43 INFO - ##### Running run-tests step. 03:58:43 INFO - ##### 03:58:43 INFO - Running pre-action listener: _resource_record_pre_action 03:58:43 INFO - Running main action method: run_tests 03:58:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:58:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:58:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:58:43 INFO - 2016-04-22 03:58:43.214 screenresolution[2552:29785] starting screenresolution argv=screenresolution get 03:58:43 INFO - 2016-04-22 03:58:43.216 screenresolution[2552:29785] Display 0: 1600x1200x32@60 03:58:43 INFO - 2016-04-22 03:58:43.223 screenresolution[2553:29787] starting screenresolution argv=screenresolution list 03:58:43 INFO - Available Modes on Display 0 03:58:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:58:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:58:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:58:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:58:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:58:43 INFO - Intel Iris: 03:58:43 INFO - Chipset Model: Intel Iris 03:58:43 INFO - Type: GPU 03:58:43 INFO - Bus: Built-In 03:58:43 INFO - VRAM (Dynamic, Max): 1536 MB 03:58:43 INFO - Vendor: Intel (0x8086) 03:58:43 INFO - Device ID: 0x0a2e 03:58:43 INFO - Revision ID: 0x0009 03:58:43 INFO - Displays: 03:58:43 INFO - Display: 03:58:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:58:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:58:43 INFO - Display Serial Number: 1600x1200 60 03:58:43 INFO - Main Display: Yes 03:58:43 INFO - Mirror: Off 03:58:43 INFO - Online: Yes 03:58:43 INFO - Rotation: Supported 03:58:43 INFO - Return code: 0 03:58:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:58:43 INFO - Python 2.7.3 03:58:43 INFO - Return code: 0 03:58:43 INFO - grabbing minidump binary from tooltool 03:58: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')]} 03:58:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021e3d30>, '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 0x102047db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021dab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:58:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:58:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:58:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:58:43 INFO - Return code: 0 03:58:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:58:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:58:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:58:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:58:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:58:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:58:44 INFO - Return code: 0 03:58:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 03:58:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 03:58:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WlQApPvhJv/Render', 03:58:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:44 INFO - 'HOME': '/Users/cltbld', 03:58:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:44 INFO - 'LOGNAME': 'cltbld', 03:58:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:58:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:44 INFO - 'MOZ_NO_REMOTE': '1', 03:58:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:58:44 INFO - 'NO_EM_RESTART': '1', 03:58:44 INFO - 'PAGER': '/bin/cat', 03:58:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:44 INFO - 'PWD': '/builds/slave/test', 03:58:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:58:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:44 INFO - 'SHELL': '/bin/bash', 03:58:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBEgAr4QSO/Listeners', 03:58:44 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 03:58:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:44 INFO - 'USER': 'cltbld', 03:58:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:44 INFO - 'XPC_FLAGS': '0x0', 03:58:44 INFO - 'XPC_SERVICE_NAME': '0', 03:58:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 03:58:44 INFO - mozversion application_buildid: 20160422030645 03:58:44 INFO - mozversion application_changeset: dc62a0f70e53975f1ddbfe09933072736571a44d 03:58:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:58:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:58:44 INFO - mozversion application_name: Firefox 03:58:44 INFO - mozversion application_remotingname: firefox-dev 03:58:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:58:44 INFO - mozversion application_vendor: Mozilla 03:58:44 INFO - mozversion application_version: 47.0a2 03:58:44 INFO - mozversion platform_buildid: 20160422030645 03:58:44 INFO - mozversion platform_changeset: dc62a0f70e53975f1ddbfe09933072736571a44d 03:58:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:58:44 INFO - mozversion platform_version: 47.0a2 03:58:44 INFO - using testdate: 1461322724 03:58:44 INFO - actual date: 1461322724 03:58:44 INFO - starting webserver on 'localhost:63272' 03:58:44 INFO - SUITE-START | Running 2 tests 03:58:44 INFO - TEST-START | dromaeo_css 03:58:44 INFO - operating with platform_type : mac_ 03:58:44 INFO - Initialising browser for dromaeo_css test... 03:58:44 INFO - TEST-INFO | started process 2571 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmppT5C8_/profile http://localhost:63272/getInfo.html) 03:58:47 INFO - PROCESS | 2571 | __metrics Screen width/height:1600/1200 03:58:47 INFO - PROCESS | 2571 | colorDepth:24 03:58:47 INFO - PROCESS | 2571 | Browser inner width/height: 1024/689 03:58:47 INFO - PROCESS | 2571 | __metrics 03:58:47 INFO - PROCESS | 2571 | 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" 03:58:47 INFO - PROCESS | 2571 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:58:47 INFO - TEST-INFO | 2571: exit 0 03:58:47 INFO - Browser initialized. 03:58:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:58:47 INFO - TEST-INFO | started process 2573 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmppT5C8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:59 INFO - PROCESS | 2573 | RSS: Main: 207982592 03:58:59 INFO - PROCESS | 2573 | 03:59:50 INFO - PROCESS | 2573 | 1461322790712 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] 04:02:00 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:63272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:63272/tests/dromaeo/cssquery-ext.html) 04:02:02 INFO - PROCESS | 2573 | RSS: Main: 310198272 04:02:02 INFO - PROCESS | 2573 | 04:05:04 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:63272/tests/dromaeo/cssquery-ext.html (next: http://localhost:63272/tests/dromaeo/cssquery-jquery.html) 04:05:04 INFO - PROCESS | 2573 | RSS: Main: 337391616 04:05:04 INFO - PROCESS | 2573 | 04:08:06 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:63272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:63272/tests/dromaeo/cssquery-mootools.html) 04:08:07 INFO - PROCESS | 2573 | RSS: Main: 343801856 04:08:07 INFO - PROCESS | 2573 | 04:11:09 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:63272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:63272/tests/dromaeo/cssquery-prototype.html) 04:11:10 INFO - PROCESS | 2573 | RSS: Main: 306200576 04:11:10 INFO - PROCESS | 2573 | 04:14:13 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:63272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:63272/tests/dromaeo/cssquery-yui.html) 04:14:13 INFO - PROCESS | 2573 | RSS: Main: 307929088 04:14:13 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:63272/tests/dromaeo/cssquery-yui.html (next: http://localhost:63272/tests/dromaeo/cssquery-dojo.html) 04:17:16 INFO - PROCESS | 2573 | RSS: Main: 356040704 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | __start_tp_report 04:17:16 INFO - PROCESS | 2573 | _x_x_mozilla_page_load 04:17:16 INFO - PROCESS | 2573 | _x_x_mozilla_page_load_details 04:17:16 INFO - PROCESS | 2573 | |i|pagename|runs| 04:17:16 INFO - PROCESS | 2573 | |0;dojo.html;3403.098178982569;3449.9137521561975;3473.531073305103;3477.7913325200498;3487.616362200159;5605.747741351639;5623.662580245183;5648.491635752782;5653.8586535336635;5675.006873797084;5637.520810731093;5675.177099320596;5696.601237913348;5708.543316534678;5709.257796486451;5153;5157.74211289435;5202.843914682547;5230.8430747077455;5245.291885595453;5289.20661900716;5289.947100528984;5293.285406470116;5294.41761406245;5294.549963253128;5237.10969135246;5273.419923808378;5288.2596436498925;5292.682439053649;5306.204069389603;5236.21456781482;5276.498732620375;5290.92063619046;5295.126304159809;5320.281761962125;4682.8126874924965;5046.924296135524;5228.503292187274;5243.68537887728;5248.895022099537;5578.44215578443;5615.719214039282;5628.465295796893;5630.071038278679;5637.436256374371;6116.396640261113;6268.843278918063;6309.905351419732;6317.842053948688;6320.462760665349;6226.501879849647;6267.498600112026;6280.277768056686;6306.243250809886;6350.555461117724;5763.049096899007;5778.566607504454;5830.242068531149;5846.853828654275;5851.356350801409;5264.710440925709;6235.095911092866;6318.147050148263;6326.398992095744;6332.98672212453;6189.659568723673;6217.6891155442045;6233.594816337014;6241.500679945593;6242.500599951993;6287.1198032275515;6293.874122517524;6294.8111556653375;6298.8425289367215;6320.841978950563;6123.081537769282;6194.907076393858;6200.007998720183;6260.530460215502;6287.685615719287;6218.253809542884;6279.529035322369;6282.560220784501;6302.684865756693;6337.588056776295;4221.387898754725;4931.087748766418;4942.604591632661;4947.82682606107;4968.627352948543;4342.6742994275555;4454.7549884756645;4460.77696115193;4495.100979804052;4500.189965806187;5798.768049278066;5810.070388737781;5887.941120588825;5901.144334071536;5905.202797622352;6265.686715664289;6310;6311.873762524724;6323.241211054703;6339;5760.683162426107;5777.566682498829;5847.473727364558;5855.151003104611;5858.8242352729485;5341.5192632662465;5362.463753624606;5382.407935127133;5405.351357837085;5409.810656626999;6289.728006376335;6325.430711235919;6335.429811317003;6344.555881088372;6352.301246862749;6306.905396418965;6331;6333.873322533432;6346.365363463617;6349.301576826638;6235.314115447207;6248.250209974832;6319.368063193644;6334.081558174131;6345.968270158804;6178.691065446799;6200.193974783249;6221.377862213742;6225.408586184305;6233.594816336924;6178.41305076017;6298.275698294755;6309;6324.430801227912;6329.430351268408;5662.8301150965535;6107.3405344170405;6159.907601385893;6206.906896396467;6207.81376558704;6086.300075491198;6176.845578860564;6187.071939209155;6197.194364732556;6199.597026193283;5824.068149096124;5901.085331773582;6010.54920880927;6011;6025.397460253939;6142.437386316015;6195.659238741912;6199.5660303780105;6200.782972595937;6214.844628884314;6017.037274036134;6022.759089636365;6033.095035744674;6039.577229593886;6046.606970546901 04:17:16 INFO - PROCESS | 2573 | |1;ext.html;492.44845772734544;595.9630243376523;603.3152372057715;611.6085705148705;635.098160611931;31288.84355578222;31866.20334491639;31933.52099718503;39633;40056.998575035635;42718.78640606793;43226;43236.13527729444;43248.783756081255;43264.56735432645;9688.46713430761;9767.755806104833;9778.511074446249;9783.21734261261;9784.55969481375;9452.29107816913;9587.856182157273;9605.087516685904;9608.471534065624;9615.42307461551;9563.521823908812;9626.326157169002;9632.988536203702;9764.218862490983;9774.853377199348;9646.131848133666;9719.902800971971;9721.222302215876;9730.562124704335;9734.172595329406;9620.903790962071;9764.707058788248;9774.90225097747;9783;9821.754456138582;40062;40331.7983410084;40351;40414.59585404138;40423.19153616911;3353.279045005336;3404.3021180657906;3536.0806190390426;3821.2357528494135;3917.5298964124204;29464.968726094485;29709;29885.70114298851;29943.85028074846;30101.548476772867;2781.0683421053973;2786.4705705916012;2803.8317700937805;2852.2156406988242;2863.6993115722967;501395;505435;505727;506502.4674876639;506660;69698.9545156823;70716.64641676759;70802.64598676974;70818.29181708118;71022.64488677627;74551.25448745552;75626.62186689032;76940;77055.61472192603;77261.2273877254;17931.910340448216;18091.638167236844;18103.275868965356;18140.09299535018;18172.36396726108;13675.700808423184;13748;13754.968377371737;13760.036797424083;13791.379387927567;22060.78665673371;22277;22323.10707571679;22395.664065039036;22420.43948901258;7633.69465221385;7744.4578879477895;8852.203301702877;9076.09239076087;9098.636054557875;1004.969850904474;1017.1151098544314;1057.2678445088695;1094.600470828158;1165.137798029469;29797.553036704467;30121.246968825955;30177.396452071276;30597.84701076525;30631;1159.7390587117832;1262.2741923394085;1272.7009152849073;1279.5585522994552;1333.4266265505926;13616;16309.836901630832;16349.836501635069;16602.58493537671;16679.583010424838;545217;668488;680057;681347;682165;60202.69898650479;60566.697166513884;60915.17254482373;61153.694231528556;61270.69364653148;1236.8515778106685;1452.0343971258994;1539.8229203641501;1578.921053947298;1590.9920450397674;27371.72628273692;29148.56277155802;31509.805668577166;32350.514742279356;32664.183395415304;55614;55648.72175639096;56289.43710562842;56347.71826141007;56356.43643563512;467389;470449;470733.93899092224;470792;472135.6393218012;33459.665403346626;33631;45180.322295166254;46602.76698616621;47083;43038.139237215706;44951.32573011473;45023;45049.32426013678;45088.0982380344;35780.64219357773;37592.43611345777;37895.81052094722;38746.22507549889;39305.01737456586;37387.813060934524;38097;38721.41917871178;38957.8052109749;38997.22005559816;37157;37453.62546374501;37695.623043769214;38894.2221155581;38908.63819766295;32751;33684.98945031653;33696.83151584226;33817.83091084529;34056.65943340634;24134.275971720876;24391.634125488486;24553.631695524226;24747.010119595736;24849.378765531008 04:17:16 INFO - PROCESS | 2573 | |2;jquery.html;318.98086114833126;339.13351387205694;380.86479299848554;383.75823231364234;384.8191350065469;19878.701819472702;20510.794892051075;21353.679694804592;21831.345059648236;21845.781542184573;12020.459079341428;12269.202501837375;12305.507779688824;12392.442340094694;12429.005679545637;5271.525562699357;5313.787448502056;5399.487048730364;6097.908531372033;6114.816555503342;6014.368491308415;6041.546883983697;6076.4834989025985;6079.300880398744;6087.2086628738425;5430;5487.75305111269;5552.222688823547;5662.150677398382;6055.122007308938;5549.556035517149;5580.246666700004;5599.964006658781;5610.607257491977;5611.3266408031095;5168.819091331823;5315.654482458628;5371;5372.570194384479;5388.353397592275;14030.228337441436;14116.082454640415;14202.218877961708;14464.421423143061;26613.334666633295;1961.2841312920823;2039.4289598912328;2152.913883444676;2555.144026751022;2819.7744180465515;9949;9968.152707019912;10572.947135264274;12174.634760957186;15599.454019109391;1672.6738286034229;1695.788026496688;1728.740688896663;1796.4610616815035;1796.5867850394482;102157;103605.48197259041;104870;107235.46382268117;109131;11468.54125834974;11661.24201926872;11663.650090497298;11685.123615728684;11802.40987950599;14689.338979745937;14740.705185896222;14741.926290368694;14765.04027238226;14769;8954;8998.460092394478;8999.550022498848;9010.63957441695;9096;9157.084291570789;9167.22078623311;9199.264058875275;9235.95382023099;9259.120383563552;3851.306764782366;4551.8179272829375;4562.977185114053;4589.495155532889;4595.333676616872;1598.424567155823;1900.3538796808875;1923.2691577200574;1931.6040211756556;1931.748872646547;924.5515924776478;943.217129782279;1011.7066050845173;1028.8250997330322;1079.5142186016324;20735.12724509739;21131.04910279041;21557.461063473223;21563;21989.789813743908;900.8558630619069;1000.1898462245605;1026.8767747870304;1029.1817357603375;1045.2474218562622;6215.12988181655;6317.399847014526;6377.23473183221;8730.91269087301;9318.673846415479;542272;638857;642147;648434;651634;103430;112742;120505;120930;128786;407.91229885574745;486.5548023558475;491.29008582597805;491.84752726654136;493.00659171768916;11455.255408398427;11560.6531804046;11622;11748.530058797553;11893.821592675944;53375.733121334146;53588;54289.72855135699;54486.727566361915;55267.72366138144;61089.77820443655;78138;78851.60574197093;78970;79066.6046669763;6767.560108592926;8079.313258373104;8372.28835548985;8435.789105272417;8468.703595374132;9915.454649994319;12569.49722011146;13578.728425431238;13889.72220555563;13902.30488475552;11225;11799.764004719686;11997.40012999364;12031.27812331263;12133.453994570442;6232.750689972531;11452.484638191134;11633.53465861356;11684.88315116838;11851.644450666494;9861.556229969812;11440;11771.764564708486;12018.879811201776;12237.388130593612;8175.959120204361;8229.506229626242;8233.547154906537;8431.53626550521;8780.209781119729;8357.540335281617;10666.679999600025;11732.94133529327;11965.880341196826;12029.097817663886 04:17:16 INFO - PROCESS | 2573 | |3;mootools.html;1278.6867217531706;1670.849623533882;1699.7110491216492;2029.2694629933221;2087.728595282614;8353.331733461324;9026.323025773068;9168.90831091689;9174.816503669923;9203.17171454569;8877.51173685447;9106.134917182873;9127.224185944186;9129.13273239043;9143.35996480245;2262.9660555091687;2441.7436169202247;2491.277529516438;2539.3016920346913;2572.884220210095;2583.405816662169;2586.754258345454;2595.779358754508;2597.1948695904234;2598.922032339023;2478.603423452248;2564.2820010397027;2565.9358516037155;2567.961480577793;2570.267473769976;2274.1358283852196;2501.9749802502106;2547.8089143314323;2552.8340657857366;2554.1698947842024;2518.8614626195554;2530.9493810123877;2565.3843077661413;2568.4863027394595;2569.331973686855;9237.030111838227;9258.814823703487;9276.443413395218;9286.978432372434;9310.301727370406;5087.287779710843;5554.750181209236;5590.748416321276;5700.087985922232;5770.769169233267;3598.046517672819;3696.5564132304294;3745.962540374616;3754.3617585010616;3761.3417651910804;1001.539291925715;1023.5291765787747;1051.3481641382316;1090.8745494268182;1105.7401510644993;12591.748165036739;12606.36968151598;12610.747785044248;12623.558175463906;12655.557055503014;4960.677555958851;4996.175631020914;5063.139266324733;5067.467915868854;5068.16375298079;10522.105621022145;10536.947315263375;10546.894531054744;10547.103496202755;10566.366018038789;5709.143628455765;5725.713714314267;5731.111677689964;5731.226284451545;5738.426157384311;4614.838480653161;4753.096911586787;4961.404631444249;5038.470960549163;5078.111330517145;4012.117334186474;4087.080406908422;4099.97950010254;4118;4125.731827431267;1334.312828893121;1353.282760137129;1356.66761643398;1356.776131938239;1363.7545241856362;1407.4510940733123;1433.5341014170435;1441.4234306277363;1461.773425119087;1464.3190916223793;8599.355048371272;8603.655853765778;8631.784205394915;8658.134186581417;8671.132886711279;519.291167556283;531.5853634165358;535.456511640685;536.7343165133284;538.1362912525418;6342;6349.809505714836;6352.110704501373;6369.554131210771;6378.681065946684;10923.836142457869;11278.718032049264;11426.02878755315;11429.599964001387;11438.656840294805;10699.73250668724;10956.178286628534;11197.944010279896;11233.887661123448;11264.324140551593;8664.61009254585;8826.823463530693;8899.866502002475;8919.375643704878;8921.509316987498;8498.405111642125;8545.444546104483;8601.22588966996;8635.913640863511;8651.221390074923;10016.599336026477;10049.799004020026;10055.648052318134;10135.442550659489;10345.896541034494;10323.63867264642;10326.535305911402;10543.525541350811;10711.785764284827;10750.516226769658;7156.642167891689;7346.926530734624;7622.847543049219;7776.261255180749;7974.282314591715;8591.398602098043;8643.524606146722;8647.87028194565;8658.134186581543;8662.566871656518;7453.590052547162;7572.356349710336;7640.656170472231;7670.348020418104;7706.460547761603;7370.815729606803;7425.517341372793;7426.74006409773;7518.210587888193;7642.694292228249;7944.324732397811;8083.878741818995;8093.797655058671;8129.187081291823;8133.105358410452;7267.491275610657;7299;7310.59791711461;7342.3758980487255;7343.596102214429;7392.408607311295;7419.740309089156;7458.105027396748;7469.925300746923;7471.589062601609 04:17:16 INFO - PROCESS | 2573 | |4;prototype.html;328.0764647517239;331.1324330254733;336.13780652626025;337.8361494675083;338.7967219668199;6525.641089740062;6552.770653027145;6575.342465753422;6611.669416529178;6615.140031795866;6514.543981921267;6532.477401807856;6557.245916719578;6565.376289252526;6573.145491086164;4692.155412025834;4785.760711964405;4891.6331275154325;4916.06594746997;4939.259111133323;4872.317875497433;4883.780229889646;4892.266160075981;4903.2645103234445;4919.704817710931;4850.684705494149;4879.707217566957;4901.8774530636665;4911.066897289522;4924.015196960604;4647.953520464803;4750.619950403977;4855.101806165835;4857.68425052374;4907.092187945206;4178.35235538489;4200.411942328076;4215.957840421587;4230.915381692349;4231.555686652888;6303.936960630427;6321.810345689591;6342.619442833398;6372.1079048932725;6392.968035159841;4691.976540117312;5720.2849643794525;5730.398308177665;5737.942620573783;5743.712814359307;6714.362135597111;6980.267071957423;6988.336108069725;7002.579845209253;7004.159500859879;3917.275304068756;4699.0836786826585;4729.456112547066;4739.459675605442;4741.241401375798;7402.962985185093;7467.178610352803;7472.654922114018;7480.214577469342;7490.063742032246;4802.783874725646;4805.990741944161;4808.807647694053;4810.927836082462;4818;4011.4183443400543;4863.124637565272;4876.439539347446;4880.902381952341;4881.804727810919;5693.28833895763;5709.600327977002;5712;5724.227229323988;5729.512991395694;6672.666366681647;7047.471439642048;7058.082449281561;7080.362767350963;7091.82270443243;1665.7834481517325;1683.5286119886453;1690.4844022572988;1692.356904376329;1697.1344614246577;1024.8872624011353;1077.0252921106412;1091.165258577189;1113.504490501735;1129.8700649425257;6478.708458119301;6635.369639884203;6642.070110184578;6647.468202543785;6665.900011499832;5950.732217050243;7280.526765760209;7290.817729556804;7324.707011719578;7325.120985481776;6600.80197594073;6616.801495955129;6621.966890165519;6622.403983641495;6624.966875165594;3819.9427008594894;3849.1339448623835;3857.4213867919475;3857.7106716995777;3861.7489863158803;7348.301911318416;7637.15991240963;7644.885326720104;7690.46166768332;7755.302022817974;7570.015897933234;7626.199249078767;7631.7328893489575;7644;7675.769726908202;7697.883806847975;7707.499012564165;7710.7686769397005;7721.613919304013;7727;6302.243730752156;6498.675066246763;7341.522801018131;7343.1555371133;7356.668949897375;7210.603416812126;7238.348548630648;7248.818779530554;7262.31008054234;7263.16473605521;7673;7688.923110768821;7701.383889288955;7706.653200605874;7710.4217183710325;3804.8287827048403;4644.094401591792;4651.930221046619;4656;4663.300504924288;6893.379595836399;7239.819004524718;7248.710051597877;7252;7258.346748792634;5999.460048595647;6996.265392133956;7004.4046256068805;7025.789226323219;7029.3673569379;6651.900221496779;6983.406410455168;6992.79021629352;6998.2651821558;7010.334018268256;6833.89749153773;6845.863082738417;6857.451403887577;6865.210500792473;6870.27862074475;6743.8988415172835;6975.267596902455;7019.438444924496;7022.08712867324;7026.262242464468;5479.232907393048;6135.478484328882;6171.722272497838;6896.965515172392;6917.584944903322 04:17:16 INFO - PROCESS | 2573 | |5;yui.html;760.7261385901077;780.9843803123938;781.5037451218475;797.3382092862925;811.4968719393977;15224.543263702093;15346.923265383657;15350.078995260292;15416.152111633855;15424.92287538562;14075.155490670568;14708.043977141479;15879.60300992473;15905.125218112998;15925.283362248698;980.6812785844611;988.3229987458614;992.7716625176214;999.9900100898072;1000.9449480278582;953.4755884263662;955.813616344813;956.6316967967348;957.310736269888;993.0962823830316;1017.064300843226;1019.7909428567164;1020.2246292817475;1021.1167340250666;1053.499587695846;1006.7684432580511;1050.4642632257564;1052.6894566103017;1053.5259133389966;1055.720234137954;656.9080328753978;770.2682451670935;788.4756636836497;789.9921000789976;791.3115589437169;14998.550043498604;15238.695226095417;15371.308291126927;15395.461158859385;15395.692086158215;13510.932445337809;13539.59381218565;13552.254625995567;13620.250886201255;13644.386002629906;9846.65536706219;10198.388096714221;10384.636537721144;10430.217733670004;10519.684409467729;3544.805035723034;3552.307300076486;3559.341521818472;3619.149499867529;3776.0937375029794;87240;87425;87701.56149219276;89656;90528.54735726345;9575.856362154573;9757.414555126574;9769.951150244204;9900.851487227697;9919.900800991898;22065.889670551544;22350.66474002891;22377.776222237895;22415.43961400978;23133.537329253322;7681.462297639112;7775.105862825734;7898.210178982171;8027.799305017421;8093.554854482943;4124.938125928114;4168.916621667549;4190.245755763931;4193.664506839506;4214.199302132585;3795.259924314732;3800.4489349044234;3842.9231415371537;3845.3655146901006;3846.3653497172654;595.6515438468458;619.5074915442234;701.9824504387432;709.66291011769;721.361594988439;465.48098869759974;527.5357685237044;565.8811649553639;593.9168516407707;594.1503649780768;8272.958635206785;8275.50346979171;8324.875126873101;8430.40987130895;8470.407071504935;604.1481511069371;609.9786507472218;611.62996387186;620.3734228429319;627.1627377451063;3263.755218358585;3794.5636251830842;3941.1329507508303;3972.5828787977425;4011.2579172853116;86065;88300.11699883048;88316.55841720878;88539;91658.08341916629;78901;79056.60471697604;81706.59146704347;82599;83020;68764;70091.8972430838;70399.64800175966;71656.64171679213;71790.64104679444;8663.393562450568;8668.696595619123;8699.043105258543;8769.605367758339;8780.385373023826;41270.38094428526;47700.33048843274;49765.751171243915;51414.714632133;52009.61937474203;66619;66947;67120.65077722256;69566;72346.63826680832;11162;11583.305001699924;11595.768084638428;11733.589324373606;11807.23252988553;11860.169788114748;12108.576199832964;12122.09084318662;12404.875951240372;12595.811062833882;11850.288982661068;11897.167198296036;12123.090768192244;12266.957308628867;12425.19236249641;9977.850332244878;11274.887251127384;11764.176507644383;12161.939190303992;12264.816027759769;11117.666470005914;11199.88800112021;11230.3261804292;11334.546618135182;11558.364289963796;9485.523329758791;9616.278779091735;9713;9894.604215831287;10348.120409764953;9726.221902247662;9932.602695892372;9952.353097048668;9957.352772069793;10014.3490673106 04:17:16 INFO - PROCESS | 2573 | __end_tp_report 04:17:16 INFO - PROCESS | 2573 | __start_cc_report 04:17:16 INFO - PROCESS | 2573 | _x_x_mozilla_cycle_collect,959 04:17:16 INFO - PROCESS | 2573 | __end_cc_report 04:17:16 INFO - PROCESS | 2573 | __startTimestamp1461323836675__endTimestamp 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | ------- Summary: start ------- 04:17:16 INFO - PROCESS | 2573 | Number of tests: 6 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | [#0] dojo.html Cycles:165 Average:5773.61 Median:6022.76 stddev:650.41 (10.8%) stddev-sans-first:625.25 04:17:16 INFO - PROCESS | 2573 | Values: 3403.1 3449.9 3473.5 3477.8 3487.6 5605.7 5623.7 5648.5 5653.9 5675.0 5637.5 5675.2 5696.6 5708.5 5709.3 5153.0 5157.7 5202.8 5230.8 5245.3 5289.2 5289.9 5293.3 5294.4 5294.5 5237.1 5273.4 5288.3 5292.7 5306.2 5236.2 5276.5 5290.9 5295.1 5320.3 4682.8 5046.9 5228.5 5243.7 5248.9 5578.4 5615.7 5628.5 5630.1 5637.4 6116.4 6268.8 6309.9 6317.8 6320.5 6226.5 6267.5 6280.3 6306.2 6350.6 5763.0 5778.6 5830.2 5846.9 5851.4 5264.7 6235.1 6318.1 6326.4 6333.0 6189.7 6217.7 6233.6 6241.5 6242.5 6287.1 6293.9 6294.8 6298.8 6320.8 6123.1 6194.9 6200.0 6260.5 6287.7 6218.3 6279.5 6282.6 6302.7 6337.6 4221.4 4931.1 4942.6 4947.8 4968.6 4342.7 4454.8 4460.8 4495.1 4500.2 5798.8 5810.1 5887.9 5901.1 5905.2 6265.7 6310.0 6311.9 6323.2 6339.0 5760.7 5777.6 5847.5 5855.2 5858.8 5341.5 5362.5 5382.4 5405.4 5409.8 6289.7 6325.4 6335.4 6344.6 6352.3 6306.9 6331.0 6333.9 6346.4 6349.3 6235.3 6248.3 6319.4 6334.1 6346.0 6178.7 6200.2 6221.4 6225.4 6233.6 6178.4 6298.3 6309.0 6324.4 6329.4 5662.8 6107.3 6159.9 6206.9 6207.8 6086.3 6176.8 6187.1 6197.2 6199.6 5824.1 5901.1 6010.5 6011.0 6025.4 6142.4 6195.7 6199.6 6200.8 6214.8 6017.0 6022.8 6033.1 6039.6 6046.6 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | [#1] ext.html Cycles:180 Average:69329.59 Median:39845.00 stddev:146403.33 (367.4%) stddev-sans-first:146722.81 04:17:16 INFO - PROCESS | 2573 | Values: 492.4 596.0 603.3 611.6 635.1 31288.8 31866.2 31933.5 39633.0 40057.0 42718.8 43226.0 43236.1 43248.8 43264.6 9688.5 9767.8 9778.5 9783.2 9784.6 9452.3 9587.9 9605.1 9608.5 9615.4 9563.5 9626.3 9633.0 9764.2 9774.9 9646.1 9719.9 9721.2 9730.6 9734.2 9620.9 9764.7 9774.9 9783.0 9821.8 40062.0 40331.8 40351.0 40414.6 40423.2 3353.3 3404.3 3536.1 3821.2 3917.5 29465.0 29709.0 29885.7 29943.9 30101.5 2781.1 2786.5 2803.8 2852.2 2863.7 501395.0 505435.0 505727.0 506502.5 506660.0 69699.0 70716.6 70802.6 70818.3 71022.6 74551.3 75626.6 76940.0 77055.6 77261.2 17931.9 18091.6 18103.3 18140.1 18172.4 13675.7 13748.0 13755.0 13760.0 13791.4 22060.8 22277.0 22323.1 22395.7 22420.4 7633.7 7744.5 8852.2 9076.1 9098.6 1005.0 1017.1 1057.3 1094.6 1165.1 29797.6 30121.2 30177.4 30597.8 30631.0 1159.7 1262.3 1272.7 1279.6 1333.4 13616.0 16309.8 16349.8 16602.6 16679.6 545217.0 668488.0 680057.0 681347.0 682165.0 60202.7 60566.7 60915.2 61153.7 61270.7 1236.9 1452.0 1539.8 1578.9 1591.0 27371.7 29148.6 31509.8 32350.5 32664.2 55614.0 55648.7 56289.4 56347.7 56356.4 467389.0 470449.0 470733.9 470792.0 472135.6 33459.7 33631.0 45180.3 46602.8 47083.0 43038.1 44951.3 45023.0 45049.3 45088.1 35780.6 37592.4 37895.8 38746.2 39305.0 37387.8 38097.0 38721.4 38957.8 38997.2 37157.0 37453.6 37695.6 38894.2 38908.6 32751.0 33685.0 33696.8 33817.8 34056.7 24134.3 24391.6 24553.6 24747.0 24849.4 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | [#2] jquery.html Cycles:180 Average:34414.98 Median:1569.38 stddev:103849.74 (6617.3%) stddev-sans-first:104109.51 04:17:16 INFO - PROCESS | 2573 | Values: 319.0 339.1 380.9 383.8 384.8 19878.7 20510.8 21353.7 21831.3 21845.8 12020.5 12269.2 12305.5 12392.4 12429.0 5271.5 5313.8 5399.5 6097.9 6114.8 6014.4 6041.5 6076.5 6079.3 6087.2 5430.0 5487.8 5552.2 5662.2 6055.1 5549.6 5580.2 5600.0 5610.6 5611.3 5168.8 5315.7 5371.0 5372.6 5388.4 14030.2 14116.1 14202.2 14464.4 26613.3 1961.3 2039.4 2152.9 2555.1 2819.8 9949.0 9968.2 10572.9 12174.6 15599.5 1672.7 1695.8 1728.7 1796.5 1796.6 102157.0 103605.5 104870.0 107235.5 109131.0 11468.5 11661.2 11663.7 11685.1 11802.4 14689.3 14740.7 14741.9 14765.0 14769.0 8954.0 8998.5 8999.6 9010.6 9096.0 9157.1 9167.2 9199.3 9236.0 9259.1 3851.3 4551.8 4563.0 4589.5 4595.3 1598.4 1900.4 1923.3 1931.6 1931.7 924.6 943.2 1011.7 1028.8 1079.5 20735.1 21131.0 21557.5 21563.0 21989.8 900.9 1000.2 1026.9 1029.2 1045.2 6215.1 6317.4 6377.2 8730.9 9318.7 542272.0 638857.0 642147.0 648434.0 651634.0 103430.0 112742.0 120505.0 120930.0 128786.0 407.9 486.6 491.3 491.8 493.0 11455.3 11560.7 11622.0 11748.5 11893.8 53375.7 53588.0 54289.7 54486.7 55267.7 61089.8 78138.0 78851.6 78970.0 79066.6 6767.6 8079.3 8372.3 8435.8 8468.7 9915.5 12569.5 13578.7 13889.7 13902.3 11225.0 11799.8 11997.4 12031.3 12133.5 6232.8 11452.5 11633.5 11684.9 11851.6 9861.6 11440.0 11771.8 12018.9 12237.4 8176.0 8229.5 8233.5 8431.5 8780.2 8357.5 10666.7 11732.9 11965.9 12029.1 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | [#3] mootools.html Cycles:180 Average:6328.88 Median:5050.81 stddev:3392.87 (67.2%) stddev-sans-first:3381.14 04:17:16 INFO - PROCESS | 2573 | Values: 1278.7 1670.8 1699.7 2029.3 2087.7 8353.3 9026.3 9168.9 9174.8 9203.2 8877.5 9106.1 9127.2 9129.1 9143.4 2263.0 2441.7 2491.3 2539.3 2572.9 2583.4 2586.8 2595.8 2597.2 2598.9 2478.6 2564.3 2565.9 2568.0 2570.3 2274.1 2502.0 2547.8 2552.8 2554.2 2518.9 2530.9 2565.4 2568.5 2569.3 9237.0 9258.8 9276.4 9287.0 9310.3 5087.3 5554.8 5590.7 5700.1 5770.8 3598.0 3696.6 3746.0 3754.4 3761.3 1001.5 1023.5 1051.3 1090.9 1105.7 12591.7 12606.4 12610.7 12623.6 12655.6 4960.7 4996.2 5063.1 5067.5 5068.2 10522.1 10536.9 10546.9 10547.1 10566.4 5709.1 5725.7 5731.1 5731.2 5738.4 4614.8 4753.1 4961.4 5038.5 5078.1 4012.1 4087.1 4100.0 4118.0 4125.7 1334.3 1353.3 1356.7 1356.8 1363.8 1407.5 1433.5 1441.4 1461.8 1464.3 8599.4 8603.7 8631.8 8658.1 8671.1 519.3 531.6 535.5 536.7 538.1 6342.0 6349.8 6352.1 6369.6 6378.7 10923.8 11278.7 11426.0 11429.6 11438.7 10699.7 10956.2 11197.9 11233.9 11264.3 8664.6 8826.8 8899.9 8919.4 8921.5 8498.4 8545.4 8601.2 8635.9 8651.2 10016.6 10049.8 10055.6 10135.4 10345.9 10323.6 10326.5 10543.5 10711.8 10750.5 7156.6 7346.9 7622.8 7776.3 7974.3 8591.4 8643.5 8647.9 8658.1 8662.6 7453.6 7572.4 7640.7 7670.3 7706.5 7370.8 7425.5 7426.7 7518.2 7642.7 7944.3 8083.9 8093.8 8129.2 8133.1 7267.5 7299.0 7310.6 7342.4 7343.6 7392.4 7419.7 7458.1 7469.9 7471.6 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | [#4] prototype.html Cycles:180 Average:5762.45 Median:6542.62 stddev:1831.11 (28.0%) stddev-sans-first:1790.24 04:17:16 INFO - PROCESS | 2573 | Values: 328.1 331.1 336.1 337.8 338.8 6525.6 6552.8 6575.3 6611.7 6615.1 6514.5 6532.5 6557.2 6565.4 6573.1 4692.2 4785.8 4891.6 4916.1 4939.3 4872.3 4883.8 4892.3 4903.3 4919.7 4850.7 4879.7 4901.9 4911.1 4924.0 4648.0 4750.6 4855.1 4857.7 4907.1 4178.4 4200.4 4216.0 4230.9 4231.6 6303.9 6321.8 6342.6 6372.1 6393.0 4692.0 5720.3 5730.4 5737.9 5743.7 6714.4 6980.3 6988.3 7002.6 7004.2 3917.3 4699.1 4729.5 4739.5 4741.2 7403.0 7467.2 7472.7 7480.2 7490.1 4802.8 4806.0 4808.8 4810.9 4818.0 4011.4 4863.1 4876.4 4880.9 4881.8 5693.3 5709.6 5712.0 5724.2 5729.5 6672.7 7047.5 7058.1 7080.4 7091.8 1665.8 1683.5 1690.5 1692.4 1697.1 1024.9 1077.0 1091.2 1113.5 1129.9 6478.7 6635.4 6642.1 6647.5 6665.9 5950.7 7280.5 7290.8 7324.7 7325.1 6600.8 6616.8 6622.0 6622.4 6625.0 3819.9 3849.1 3857.4 3857.7 3861.7 7348.3 7637.2 7644.9 7690.5 7755.3 7570.0 7626.2 7631.7 7644.0 7675.8 7697.9 7707.5 7710.8 7721.6 7727.0 6302.2 6498.7 7341.5 7343.2 7356.7 7210.6 7238.3 7248.8 7262.3 7263.2 7673.0 7688.9 7701.4 7706.7 7710.4 3804.8 4644.1 4651.9 4656.0 4663.3 6893.4 7239.8 7248.7 7252.0 7258.3 5999.5 6996.3 7004.4 7025.8 7029.4 6651.9 6983.4 6992.8 6998.3 7010.3 6833.9 6845.9 6857.5 6865.2 6870.3 6743.9 6975.3 7019.4 7022.1 7026.3 5479.2 6135.5 6171.7 6897.0 6917.6 04:17:16 INFO - PROCESS | 2573 | 04:17:16 INFO - PROCESS | 2573 | [#5] yui.html Cycles:180 Average:18718.46 Median:51712.17 stddev:26125.78 (50.5%) stddev-sans-first:26164.27 04:17:16 INFO - PROCESS | 2573 | Values: 760.7 781.0 781.5 797.3 811.5 15224.5 15346.9 15350.1 15416.2 15424.9 14075.2 14708.0 15879.6 15905.1 15925.3 980.7 988.3 992.8 1000.0 1000.9 953.5 955.8 956.6 957.3 993.1 1017.1 1019.8 1020.2 1021.1 1053.5 1006.8 1050.5 1052.7 1053.5 1055.7 656.9 770.3 788.5 790.0 791.3 14998.6 15238.7 15371.3 15395.5 15395.7 13510.9 13539.6 13552.3 13620.3 13644.4 9846.7 10198.4 10384.6 10430.2 10519.7 3544.8 3552.3 3559.3 3619.1 3776.1 87240.0 87425.0 87701.6 89656.0 90528.5 9575.9 9757.4 9770.0 9900.9 9919.9 22065.9 22350.7 22377.8 22415.4 23133.5 7681.5 7775.1 7898.2 8027.8 8093.6 4124.9 4168.9 4190.2 4193.7 4214.2 3795.3 3800.4 3842.9 3845.4 3846.4 595.7 619.5 702.0 709.7 721.4 465.5 527.5 565.9 593.9 594.2 8273.0 8275.5 8324.9 8430.4 8470.4 604.1 610.0 611.6 620.4 627.2 3263.8 3794.6 3941.1 3972.6 4011.3 86065.0 88300.1 88316.6 88539.0 91658.1 78901.0 79056.6 81706.6 82599.0 83020.0 68764.0 70091.9 70399.6 71656.6 71790.6 8663.4 8668.7 8699.0 8769.6 8780.4 41270.4 47700.3 49765.8 51414.7 52009.6 66619.0 66947.0 67120.7 69566.0 72346.6 11162.0 11583.3 11595.8 11733.6 11807.2 11860.2 12108.6 12122.1 12404.9 12595.8 11850.3 11897.2 12123.1 12267.0 12425.2 9977.9 11274.9 11764.2 12161.9 12264.8 11117.7 11199.9 11230.3 11334.5 11558.4 9485.5 9616.3 9713.0 9894.6 10348.1 9726.2 9932.6 9952.4 9957.4 10014.3 04:17:16 INFO - PROCESS | 2573 | -------- Summary: end -------- 04:17:16 INFO - PROCESS | 2573 | 04:17:17 INFO - TEST-INFO | 2573: exit 0 04:17:17 INFO - TEST-OK | dromaeo_css | took 1112245ms 04:17:17 INFO - TEST-START | kraken 04:17:17 INFO - operating with platform_type : mac_ 04:17:17 INFO - Initialising browser for kraken test... 04:17:17 INFO - TEST-INFO | started process 2575 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpi0Gued/profile http://localhost:63272/getInfo.html) 04:17:19 INFO - PROCESS | 2575 | __metrics Screen width/height:1600/1200 04:17:19 INFO - PROCESS | 2575 | colorDepth:24 04:17:19 INFO - PROCESS | 2575 | Browser inner width/height: 1024/689 04:17:19 INFO - PROCESS | 2575 | __metrics 04:17:19 INFO - PROCESS | 2575 | 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" 04:17:19 INFO - PROCESS | 2575 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:17:20 INFO - TEST-INFO | 2575: exit 0 04:17:20 INFO - Browser initialized. 04:17:20 INFO - Running cycle 1/1 for kraken test... 04:17:20 INFO - TEST-INFO | started process 2577 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpi0Gued/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:31 INFO - PROCESS | 2577 | RSS: Main: 207544320 04:17:31 INFO - PROCESS | 2577 | 04:17:34 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:63272/tests/kraken/driver.html?testName=audio-beat-detection) 04:17:35 INFO - PROCESS | 2577 | RSS: Main: 262823936 04:17:35 INFO - PROCESS | 2577 | 04:17:37 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:63272/tests/kraken/driver.html?testName=audio-dft) 04:17:38 INFO - PROCESS | 2577 | RSS: Main: 273526784 04:17:38 INFO - PROCESS | 2577 | 04:17:41 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:63272/tests/kraken/driver.html?testName=audio-fft) 04:17:42 INFO - PROCESS | 2577 | RSS: Main: 263917568 04:17:42 INFO - PROCESS | 2577 | 04:17:44 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:63272/tests/kraken/driver.html?testName=audio-oscillator) 04:17:44 INFO - PROCESS | 2577 | RSS: Main: 265875456 04:17:44 INFO - PROCESS | 2577 | 04:17:46 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:63272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:17:47 INFO - PROCESS | 2577 | RSS: Main: 265633792 04:17:47 INFO - PROCESS | 2577 | 04:17:50 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:63272/tests/kraken/driver.html?testName=imaging-darkroom) 04:17:50 INFO - PROCESS | 2577 | RSS: Main: 265867264 04:17:50 INFO - PROCESS | 2577 | 04:17:53 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:63272/tests/kraken/driver.html?testName=imaging-desaturate) 04:17:54 INFO - PROCESS | 2577 | RSS: Main: 266190848 04:17:54 INFO - PROCESS | 2577 | 04:17:56 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:63272/tests/kraken/driver.html?testName=json-parse-financial) 04:17:57 INFO - PROCESS | 2577 | RSS: Main: 266592256 04:17:57 INFO - PROCESS | 2577 | 04:17:58 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:63272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:17:59 INFO - PROCESS | 2577 | RSS: Main: 298950656 04:17:59 INFO - PROCESS | 2577 | 04:18:01 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:18:01 INFO - PROCESS | 2577 | RSS: Main: 300101632 04:18:01 INFO - PROCESS | 2577 | 04:18:04 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:18:04 INFO - PROCESS | 2577 | RSS: Main: 305811456 04:18:04 INFO - PROCESS | 2577 | 04:18:07 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:18:07 INFO - PROCESS | 2577 | RSS: Main: 307838976 04:18:07 INFO - PROCESS | 2577 | 04:18:10 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:18:10 INFO - PROCESS | 2577 | RSS: Main: 307863552 04:18:10 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | Cycle 1(1): loaded http://localhost:63272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:63272/tests/kraken/driver.html?testName=ai-astar) 04:18:12 INFO - PROCESS | 2577 | RSS: Main: 370769920 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | __start_tp_report 04:18:12 INFO - PROCESS | 2577 | _x_x_mozilla_page_load 04:18:12 INFO - PROCESS | 2577 | _x_x_mozilla_page_load_details 04:18:12 INFO - PROCESS | 2577 | |i|pagename|runs| 04:18:12 INFO - PROCESS | 2577 | |0;ai-astar;112;109;110;109;111;112;119;112;109;111 04:18:12 INFO - PROCESS | 2577 | |1;audio-beat-detection;157;125;144;178;145;138;115;151;148;120 04:18:12 INFO - PROCESS | 2577 | |2;audio-dft;186;185;187;186;184;186;187;187;185;186 04:18:12 INFO - PROCESS | 2577 | |3;audio-fft;73;78;78;150;82;57;58;57;118;56 04:18:12 INFO - PROCESS | 2577 | |4;audio-oscillator;91;95;130;81;78;86;119;75;79;122 04:18:12 INFO - PROCESS | 2577 | |5;imaging-gaussian-blur;88;85;89;89;136;85;88;88;89;85 04:18:12 INFO - PROCESS | 2577 | |6;imaging-darkroom;94;94;101;95;93;93;93;93;93;92 04:18:12 INFO - PROCESS | 2577 | |7;imaging-desaturate;87;87;85;85;87;87;86;87;87;86 04:18:12 INFO - PROCESS | 2577 | |8;json-parse-financial;54;59;57;58;57;58;59;70;50;52 04:18:12 INFO - PROCESS | 2577 | |9;json-stringify-tinderbox;62;62;72;75;71;67;66;68;69;68 04:18:12 INFO - PROCESS | 2577 | |10;stanford-crypto-aes;87;81;82;80;81;79;79;78;80;82 04:18:12 INFO - PROCESS | 2577 | |11;stanford-crypto-ccm;122;119;122;125;117;130;119;118;129;117 04:18:12 INFO - PROCESS | 2577 | |12;stanford-crypto-pbkdf2;134;124;140;133;131;114;133;134;134;134 04:18:12 INFO - PROCESS | 2577 | |13;stanford-crypto-sha256-iterative;66;65;72;66;65;67;66;66;64;66 04:18:12 INFO - PROCESS | 2577 | __end_tp_report 04:18:12 INFO - PROCESS | 2577 | __start_cc_report 04:18:12 INFO - PROCESS | 2577 | _x_x_mozilla_cycle_collect,1198 04:18:12 INFO - PROCESS | 2577 | __end_cc_report 04:18:12 INFO - PROCESS | 2577 | __startTimestamp1461323892717__endTimestamp 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | ------- Summary: start ------- 04:18:12 INFO - PROCESS | 2577 | Number of tests: 14 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.50 stddev:2.95 (2.6%) stddev-sans-first:3.12 04:18:12 INFO - PROCESS | 2577 | Values: 112.0 109.0 110.0 109.0 111.0 112.0 119.0 112.0 109.0 111.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:146.50 stddev:18.76 (12.8%) stddev-sans-first:19.11 04:18:12 INFO - PROCESS | 2577 | Values: 157.0 125.0 144.0 178.0 145.0 138.0 115.0 151.0 148.0 120.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#2] audio-dft Cycles:10 Average:185.90 Median:186.00 stddev:0.99 (0.5%) stddev-sans-first:1.05 04:18:12 INFO - PROCESS | 2577 | Values: 186.0 185.0 187.0 186.0 184.0 186.0 187.0 187.0 185.0 186.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#3] audio-fft Cycles:10 Average:80.70 Median:65.50 stddev:30.76 (47.0%) stddev-sans-first:32.50 04:18:12 INFO - PROCESS | 2577 | Values: 73.0 78.0 78.0 150.0 82.0 57.0 58.0 57.0 118.0 56.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:80.00 stddev:20.45 (25.6%) stddev-sans-first:21.62 04:18:12 INFO - PROCESS | 2577 | Values: 91.0 95.0 130.0 81.0 78.0 86.0 119.0 75.0 79.0 122.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:88.00 stddev:15.48 (17.6%) stddev-sans-first:16.35 04:18:12 INFO - PROCESS | 2577 | Values: 88.0 85.0 89.0 89.0 136.0 85.0 88.0 88.0 89.0 85.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.00 stddev:2.56 (2.8%) stddev-sans-first:2.71 04:18:12 INFO - PROCESS | 2577 | Values: 94.0 94.0 101.0 95.0 93.0 93.0 93.0 93.0 93.0 92.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:87.00 stddev:0.84 (1.0%) stddev-sans-first:0.87 04:18:12 INFO - PROCESS | 2577 | Values: 87.0 87.0 85.0 85.0 87.0 87.0 86.0 87.0 87.0 86.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:5.38 (9.3%) stddev-sans-first:5.56 04:18:12 INFO - PROCESS | 2577 | Values: 54.0 59.0 57.0 58.0 57.0 58.0 59.0 70.0 50.0 52.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.50 stddev:4.11 (6.0%) stddev-sans-first:3.74 04:18:12 INFO - PROCESS | 2577 | Values: 62.0 62.0 72.0 75.0 71.0 67.0 66.0 68.0 69.0 68.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:2.51 (3.1%) stddev-sans-first:1.39 04:18:12 INFO - PROCESS | 2577 | Values: 87.0 81.0 82.0 80.0 81.0 79.0 79.0 78.0 80.0 82.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:122.00 stddev:4.78 (3.9%) stddev-sans-first:5.07 04:18:12 INFO - PROCESS | 2577 | Values: 122.0 119.0 122.0 125.0 117.0 130.0 119.0 118.0 129.0 117.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.10 Median:134.00 stddev:7.17 (5.4%) stddev-sans-first:7.53 04:18:12 INFO - PROCESS | 2577 | Values: 134.0 124.0 140.0 133.0 131.0 114.0 133.0 134.0 134.0 134.0 04:18:12 INFO - PROCESS | 2577 | 04:18:12 INFO - PROCESS | 2577 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:2.16 (3.3%) stddev-sans-first:2.29 04:18:12 INFO - PROCESS | 2577 | Values: 66.0 65.0 72.0 66.0 65.0 67.0 66.0 66.0 64.0 66.0 04:18:12 INFO - PROCESS | 2577 | -------- Summary: end -------- 04:18:12 INFO - PROCESS | 2577 | 04:18:13 INFO - TEST-INFO | 2577: exit 0 04:18:13 INFO - TEST-OK | kraken | took 56215ms 04:18:13 INFO - SUITE-END | took 1168s 04:18:13 INFO - Completed test suite (00:19:28) 04:18:13 INFO - javascript benchmark 04:18:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3403.098178982569, 3449.9137521561975, 3473.531073305103, 3477.7913325200498, 3487.616362200159, 5605.747741351639, 5623.662580245183, 5648.491635752782, 5653.8586535336635, 5675.006873797084, 5637.520810731093, 5675.177099320596, 5696.601237913348, 5708.543316534678, 5709.257796486451, 5153.0, 5157.74211289435, 5202.843914682547, 5230.8430747077455, 5245.291885595453, 5289.20661900716, 5289.947100528984, 5293.285406470116, 5294.41761406245, 5294.549963253128, 5237.10969135246, 5273.419923808378, 5288.2596436498925, 5292.682439053649, 5306.204069389603, 5236.21456781482, 5276.498732620375, 5290.92063619046, 5295.126304159809, 5320.281761962125, 4682.8126874924965, 5046.924296135524, 5228.503292187274, 5243.68537887728, 5248.895022099537, 5578.44215578443, 5615.719214039282, 5628.465295796893, 5630.071038278679, 5637.436256374371, 6116.396640261113, 6268.843278918063, 6309.905351419732, 6317.842053948688, 6320.462760665349, 6226.501879849647, 6267.498600112026, 6280.277768056686, 6306.243250809886, 6350.555461117724, 5763.049096899007, 5778.566607504454, 5830.242068531149, 5846.853828654275, 5851.356350801409, 5264.710440925709, 6235.095911092866, 6318.147050148263, 6326.398992095744, 6332.98672212453, 6189.659568723673, 6217.6891155442045, 6233.594816337014, 6241.500679945593, 6242.500599951993, 6287.1198032275515, 6293.874122517524, 6294.8111556653375, 6298.8425289367215, 6320.841978950563, 6123.081537769282, 6194.907076393858, 6200.007998720183, 6260.530460215502, 6287.685615719287, 6218.253809542884, 6279.529035322369, 6282.560220784501, 6302.684865756693, 6337.588056776295, 4221.387898754725, 4931.087748766418, 4942.604591632661, 4947.82682606107, 4968.627352948543, 4342.6742994275555, 4454.7549884756645, 4460.77696115193, 4495.100979804052, 4500.189965806187, 5798.768049278066, 5810.070388737781, 5887.941120588825, 5901.144334071536, 5905.202797622352, 6265.686715664289, 6310.0, 6311.873762524724, 6323.241211054703, 6339.0, 5760.683162426107, 5777.566682498829, 5847.473727364558, 5855.151003104611, 5858.8242352729485, 5341.5192632662465, 5362.463753624606, 5382.407935127133, 5405.351357837085, 5409.810656626999, 6289.728006376335, 6325.430711235919, 6335.429811317003, 6344.555881088372, 6352.301246862749, 6306.905396418965, 6331.0, 6333.873322533432, 6346.365363463617, 6349.301576826638, 6235.314115447207, 6248.250209974832, 6319.368063193644, 6334.081558174131, 6345.968270158804, 6178.691065446799, 6200.193974783249, 6221.377862213742, 6225.408586184305, 6233.594816336924, 6178.41305076017, 6298.275698294755, 6309.0, 6324.430801227912, 6329.430351268408, 5662.8301150965535, 6107.3405344170405, 6159.907601385893, 6206.906896396467, 6207.81376558704, 6086.300075491198, 6176.845578860564, 6187.071939209155, 6197.194364732556, 6199.597026193283, 5824.068149096124, 5901.085331773582, 6010.54920880927, 6011.0, 6025.397460253939, 6142.437386316015, 6195.659238741912, 6199.5660303780105, 6200.782972595937, 6214.844628884314, 6017.037274036134, 6022.759089636365, 6033.095035744674, 6039.577229593886, 6046.606970546901], "unit": "score", "name": "dojo.html", "value": 5731.984943893167}, {"lowerIsBetter": false, "replicates": [492.44845772734544, 595.9630243376523, 603.3152372057715, 611.6085705148705, 635.098160611931, 31288.84355578222, 31866.20334491639, 31933.52099718503, 39633.0, 40056.998575035635, 42718.78640606793, 43226.0, 43236.13527729444, 43248.783756081255, 43264.56735432645, 9688.46713430761, 9767.755806104833, 9778.511074446249, 9783.21734261261, 9784.55969481375, 9452.29107816913, 9587.856182157273, 9605.087516685904, 9608.471534065624, 9615.42307461551, 9563.521823908812, 9626.326157169002, 9632.988536203702, 9764.218862490983, 9774.853377199348, 9646.131848133666, 9719.902800971971, 9721.222302215876, 9730.562124704335, 9734.172595329406, 9620.903790962071, 9764.707058788248, 9774.90225097747, 9783.0, 9821.754456138582, 40062.0, 40331.7983410084, 40351.0, 40414.59585404138, 40423.19153616911, 3353.279045005336, 3404.3021180657906, 3536.0806190390426, 3821.2357528494135, 3917.5298964124204, 29464.968726094485, 29709.0, 29885.70114298851, 29943.85028074846, 30101.548476772867, 2781.0683421053973, 2786.4705705916012, 2803.8317700937805, 2852.2156406988242, 2863.6993115722967, 501395.0, 505435.0, 505727.0, 506502.4674876639, 506660.0, 69698.9545156823, 70716.64641676759, 70802.64598676974, 70818.29181708118, 71022.64488677627, 74551.25448745552, 75626.62186689032, 76940.0, 77055.61472192603, 77261.2273877254, 17931.910340448216, 18091.638167236844, 18103.275868965356, 18140.09299535018, 18172.36396726108, 13675.700808423184, 13748.0, 13754.968377371737, 13760.036797424083, 13791.379387927567, 22060.78665673371, 22277.0, 22323.10707571679, 22395.664065039036, 22420.43948901258, 7633.69465221385, 7744.4578879477895, 8852.203301702877, 9076.09239076087, 9098.636054557875, 1004.969850904474, 1017.1151098544314, 1057.2678445088695, 1094.600470828158, 1165.137798029469, 29797.553036704467, 30121.246968825955, 30177.396452071276, 30597.84701076525, 30631.0, 1159.7390587117832, 1262.2741923394085, 1272.7009152849073, 1279.5585522994552, 1333.4266265505926, 13616.0, 16309.836901630832, 16349.836501635069, 16602.58493537671, 16679.583010424838, 545217.0, 668488.0, 680057.0, 681347.0, 682165.0, 60202.69898650479, 60566.697166513884, 60915.17254482373, 61153.694231528556, 61270.69364653148, 1236.8515778106685, 1452.0343971258994, 1539.8229203641501, 1578.921053947298, 1590.9920450397674, 27371.72628273692, 29148.56277155802, 31509.805668577166, 32350.514742279356, 32664.183395415304, 55614.0, 55648.72175639096, 56289.43710562842, 56347.71826141007, 56356.43643563512, 467389.0, 470449.0, 470733.93899092224, 470792.0, 472135.6393218012, 33459.665403346626, 33631.0, 45180.322295166254, 46602.76698616621, 47083.0, 43038.139237215706, 44951.32573011473, 45023.0, 45049.32426013678, 45088.0982380344, 35780.64219357773, 37592.43611345777, 37895.81052094722, 38746.22507549889, 39305.01737456586, 37387.813060934524, 38097.0, 38721.41917871178, 38957.8052109749, 38997.22005559816, 37157.0, 37453.62546374501, 37695.623043769214, 38894.2221155581, 38908.63819766295, 32751.0, 33684.98945031653, 33696.83151584226, 33817.83091084529, 34056.65943340634, 24134.275971720876, 24391.634125488486, 24553.631695524226, 24747.010119595736, 24849.378765531008], "unit": "score", "name": "ext.html", "value": 20847.995005275287}, {"lowerIsBetter": false, "replicates": [318.98086114833126, 339.13351387205694, 380.86479299848554, 383.75823231364234, 384.8191350065469, 19878.701819472702, 20510.794892051075, 21353.679694804592, 21831.345059648236, 21845.781542184573, 12020.459079341428, 12269.202501837375, 12305.507779688824, 12392.442340094694, 12429.005679545637, 5271.525562699357, 5313.787448502056, 5399.487048730364, 6097.908531372033, 6114.816555503342, 6014.368491308415, 6041.546883983697, 6076.4834989025985, 6079.300880398744, 6087.2086628738425, 5430.0, 5487.75305111269, 5552.222688823547, 5662.150677398382, 6055.122007308938, 5549.556035517149, 5580.246666700004, 5599.964006658781, 5610.607257491977, 5611.3266408031095, 5168.819091331823, 5315.654482458628, 5371.0, 5372.570194384479, 5388.353397592275, 14030.228337441436, 14116.082454640415, 14202.218877961708, 14464.421423143061, 26613.334666633295, 1961.2841312920823, 2039.4289598912328, 2152.913883444676, 2555.144026751022, 2819.7744180465515, 9949.0, 9968.152707019912, 10572.947135264274, 12174.634760957186, 15599.454019109391, 1672.6738286034229, 1695.788026496688, 1728.740688896663, 1796.4610616815035, 1796.5867850394482, 102157.0, 103605.48197259041, 104870.0, 107235.46382268117, 109131.0, 11468.54125834974, 11661.24201926872, 11663.650090497298, 11685.123615728684, 11802.40987950599, 14689.338979745937, 14740.705185896222, 14741.926290368694, 14765.04027238226, 14769.0, 8954.0, 8998.460092394478, 8999.550022498848, 9010.63957441695, 9096.0, 9157.084291570789, 9167.22078623311, 9199.264058875275, 9235.95382023099, 9259.120383563552, 3851.306764782366, 4551.8179272829375, 4562.977185114053, 4589.495155532889, 4595.333676616872, 1598.424567155823, 1900.3538796808875, 1923.2691577200574, 1931.6040211756556, 1931.748872646547, 924.5515924776478, 943.217129782279, 1011.7066050845173, 1028.8250997330322, 1079.5142186016324, 20735.12724509739, 21131.04910279041, 21557.461063473223, 21563.0, 21989.789813743908, 900.8558630619069, 1000.1898462245605, 1026.8767747870304, 1029.1817357603375, 1045.2474218562622, 6215.12988181655, 6317.399847014526, 6377.23473183221, 8730.91269087301, 9318.673846415479, 542272.0, 638857.0, 642147.0, 648434.0, 651634.0, 103430.0, 112742.0, 120505.0, 120930.0, 128786.0, 407.91229885574745, 486.5548023558475, 491.29008582597805, 491.84752726654136, 493.00659171768916, 11455.255408398427, 11560.6531804046, 11622.0, 11748.530058797553, 11893.821592675944, 53375.733121334146, 53588.0, 54289.72855135699, 54486.727566361915, 55267.72366138144, 61089.77820443655, 78138.0, 78851.60574197093, 78970.0, 79066.6046669763, 6767.560108592926, 8079.313258373104, 8372.28835548985, 8435.789105272417, 8468.703595374132, 9915.454649994319, 12569.49722011146, 13578.728425431238, 13889.72220555563, 13902.30488475552, 11225.0, 11799.764004719686, 11997.40012999364, 12031.27812331263, 12133.453994570442, 6232.750689972531, 11452.484638191134, 11633.53465861356, 11684.88315116838, 11851.644450666494, 9861.556229969812, 11440.0, 11771.764564708486, 12018.879811201776, 12237.388130593612, 8175.959120204361, 8229.506229626242, 8233.547154906537, 8431.53626550521, 8780.209781119729, 8357.540335281617, 10666.679999600025, 11732.94133529327, 11965.880341196826, 12029.097817663886], "unit": "score", "name": "jquery.html", "value": 8947.911697007865}, {"lowerIsBetter": false, "replicates": [1278.6867217531706, 1670.849623533882, 1699.7110491216492, 2029.2694629933221, 2087.728595282614, 8353.331733461324, 9026.323025773068, 9168.90831091689, 9174.816503669923, 9203.17171454569, 8877.51173685447, 9106.134917182873, 9127.224185944186, 9129.13273239043, 9143.35996480245, 2262.9660555091687, 2441.7436169202247, 2491.277529516438, 2539.3016920346913, 2572.884220210095, 2583.405816662169, 2586.754258345454, 2595.779358754508, 2597.1948695904234, 2598.922032339023, 2478.603423452248, 2564.2820010397027, 2565.9358516037155, 2567.961480577793, 2570.267473769976, 2274.1358283852196, 2501.9749802502106, 2547.8089143314323, 2552.8340657857366, 2554.1698947842024, 2518.8614626195554, 2530.9493810123877, 2565.3843077661413, 2568.4863027394595, 2569.331973686855, 9237.030111838227, 9258.814823703487, 9276.443413395218, 9286.978432372434, 9310.301727370406, 5087.287779710843, 5554.750181209236, 5590.748416321276, 5700.087985922232, 5770.769169233267, 3598.046517672819, 3696.5564132304294, 3745.962540374616, 3754.3617585010616, 3761.3417651910804, 1001.539291925715, 1023.5291765787747, 1051.3481641382316, 1090.8745494268182, 1105.7401510644993, 12591.748165036739, 12606.36968151598, 12610.747785044248, 12623.558175463906, 12655.557055503014, 4960.677555958851, 4996.175631020914, 5063.139266324733, 5067.467915868854, 5068.16375298079, 10522.105621022145, 10536.947315263375, 10546.894531054744, 10547.103496202755, 10566.366018038789, 5709.143628455765, 5725.713714314267, 5731.111677689964, 5731.226284451545, 5738.426157384311, 4614.838480653161, 4753.096911586787, 4961.404631444249, 5038.470960549163, 5078.111330517145, 4012.117334186474, 4087.080406908422, 4099.97950010254, 4118.0, 4125.731827431267, 1334.312828893121, 1353.282760137129, 1356.66761643398, 1356.776131938239, 1363.7545241856362, 1407.4510940733123, 1433.5341014170435, 1441.4234306277363, 1461.773425119087, 1464.3190916223793, 8599.355048371272, 8603.655853765778, 8631.784205394915, 8658.134186581417, 8671.132886711279, 519.291167556283, 531.5853634165358, 535.456511640685, 536.7343165133284, 538.1362912525418, 6342.0, 6349.809505714836, 6352.110704501373, 6369.554131210771, 6378.681065946684, 10923.836142457869, 11278.718032049264, 11426.02878755315, 11429.599964001387, 11438.656840294805, 10699.73250668724, 10956.178286628534, 11197.944010279896, 11233.887661123448, 11264.324140551593, 8664.61009254585, 8826.823463530693, 8899.866502002475, 8919.375643704878, 8921.509316987498, 8498.405111642125, 8545.444546104483, 8601.22588966996, 8635.913640863511, 8651.221390074923, 10016.599336026477, 10049.799004020026, 10055.648052318134, 10135.442550659489, 10345.896541034494, 10323.63867264642, 10326.535305911402, 10543.525541350811, 10711.785764284827, 10750.516226769658, 7156.642167891689, 7346.926530734624, 7622.847543049219, 7776.261255180749, 7974.282314591715, 8591.398602098043, 8643.524606146722, 8647.87028194565, 8658.134186581543, 8662.566871656518, 7453.590052547162, 7572.356349710336, 7640.656170472231, 7670.348020418104, 7706.460547761603, 7370.815729606803, 7425.517341372793, 7426.74006409773, 7518.210587888193, 7642.694292228249, 7944.324732397811, 8083.878741818995, 8093.797655058671, 8129.187081291823, 8133.105358410452, 7267.491275610657, 7299.0, 7310.59791711461, 7342.3758980487255, 7343.596102214429, 7392.408607311295, 7419.740309089156, 7458.105027396748, 7469.925300746923, 7471.589062601609], "unit": "score", "name": "mootools.html", "value": 5054.800690577803}, {"lowerIsBetter": false, "replicates": [328.0764647517239, 331.1324330254733, 336.13780652626025, 337.8361494675083, 338.7967219668199, 6525.641089740062, 6552.770653027145, 6575.342465753422, 6611.669416529178, 6615.140031795866, 6514.543981921267, 6532.477401807856, 6557.245916719578, 6565.376289252526, 6573.145491086164, 4692.155412025834, 4785.760711964405, 4891.6331275154325, 4916.06594746997, 4939.259111133323, 4872.317875497433, 4883.780229889646, 4892.266160075981, 4903.2645103234445, 4919.704817710931, 4850.684705494149, 4879.707217566957, 4901.8774530636665, 4911.066897289522, 4924.015196960604, 4647.953520464803, 4750.619950403977, 4855.101806165835, 4857.68425052374, 4907.092187945206, 4178.35235538489, 4200.411942328076, 4215.957840421587, 4230.915381692349, 4231.555686652888, 6303.936960630427, 6321.810345689591, 6342.619442833398, 6372.1079048932725, 6392.968035159841, 4691.976540117312, 5720.2849643794525, 5730.398308177665, 5737.942620573783, 5743.712814359307, 6714.362135597111, 6980.267071957423, 6988.336108069725, 7002.579845209253, 7004.159500859879, 3917.275304068756, 4699.0836786826585, 4729.456112547066, 4739.459675605442, 4741.241401375798, 7402.962985185093, 7467.178610352803, 7472.654922114018, 7480.214577469342, 7490.063742032246, 4802.783874725646, 4805.990741944161, 4808.807647694053, 4810.927836082462, 4818.0, 4011.4183443400543, 4863.124637565272, 4876.439539347446, 4880.902381952341, 4881.804727810919, 5693.28833895763, 5709.600327977002, 5712.0, 5724.227229323988, 5729.512991395694, 6672.666366681647, 7047.471439642048, 7058.082449281561, 7080.362767350963, 7091.82270443243, 1665.7834481517325, 1683.5286119886453, 1690.4844022572988, 1692.356904376329, 1697.1344614246577, 1024.8872624011353, 1077.0252921106412, 1091.165258577189, 1113.504490501735, 1129.8700649425257, 6478.708458119301, 6635.369639884203, 6642.070110184578, 6647.468202543785, 6665.900011499832, 5950.732217050243, 7280.526765760209, 7290.817729556804, 7324.707011719578, 7325.120985481776, 6600.80197594073, 6616.801495955129, 6621.966890165519, 6622.403983641495, 6624.966875165594, 3819.9427008594894, 3849.1339448623835, 3857.4213867919475, 3857.7106716995777, 3861.7489863158803, 7348.301911318416, 7637.15991240963, 7644.885326720104, 7690.46166768332, 7755.302022817974, 7570.015897933234, 7626.199249078767, 7631.7328893489575, 7644.0, 7675.769726908202, 7697.883806847975, 7707.499012564165, 7710.7686769397005, 7721.613919304013, 7727.0, 6302.243730752156, 6498.675066246763, 7341.522801018131, 7343.1555371133, 7356.668949897375, 7210.603416812126, 7238.348548630648, 7248.818779530554, 7262.31008054234, 7263.16473605521, 7673.0, 7688.923110768821, 7701.383889288955, 7706.653200605874, 7710.4217183710325, 3804.8287827048403, 4644.094401591792, 4651.930221046619, 4656.0, 4663.300504924288, 6893.379595836399, 7239.819004524718, 7248.710051597877, 7252.0, 7258.346748792634, 5999.460048595647, 6996.265392133956, 7004.4046256068805, 7025.789226323219, 7029.3673569379, 6651.900221496779, 6983.406410455168, 6992.79021629352, 6998.2651821558, 7010.334018268256, 6833.89749153773, 6845.863082738417, 6857.451403887577, 6865.210500792473, 6870.27862074475, 6743.8988415172835, 6975.267596902455, 7019.438444924496, 7022.08712867324, 7026.262242464468, 5479.232907393048, 6135.478484328882, 6171.722272497838, 6896.965515172392, 6917.584944903322], "unit": "score", "name": "prototype.html", "value": 5155.902805265112}, {"lowerIsBetter": false, "replicates": [760.7261385901077, 780.9843803123938, 781.5037451218475, 797.3382092862925, 811.4968719393977, 15224.543263702093, 15346.923265383657, 15350.078995260292, 15416.152111633855, 15424.92287538562, 14075.155490670568, 14708.043977141479, 15879.60300992473, 15905.125218112998, 15925.283362248698, 980.6812785844611, 988.3229987458614, 992.7716625176214, 999.9900100898072, 1000.9449480278582, 953.4755884263662, 955.813616344813, 956.6316967967348, 957.310736269888, 993.0962823830316, 1017.064300843226, 1019.7909428567164, 1020.2246292817475, 1021.1167340250666, 1053.499587695846, 1006.7684432580511, 1050.4642632257564, 1052.6894566103017, 1053.5259133389966, 1055.720234137954, 656.9080328753978, 770.2682451670935, 788.4756636836497, 789.9921000789976, 791.3115589437169, 14998.550043498604, 15238.695226095417, 15371.308291126927, 15395.461158859385, 15395.692086158215, 13510.932445337809, 13539.59381218565, 13552.254625995567, 13620.250886201255, 13644.386002629906, 9846.65536706219, 10198.388096714221, 10384.636537721144, 10430.217733670004, 10519.684409467729, 3544.805035723034, 3552.307300076486, 3559.341521818472, 3619.149499867529, 3776.0937375029794, 87240.0, 87425.0, 87701.56149219276, 89656.0, 90528.54735726345, 9575.856362154573, 9757.414555126574, 9769.951150244204, 9900.851487227697, 9919.900800991898, 22065.889670551544, 22350.66474002891, 22377.776222237895, 22415.43961400978, 23133.537329253322, 7681.462297639112, 7775.105862825734, 7898.210178982171, 8027.799305017421, 8093.554854482943, 4124.938125928114, 4168.916621667549, 4190.245755763931, 4193.664506839506, 4214.199302132585, 3795.259924314732, 3800.4489349044234, 3842.9231415371537, 3845.3655146901006, 3846.3653497172654, 595.6515438468458, 619.5074915442234, 701.9824504387432, 709.66291011769, 721.361594988439, 465.48098869759974, 527.5357685237044, 565.8811649553639, 593.9168516407707, 594.1503649780768, 8272.958635206785, 8275.50346979171, 8324.875126873101, 8430.40987130895, 8470.407071504935, 604.1481511069371, 609.9786507472218, 611.62996387186, 620.3734228429319, 627.1627377451063, 3263.755218358585, 3794.5636251830842, 3941.1329507508303, 3972.5828787977425, 4011.2579172853116, 86065.0, 88300.11699883048, 88316.55841720878, 88539.0, 91658.08341916629, 78901.0, 79056.60471697604, 81706.59146704347, 82599.0, 83020.0, 68764.0, 70091.8972430838, 70399.64800175966, 71656.64171679213, 71790.64104679444, 8663.393562450568, 8668.696595619123, 8699.043105258543, 8769.605367758339, 8780.385373023826, 41270.38094428526, 47700.33048843274, 49765.751171243915, 51414.714632133, 52009.61937474203, 66619.0, 66947.0, 67120.65077722256, 69566.0, 72346.63826680832, 11162.0, 11583.305001699924, 11595.768084638428, 11733.589324373606, 11807.23252988553, 11860.169788114748, 12108.576199832964, 12122.09084318662, 12404.875951240372, 12595.811062833882, 11850.288982661068, 11897.167198296036, 12123.090768192244, 12266.957308628867, 12425.19236249641, 9977.850332244878, 11274.887251127384, 11764.176507644383, 12161.939190303992, 12264.816027759769, 11117.666470005914, 11199.88800112021, 11230.3261804292, 11334.546618135182, 11558.364289963796, 9485.523329758791, 9616.278779091735, 9713.0, 9894.604215831287, 10348.120409764953, 9726.221902247662, 9932.602695892372, 9952.353097048668, 9957.352772069793, 10014.3490673106], "unit": "score", "name": "yui.html", "value": 7167.884562332266}], "name": "dromaeo_css", "value": 7645.765485223246}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 110.0, 109.0, 111.0, 112.0, 119.0, 112.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.4}, {"lowerIsBetter": true, "replicates": [157.0, 125.0, 144.0, 178.0, 145.0, 138.0, 115.0, 151.0, 148.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 142.1}, {"lowerIsBetter": true, "replicates": [186.0, 185.0, 187.0, 186.0, 184.0, 186.0, 187.0, 187.0, 185.0, 186.0], "unit": "score", "name": "audio-dft", "value": 185.9}, {"lowerIsBetter": true, "replicates": [73.0, 78.0, 78.0, 150.0, 82.0, 57.0, 58.0, 57.0, 118.0, 56.0], "unit": "score", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [91.0, 95.0, 130.0, 81.0, 78.0, 86.0, 119.0, 75.0, 79.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 95.6}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 89.0, 89.0, 136.0, 85.0, 88.0, 88.0, 89.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 92.2}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 101.0, 95.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 94.1}, {"lowerIsBetter": true, "replicates": [87.0, 87.0, 85.0, 85.0, 87.0, 87.0, 86.0, 87.0, 87.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 86.4}, {"lowerIsBetter": true, "replicates": [54.0, 59.0, 57.0, 58.0, 57.0, 58.0, 59.0, 70.0, 50.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 72.0, 75.0, 71.0, 67.0, 66.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.0}, {"lowerIsBetter": true, "replicates": [87.0, 81.0, 82.0, 80.0, 81.0, 79.0, 79.0, 78.0, 80.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.9}, {"lowerIsBetter": true, "replicates": [122.0, 119.0, 122.0, 125.0, 117.0, 130.0, 119.0, 118.0, 129.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.8}, {"lowerIsBetter": true, "replicates": [134.0, 124.0, 140.0, 133.0, 131.0, 114.0, 133.0, 134.0, 134.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.1}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 72.0, 66.0, 65.0, 67.0, 66.0, 66.0, 64.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.3}], "name": "kraken", "value": 1413.8999999999999}]} 04:18:13 INFO - Return code: 0 04:18:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:18:14 INFO - # TBPL SUCCESS # 04:18:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:14 INFO - 2016-04-22 04:18:14.030 screenresolution[2581:31757] starting screenresolution argv=screenresolution get 04:18:14 INFO - 2016-04-22 04:18:14.033 screenresolution[2581:31757] Display 0: 1600x1200x32@60 04:18:14 INFO - 2016-04-22 04:18:14.039 screenresolution[2582:31760] starting screenresolution argv=screenresolution list 04:18:14 INFO - Available Modes on Display 0 04:18:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:14 INFO - Intel Iris: 04:18:14 INFO - Chipset Model: Intel Iris 04:18:14 INFO - Type: GPU 04:18:14 INFO - Bus: Built-In 04:18:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:14 INFO - Vendor: Intel (0x8086) 04:18:14 INFO - Device ID: 0x0a2e 04:18:14 INFO - Revision ID: 0x0009 04:18:14 INFO - Displays: 04:18:14 INFO - Display: 04:18:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:14 INFO - Display Serial Number: 1600x1200 60 04:18:14 INFO - Main Display: Yes 04:18:14 INFO - Mirror: Off 04:18:14 INFO - Online: Yes 04:18:14 INFO - Rotation: Supported 04:18:14 INFO - Return code: 0 04:18:14 INFO - Running post-action listener: _resource_record_post_action 04:18:14 INFO - Running post-run listener: _resource_record_post_run 04:18:15 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 0; Write bytes: 664016384; Read time: 0; Write time: 1858 04:18:15 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 354816512; Write bytes: 354477568; Read time: 27817; Write time: 672 04:18:15 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 0; Write bytes: 298360832; Read time: 0; Write time: 1151 04:18:15 INFO - Running post-run listener: _upload_blobber_files 04:18:15 INFO - Blob upload gear active. 04:18:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:15 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 04:18:15 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'] 04:18:15 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 04:18:15 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:18:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:18:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:16 INFO - (blobuploader) - INFO - Done attempting. 04:18:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:18:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:18:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:18 INFO - (blobuploader) - INFO - Done attempting. 04:18:18 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:18 INFO - Return code: 0 04:18:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/670a34113d7008b487a55f2679377407959b0719dcf5082175c99ad52fdd36a3a6a70f0a85365ad82281a51ffd5a0dd491c63e9c82acbde1cc277d0fc45b2e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86538be158cf79f919ae5d21a43c8d3e07e93509b84752e83c9de1c3bdd99073c91f2c79d8e570a2fda8504bd948356aa590e1e5f7c74bd7700d9bad1fe65869"} 04:18:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:18 INFO - Contents: 04:18:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/670a34113d7008b487a55f2679377407959b0719dcf5082175c99ad52fdd36a3a6a70f0a85365ad82281a51ffd5a0dd491c63e9c82acbde1cc277d0fc45b2e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86538be158cf79f919ae5d21a43c8d3e07e93509b84752e83c9de1c3bdd99073c91f2c79d8e570a2fda8504bd948356aa590e1e5f7c74bd7700d9bad1fe65869"} 04:18:18 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:18 INFO - Copying logs to upload dir... 04:18:18 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.816515 ========= master_lag: 1.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-22 04:18:19.152430) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 04:18:19.155752) ========= 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.WlQApPvhJv/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.VBEgAr4QSO/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/670a34113d7008b487a55f2679377407959b0719dcf5082175c99ad52fdd36a3a6a70f0a85365ad82281a51ffd5a0dd491c63e9c82acbde1cc277d0fc45b2e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86538be158cf79f919ae5d21a43c8d3e07e93509b84752e83c9de1c3bdd99073c91f2c79d8e570a2fda8504bd948356aa590e1e5f7c74bd7700d9bad1fe65869"} build_url:https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010870 build_url: 'https://queue.taskcluster.net/v1/task/cBOHv_WYShOQgALqZ38vag/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/670a34113d7008b487a55f2679377407959b0719dcf5082175c99ad52fdd36a3a6a70f0a85365ad82281a51ffd5a0dd491c63e9c82acbde1cc277d0fc45b2e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86538be158cf79f919ae5d21a43c8d3e07e93509b84752e83c9de1c3bdd99073c91f2c79d8e570a2fda8504bd948356aa590e1e5f7c74bd7700d9bad1fe65869"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 04:18:19.193134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 04:18:19.193890) ========= 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.WlQApPvhJv/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.VBEgAr4QSO/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005252 ========= master_lag: 0.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 04:18:19.385876) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 04:18:19.386229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 04:18:19.386784) ========= ========= Total master_lag: 5.76 =========