builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0069 starttime: 1462537100.83 results: success (0) buildid: 20160506013122 builduid: edd2de8de4ca48969474bf5b1f92b92a revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.831931) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.832387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.832663) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.850316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.850656) ========= 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.tfvaqUsKos/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.N6n6FLSMtN/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-06 05:18:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 24.5M=0s 2016-05-06 05:18:20 (24.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097207 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.964441) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:20.964752) ========= 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.tfvaqUsKos/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.N6n6FLSMtN/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.017742 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:21.012883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:18:21.013209) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b14d8e23323e95c5db3bc4cca69738edeee44302 --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 b14d8e23323e95c5db3bc4cca69738edeee44302 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tfvaqUsKos/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.N6n6FLSMtN/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-06 05:18:21,078 truncating revision to first 12 chars 2016-05-06 05:18:21,078 Setting DEBUG logging. 2016-05-06 05:18:21,078 attempt 1/10 2016-05-06 05:18:21,078 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b14d8e23323e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 05:18:22,589 unpacking tar archive at: mozilla-aurora-b14d8e23323e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.748088 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:18:22.777532) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:22.777844) ========= script_repo_revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:22.778209) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:22.778550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:22.791625) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-06 05:18:22.791886) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.tfvaqUsKos/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.N6n6FLSMtN/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:18:22 INFO - MultiFileLogger online at 20160506 05:18:22 in /builds/slave/test 05:18:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:18:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:18:22 INFO - {'append_to_log': False, 05:18:22 INFO - 'base_work_dir': '/builds/slave/test', 05:18:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:18:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:18:22 INFO - 'branch': 'Mozilla-Aurora', 05:18:22 INFO - 'buildbot_json_path': 'buildprops.json', 05:18:22 INFO - 'config_files': ('talos/mac_config.py',), 05:18:22 INFO - 'default_actions': ('clobber', 05:18:22 INFO - 'read-buildbot-config', 05:18:22 INFO - 'download-and-extract', 05:18:22 INFO - 'populate-webroot', 05:18:22 INFO - 'create-virtualenv', 05:18:22 INFO - 'install', 05:18:22 INFO - 'run-tests'), 05:18:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:18:22 INFO - 'download_minidump_stackwalk': True, 05:18:22 INFO - 'download_symbols': 'ondemand', 05:18:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:18:22 INFO - 'tooltool.py': '/tools/tooltool.py', 05:18:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:18:22 INFO - '/tools/misc-python/virtualenv.py')}, 05:18:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:18:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:18:22 INFO - 'installer_path': 'installer.exe', 05:18:22 INFO - 'log_level': 'info', 05:18:22 INFO - 'log_name': 'talos', 05:18:22 INFO - 'log_to_console': True, 05:18:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:18:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:18:22 INFO - 'opt_config_files': (), 05:18:22 INFO - 'pip_index': False, 05:18:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:22 INFO - 'cmd': ('bash', 05:18:22 INFO - '-c', 05:18:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:22 INFO - 'enabled': True, 05:18:22 INFO - 'halt_on_failure': False, 05:18:22 INFO - 'name': 'check_screen_resolution'},), 05:18:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:22 INFO - 'cmd': ('bash', 05:18:22 INFO - '-c', 05:18:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:22 INFO - 'enabled': True, 05:18:22 INFO - 'halt_on_failure': False, 05:18:22 INFO - 'name': 'check_screen_resolution'},), 05:18:22 INFO - 'run_cmd_checks_enabled': True, 05:18:22 INFO - 'sps_profile': False, 05:18:22 INFO - 'sps_profile_interval': 0, 05:18:22 INFO - 'suite': 'dromaeojs-e10s', 05:18:22 INFO - 'system_bits': '32', 05:18:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:18:22 INFO - 'title': 't-yosemite-r7-0069', 05:18:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:18:22 INFO - 'use_talos_json': True, 05:18:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:18:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:18:22 INFO - 'work_dir': 'build'} 05:18:22 INFO - ##### 05:18:22 INFO - ##### Running clobber step. 05:18:22 INFO - ##### 05:18:22 INFO - Running pre-action listener: _resource_record_pre_action 05:18:22 INFO - Running main action method: clobber 05:18:22 INFO - rmtree: /builds/slave/test/build 05:18:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:18:24 INFO - Running post-action listener: _resource_record_post_action 05:18:24 INFO - ##### 05:18:24 INFO - ##### Running read-buildbot-config step. 05:18:24 INFO - ##### 05:18:24 INFO - Running pre-action listener: _resource_record_pre_action 05:18:24 INFO - Running main action method: read_buildbot_config 05:18:24 INFO - Using buildbot properties: 05:18:24 INFO - { 05:18:24 INFO - "project": "", 05:18:24 INFO - "product": "firefox", 05:18:24 INFO - "script_repo_revision": "production", 05:18:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:18:24 INFO - "repository": "", 05:18:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:18:24 INFO - "buildid": "20160506013122", 05:18:24 INFO - "pgo_build": "False", 05:18:24 INFO - "basedir": "/builds/slave/test", 05:18:24 INFO - "buildnumber": 4, 05:18:24 INFO - "slavename": "t-yosemite-r7-0069", 05:18:24 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:18:24 INFO - "platform": "yosemite_r7", 05:18:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:18:24 INFO - "branch": "mozilla-aurora", 05:18:24 INFO - "repo_path": "releases/mozilla-aurora", 05:18:24 INFO - "revision": "b14d8e23323e95c5db3bc4cca69738edeee44302", 05:18:24 INFO - "stage_platform": "macosx64", 05:18:24 INFO - "builduid": "edd2de8de4ca48969474bf5b1f92b92a", 05:18:24 INFO - "slavebuilddir": "test" 05:18:24 INFO - } 05:18:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:18:24 INFO - Running post-action listener: _resource_record_post_action 05:18:24 INFO - ##### 05:18:24 INFO - ##### Running download-and-extract step. 05:18:24 INFO - ##### 05:18:24 INFO - Running pre-action listener: _resource_record_pre_action 05:18:24 INFO - Running main action method: download_and_extract 05:18:24 INFO - mkdir: /builds/slave/test/build/tests 05:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:24 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 05:18:25 INFO - Downloaded 1199 bytes. 05:18:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:25 INFO - Using the following test package requirements: 05:18:25 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:18:25 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:18:25 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:18:25 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'jsshell-mac.zip'], 05:18:25 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:18:25 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:18:25 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:18:25 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:18:25 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:18:25 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:25 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:18:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:18:25 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')]} 05:18:25 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:26 INFO - Downloaded 18113035 bytes. 05:18:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:29 INFO - Return code: 0 05:18:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:29 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:31 INFO - Downloaded 18113035 bytes. 05:18:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:34 INFO - Return code: 0 05:18:34 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')]} 05:18:34 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:18:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:18:36 INFO - Downloaded 10840145 bytes. 05:18:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:18:36 INFO - Return code: 0 05:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:36 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:18:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:18:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 05:18:39 INFO - Downloaded 114079746 bytes. 05:18:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:39 INFO - mkdir: /builds/slave/test/properties 05:18:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:39 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:39 INFO - Contents: 05:18:39 INFO - build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:40 INFO - Running post-action listener: _resource_record_post_action 05:18:40 INFO - Running post-action listener: set_extra_try_arguments 05:18:40 INFO - ##### 05:18:40 INFO - ##### Running populate-webroot step. 05:18:40 INFO - ##### 05:18:40 INFO - Running pre-action listener: _resource_record_pre_action 05:18:40 INFO - Running main action method: populate_webroot 05:18:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:40 INFO - '--tppagecycles', 05:18:40 INFO - '7'], 05:18:40 INFO - 'tests': ['tsvgm']}}, 05:18:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['damp', 'tps']}, 05:18:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'tests': ['damp', 'tps']}, 05:18:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['dromaeo_dom']}, 05:18:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['a11yr', 05:18:40 INFO - 'ts_paint', 05:18:40 INFO - 'tpaint', 05:18:40 INFO - 'sessionrestore', 05:18:40 INFO - 'sessionrestore_no_auto_restore', 05:18:40 INFO - 'tabpaint']}, 05:18:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:40 INFO - 'ts_paint', 05:18:40 INFO - 'tpaint', 05:18:40 INFO - 'sessionrestore', 05:18:40 INFO - 'sessionrestore_no_auto_restore', 05:18:40 INFO - 'tabpaint']}, 05:18:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:40 INFO - 'ts_paint', 05:18:40 INFO - 'tpaint', 05:18:40 INFO - 'sessionrestore', 05:18:40 INFO - 'sessionrestore_no_auto_restore', 05:18:40 INFO - 'tabpaint']}, 05:18:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:40 INFO - 'ts_paint', 05:18:40 INFO - 'tpaint', 05:18:40 INFO - 'sessionrestore', 05:18:40 INFO - 'sessionrestore_no_auto_restore', 05:18:40 INFO - 'tabpaint']}, 05:18:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['a11yr', 05:18:40 INFO - 'ts_paint', 05:18:40 INFO - 'tpaint', 05:18:40 INFO - 'sessionrestore', 05:18:40 INFO - 'sessionrestore_no_auto_restore', 05:18:40 INFO - 'tabpaint']}, 05:18:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['a11yr', 05:18:40 INFO - 'ts_paint', 05:18:40 INFO - 'tpaint', 05:18:40 INFO - 'sessionrestore', 05:18:40 INFO - 'sessionrestore_no_auto_restore', 05:18:40 INFO - 'tabpaint']}, 05:18:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['tsvgx', 05:18:40 INFO - 'tsvgr_opacity', 05:18:40 INFO - 'tart', 05:18:40 INFO - 'tscrollx', 05:18:40 INFO - 'cart']}, 05:18:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:40 INFO - 'tsvgr_opacity', 05:18:40 INFO - 'tart', 05:18:40 INFO - 'tscrollx', 05:18:40 INFO - 'cart']}, 05:18:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'talos_options': ['--disable-e10s'], 05:18:40 INFO - 'tests': ['tp5o']}, 05:18:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'tests': ['tp5o']}, 05:18:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'talos_options': ['--disable-e10s', 05:18:40 INFO - '--xperf_path', 05:18:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:40 INFO - 'tests': ['tp5n']}, 05:18:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:40 INFO - 'talos_options': ['--xperf_path', 05:18:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:40 INFO - 'tests': ['tp5n']}}, 05:18:40 INFO - 'talos.zip': {'path': '', 05:18:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:40 INFO - Running post-action listener: _resource_record_post_action 05:18:40 INFO - ##### 05:18:40 INFO - ##### Running create-virtualenv step. 05:18:40 INFO - ##### 05:18:40 INFO - Running pre-action listener: _resource_record_pre_action 05:18:40 INFO - Running main action method: create_virtualenv 05:18:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:40 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 05:18:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:40 INFO - Using real prefix '/tools/python27' 05:18:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:44 INFO - Installing pip.................done. 05:18:44 INFO - Return code: 0 05:18:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:18: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', 'pip>=1.5'] in /builds/slave/test/build 05:18: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 pip>=1.5 05:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:44 INFO - 'HOME': '/Users/cltbld', 05:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:44 INFO - 'LOGNAME': 'cltbld', 05:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:44 INFO - 'MOZ_NO_REMOTE': '1', 05:18:44 INFO - 'NO_EM_RESTART': '1', 05:18:44 INFO - 'PAGER': '/bin/cat', 05:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:44 INFO - 'PWD': '/builds/slave/test', 05:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:44 INFO - 'SHELL': '/bin/bash', 05:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:18:44 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:44 INFO - 'USER': 'cltbld', 05:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:44 INFO - 'XPC_FLAGS': '0x0', 05:18:44 INFO - 'XPC_SERVICE_NAME': '0', 05:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:44 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 05:18:44 INFO - Cleaning up... 05:18:44 INFO - Return code: 0 05:18:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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', '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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:18: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', 'psutil>=0.7.1'] in /builds/slave/test/build 05:18: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 psutil>=0.7.1 05:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:44 INFO - 'HOME': '/Users/cltbld', 05:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:44 INFO - 'LOGNAME': 'cltbld', 05:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:44 INFO - 'MOZ_NO_REMOTE': '1', 05:18:44 INFO - 'NO_EM_RESTART': '1', 05:18:44 INFO - 'PAGER': '/bin/cat', 05:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:44 INFO - 'PWD': '/builds/slave/test', 05:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:44 INFO - 'SHELL': '/bin/bash', 05:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:18:44 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:44 INFO - 'USER': 'cltbld', 05:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:44 INFO - 'XPC_FLAGS': '0x0', 05:18:44 INFO - 'XPC_SERVICE_NAME': '0', 05:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:44 INFO - Downloading/unpacking psutil>=0.7.1 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:48 INFO - Installing collected packages: psutil 05:18:48 INFO - Running setup.py install for psutil 05:18:48 INFO - building 'psutil._psutil_osx' extension 05:18:48 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 05:18:48 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 05:18:48 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 05:18:48 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 05:18:48 INFO - building 'psutil._psutil_posix' extension 05:18:48 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 05:18:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:48 INFO - ^ 05:18:48 INFO - 1 warning generated. 05:18:48 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 05:18:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:48 INFO - Successfully installed psutil 05:18:48 INFO - Cleaning up... 05:18:48 INFO - Return code: 0 05:18:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:18:48 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')]} 05:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:48 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')]} 05:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:48 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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:18:48 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 05:18:48 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 05:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:48 INFO - 'HOME': '/Users/cltbld', 05:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:48 INFO - 'LOGNAME': 'cltbld', 05:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:48 INFO - 'MOZ_NO_REMOTE': '1', 05:18:48 INFO - 'NO_EM_RESTART': '1', 05:18:48 INFO - 'PAGER': '/bin/cat', 05:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:48 INFO - 'PWD': '/builds/slave/test', 05:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:48 INFO - 'SHELL': '/bin/bash', 05:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:18:48 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:48 INFO - 'USER': 'cltbld', 05:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:48 INFO - 'XPC_FLAGS': '0x0', 05:18:48 INFO - 'XPC_SERVICE_NAME': '0', 05:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:18:51 INFO - Installing collected packages: mozsystemmonitor 05:18:51 INFO - Running setup.py install for mozsystemmonitor 05:18:51 INFO - Successfully installed mozsystemmonitor 05:18:51 INFO - Cleaning up... 05:18:51 INFO - Return code: 0 05:18:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:51 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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:18:51 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 05:18:51 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 05:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:51 INFO - 'HOME': '/Users/cltbld', 05:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:51 INFO - 'LOGNAME': 'cltbld', 05:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:51 INFO - 'MOZ_NO_REMOTE': '1', 05:18:51 INFO - 'NO_EM_RESTART': '1', 05:18:51 INFO - 'PAGER': '/bin/cat', 05:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:51 INFO - 'PWD': '/builds/slave/test', 05:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:51 INFO - 'SHELL': '/bin/bash', 05:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:18:51 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:51 INFO - 'USER': 'cltbld', 05:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:51 INFO - 'XPC_FLAGS': '0x0', 05:18:51 INFO - 'XPC_SERVICE_NAME': '0', 05:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:52 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 05:18:52 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 05:18:52 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 05:18:52 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 05:18:52 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 05:18:52 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 05:18:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:55 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 05:18:55 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 05:18:55 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 05:18:55 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 05:18:55 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 05:18:55 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 05:18:55 INFO - Downloading docopt-0.6.1.tar.gz 05:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:55 INFO - Running setup.py install for blobuploader 05:18:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:55 INFO - Running setup.py install for requests 05:18:56 INFO - Running setup.py install for docopt 05:18:56 INFO - Successfully installed blobuploader requests docopt 05:18:56 INFO - Cleaning up... 05:18:56 INFO - Return code: 0 05:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:56 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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:18:56 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 05:18:56 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 05:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:56 INFO - 'HOME': '/Users/cltbld', 05:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:56 INFO - 'LOGNAME': 'cltbld', 05:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:56 INFO - 'MOZ_NO_REMOTE': '1', 05:18:56 INFO - 'NO_EM_RESTART': '1', 05:18:56 INFO - 'PAGER': '/bin/cat', 05:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:56 INFO - 'PWD': '/builds/slave/test', 05:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:56 INFO - 'SHELL': '/bin/bash', 05:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:18:56 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:56 INFO - 'USER': 'cltbld', 05:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:56 INFO - 'XPC_FLAGS': '0x0', 05:18:56 INFO - 'XPC_SERVICE_NAME': '0', 05:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-AgywXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-bKpOPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-zb61sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-FZiok3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-LGotEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-1K53rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-2iD8tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-bqNP71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-6p1TDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:57 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-IKkb5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-RKZEfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-dPJviZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-10Mj5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-ej4BE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-rIGXZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-exU55I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-2nPfkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:58 INFO - Running setup.py install for manifestparser 05:18:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:58 INFO - Running setup.py install for mozcrash 05:18:59 INFO - Running setup.py install for mozdebug 05:18:59 INFO - Running setup.py install for mozdevice 05:18:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Running setup.py install for mozfile 05:18:59 INFO - Running setup.py install for mozhttpd 05:18:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Running setup.py install for mozinfo 05:18:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Running setup.py install for mozInstall 05:18:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:59 INFO - Running setup.py install for mozleak 05:19:00 INFO - Running setup.py install for mozlog 05:19:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:00 INFO - Running setup.py install for moznetwork 05:19:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:00 INFO - Running setup.py install for mozprocess 05:19:00 INFO - Running setup.py install for mozprofile 05:19:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:00 INFO - Running setup.py install for mozrunner 05:19:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:00 INFO - Running setup.py install for mozscreenshot 05:19:01 INFO - Running setup.py install for moztest 05:19:01 INFO - Running setup.py install for mozversion 05:19:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:01 INFO - Cleaning up... 05:19:01 INFO - Return code: 0 05:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:01 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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:19:01 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 05:19:01 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 05:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:01 INFO - 'HOME': '/Users/cltbld', 05:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:01 INFO - 'LOGNAME': 'cltbld', 05:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:01 INFO - 'MOZ_NO_REMOTE': '1', 05:19:01 INFO - 'NO_EM_RESTART': '1', 05:19:01 INFO - 'PAGER': '/bin/cat', 05:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:01 INFO - 'PWD': '/builds/slave/test', 05:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:01 INFO - 'SHELL': '/bin/bash', 05:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:19:01 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:01 INFO - 'USER': 'cltbld', 05:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:01 INFO - 'XPC_FLAGS': '0x0', 05:19:01 INFO - 'XPC_SERVICE_NAME': '0', 05:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-J23piv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:01 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)) 05:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-BsWniK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:01 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)) 05:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:01 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-SEK9IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-4iTKnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-oK7aFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-V8R5pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-MijeoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-rGLmmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-lNuxM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-AZ_wTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-WLtVT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-4YQn0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:02 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)) 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-jFUsSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:03 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)) 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Gj04xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:03 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)) 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-RX1Fzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:03 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)) 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-g22CJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:03 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)) 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-LMpWiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:03 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)) 05:19:03 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)) 05:19:03 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)) 05:19:03 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)) 05:19:03 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)) 05:19:03 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)) 05:19:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:03 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 05:19:03 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 05:19:03 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 05:19:03 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 05:19:03 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 05:19:03 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 05:19:05 INFO - Downloading blessings-1.6.tar.gz 05:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:06 INFO - Installing collected packages: blessings 05:19:06 INFO - Running setup.py install for blessings 05:19:06 INFO - Successfully installed blessings 05:19:06 INFO - Cleaning up... 05:19:06 INFO - Return code: 0 05:19:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:06 INFO - Reading from file tmpfile_stdout 05:19:06 INFO - Current package versions: 05:19:06 INFO - blessings == 1.6 05:19:06 INFO - blobuploader == 1.2.4 05:19:06 INFO - docopt == 0.6.1 05:19:06 INFO - manifestparser == 1.1 05:19:06 INFO - mozInstall == 1.12 05:19:06 INFO - mozcrash == 0.17 05:19:06 INFO - mozdebug == 0.1 05:19:06 INFO - mozdevice == 0.48 05:19:06 INFO - mozfile == 1.2 05:19:06 INFO - mozhttpd == 0.7 05:19:06 INFO - mozinfo == 0.9 05:19:06 INFO - mozleak == 0.1 05:19:06 INFO - mozlog == 3.1 05:19:06 INFO - moznetwork == 0.27 05:19:06 INFO - mozprocess == 0.22 05:19:06 INFO - mozprofile == 0.28 05:19:06 INFO - mozrunner == 6.11 05:19:06 INFO - mozscreenshot == 0.1 05:19:06 INFO - mozsystemmonitor == 0.0 05:19:06 INFO - moztest == 0.7 05:19:06 INFO - mozversion == 1.4 05:19:06 INFO - psutil == 3.1.1 05:19:06 INFO - requests == 1.2.3 05:19:06 INFO - wsgiref == 0.1.2 05:19:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:19:06 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 05:19:06 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 05:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:06 INFO - 'HOME': '/Users/cltbld', 05:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:06 INFO - 'LOGNAME': 'cltbld', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:06 INFO - 'SHELL': '/bin/bash', 05:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:06 INFO - 'USER': 'cltbld', 05:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:06 INFO - 'XPC_FLAGS': '0x0', 05:19:06 INFO - 'XPC_SERVICE_NAME': '0', 05:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 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)) 05:19:06 INFO - Cleaning up... 05:19:06 INFO - Return code: 0 05:19:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:06 INFO - 'HOME': '/Users/cltbld', 05:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:06 INFO - 'LOGNAME': 'cltbld', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:06 INFO - 'SHELL': '/bin/bash', 05:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:06 INFO - 'USER': 'cltbld', 05:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:06 INFO - 'XPC_FLAGS': '0x0', 05:19:06 INFO - 'XPC_SERVICE_NAME': '0', 05:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:07 INFO - Downloading/unpacking jsonschema 05:19:07 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 05:19:07 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 05:19:07 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 05:19:07 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 05:19:07 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 05:19:07 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 05:19:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:09 INFO - Downloading/unpacking functools32 (from jsonschema) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:10 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:10 INFO - warning: no files found matching '*.txt' 05:19:10 INFO - no previously-included directories found matching 'build' 05:19:10 INFO - no previously-included directories found matching 'dist' 05:19:10 INFO - no previously-included directories found matching '.git*' 05:19:10 INFO - Installing collected packages: jsonschema, functools32 05:19:10 INFO - Running setup.py install for functools32 05:19:10 INFO - warning: no files found matching '*.txt' 05:19:10 INFO - no previously-included directories found matching 'build' 05:19:10 INFO - no previously-included directories found matching 'dist' 05:19:10 INFO - no previously-included directories found matching '.git*' 05:19:10 INFO - Successfully installed jsonschema functools32 05:19:10 INFO - Cleaning up... 05:19:10 INFO - Return code: 0 05:19:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:10 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 0x103e51a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e61420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bcbbc80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, '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.tfvaqUsKos/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.N6n6FLSMtN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 05:19:10 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 05:19:10 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 05:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:10 INFO - 'HOME': '/Users/cltbld', 05:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:10 INFO - 'LOGNAME': 'cltbld', 05:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:10 INFO - 'MOZ_NO_REMOTE': '1', 05:19:10 INFO - 'NO_EM_RESTART': '1', 05:19:10 INFO - 'PAGER': '/bin/cat', 05:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:10 INFO - 'PWD': '/builds/slave/test', 05:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:10 INFO - 'SHELL': '/bin/bash', 05:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:19:10 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:10 INFO - 'USER': 'cltbld', 05:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:10 INFO - 'XPC_FLAGS': '0x0', 05:19:10 INFO - 'XPC_SERVICE_NAME': '0', 05:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:10 INFO - Downloading/unpacking flake8 05:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:19:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:19:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:19:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:14 INFO - no previously-included directories found matching 'docs/_build' 05:19:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:19:14 INFO - Running setup.py install for pep8 05:19:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:14 INFO - no previously-included directories found matching 'docs/_build' 05:19:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:19:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:19:14 INFO - Cleaning up... 05:19:14 INFO - Return code: 0 05:19:14 INFO - Running post-action listener: _resource_record_post_action 05:19:14 INFO - Running post-action listener: _start_resource_monitoring 05:19:14 INFO - Starting resource monitoring. 05:19:14 INFO - ##### 05:19:14 INFO - ##### Running install step. 05:19:14 INFO - ##### 05:19:14 INFO - Running pre-action listener: _resource_record_pre_action 05:19:14 INFO - Running main action method: install 05:19:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:14 INFO - Reading from file tmpfile_stdout 05:19:14 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:15 INFO - Reading from file tmpfile_stdout 05:19:15 INFO - Output received: 05:19:15 INFO - Usage: mozinstall [options] installer 05:19:15 INFO - Options: 05:19:15 INFO - -h, --help show this help message and exit 05:19:15 INFO - -d DEST, --destination=DEST 05:19:15 INFO - Directory to install application into. [default: 05:19:15 INFO - "/builds/slave/test"] 05:19:15 INFO - --app=APP Application being installed. [default: firefox] 05:19:15 INFO - mkdir: /builds/slave/test/build/application 05:19:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:46 INFO - Reading from file tmpfile_stdout 05:19:46 INFO - Output received: 05:19:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:19:46 INFO - Running post-action listener: _resource_record_post_action 05:19:46 INFO - ##### 05:19:46 INFO - ##### Running run-tests step. 05:19:46 INFO - ##### 05:19:46 INFO - Running pre-action listener: _resource_record_pre_action 05:19:46 INFO - Running main action method: run_tests 05:19:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:46 INFO - 2016-05-06 05:19:46.513 screenresolution[1918:14594] starting screenresolution argv=screenresolution get 05:19:46 INFO - 2016-05-06 05:19:46.515 screenresolution[1918:14594] Display 0: 1600x1200x32@60 05:19:46 INFO - 2016-05-06 05:19:46.520 screenresolution[1919:14596] starting screenresolution argv=screenresolution list 05:19:46 INFO - Available Modes on Display 0 05:19:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:46 INFO - Intel Iris: 05:19:46 INFO - Chipset Model: Intel Iris 05:19:46 INFO - Type: GPU 05:19:46 INFO - Bus: Built-In 05:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:46 INFO - Vendor: Intel (0x8086) 05:19:46 INFO - Device ID: 0x0a2e 05:19:46 INFO - Revision ID: 0x0009 05:19:46 INFO - Displays: 05:19:46 INFO - Display: 05:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:46 INFO - Display Serial Number: 1600x1200 60 05:19:46 INFO - Main Display: Yes 05:19:46 INFO - Mirror: Off 05:19:46 INFO - Online: Yes 05:19:46 INFO - Rotation: Supported 05:19:46 INFO - Return code: 0 05:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:46 INFO - Python 2.7.3 05:19:46 INFO - Return code: 0 05:19:46 INFO - grabbing minidump binary from tooltool 05:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:46 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 0x103cf0e00>, '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 0x103b59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103cebb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:46 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 05:19:46 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 05:19:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:46 INFO - Return code: 0 05:19:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:19:47 INFO - Return code: 0 05:19:47 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:19:47 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfvaqUsKos/Render', 05:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:47 INFO - 'HOME': '/Users/cltbld', 05:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:47 INFO - 'LOGNAME': 'cltbld', 05:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:47 INFO - 'MOZ_NO_REMOTE': '1', 05:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:47 INFO - 'NO_EM_RESTART': '1', 05:19:47 INFO - 'PAGER': '/bin/cat', 05:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:47 INFO - 'PWD': '/builds/slave/test', 05:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:47 INFO - 'SHELL': '/bin/bash', 05:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N6n6FLSMtN/Listeners', 05:19:47 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 05:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:47 INFO - 'USER': 'cltbld', 05:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:47 INFO - 'XPC_FLAGS': '0x0', 05:19:47 INFO - 'XPC_SERVICE_NAME': '0', 05:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:19:48 INFO - mozversion application_buildid: 20160506013122 05:19:48 INFO - mozversion application_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:19:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:19:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:48 INFO - mozversion application_name: Firefox 05:19:48 INFO - mozversion application_remotingname: firefox-dev 05:19:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:19:48 INFO - mozversion application_vendor: Mozilla 05:19:48 INFO - mozversion application_version: 48.0a2 05:19:48 INFO - mozversion platform_buildid: 20160506013122 05:19:48 INFO - mozversion platform_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:19:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:19:48 INFO - mozversion platform_version: 48.0a2 05:19:48 INFO - using testdate: 1462537188 05:19:48 INFO - actual date: 1462537188 05:19:48 INFO - starting webserver on 'localhost:53793' 05:19:48 INFO - SUITE-START | Running 2 tests 05:19:48 INFO - TEST-START | dromaeo_css 05:19:48 INFO - operating with platform_type : mac_ 05:19:48 INFO - Initialising browser for dromaeo_css test... 05:19:48 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpLeqdJ_/profile http://localhost:53793/getInfo.html) 05:19:51 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 05:19:51 INFO - PROCESS | 1937 | colorDepth:24 05:19:51 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 05:19:51 INFO - PROCESS | 1937 | __metrics 05:19:51 ERROR - PROCESS | 1937 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:19:51 INFO - TEST-INFO | 1937: exit 0 05:19:51 INFO - Browser initialized. 05:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:51 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpLeqdJ_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:03 INFO - PROCESS | 1940 | RSS: Main: 221310976 05:20:03 INFO - PROCESS | 1940 | 05:20:53 INFO - PROCESS | 1940 | 1462537253365 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] 05:23:04 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:53793/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53793/tests/dromaeo/cssquery-ext.html) 05:23:05 INFO - PROCESS | 1940 | RSS: Main: 232034304 05:23:05 INFO - PROCESS | 1940 | 05:26:07 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:53793/tests/dromaeo/cssquery-ext.html (next: http://localhost:53793/tests/dromaeo/cssquery-jquery.html) 05:26:08 INFO - PROCESS | 1940 | RSS: Main: 243691520 05:26:08 INFO - PROCESS | 1940 | 05:29:10 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:53793/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53793/tests/dromaeo/cssquery-mootools.html) 05:29:11 INFO - PROCESS | 1940 | RSS: Main: 245506048 05:29:11 INFO - PROCESS | 1940 | 05:32:12 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:53793/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53793/tests/dromaeo/cssquery-prototype.html) 05:32:13 INFO - PROCESS | 1940 | RSS: Main: 247013376 05:32:13 INFO - PROCESS | 1940 | 05:35:15 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:53793/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53793/tests/dromaeo/cssquery-yui.html) 05:35:16 INFO - PROCESS | 1940 | RSS: Main: 250421248 05:35:16 INFO - PROCESS | 1940 | 05:38:18 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:53793/tests/dromaeo/cssquery-yui.html (next: http://localhost:53793/tests/dromaeo/cssquery-dojo.html) 05:38:19 INFO - PROCESS | 1940 | RSS: Main: 251260928 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | __start_tp_report 05:38:19 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 05:38:19 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 05:38:19 INFO - PROCESS | 1940 | |i|pagename|runs| 05:38:19 INFO - PROCESS | 1940 | |0;dojo.html;3419.4186988212;3475.2875660489594;3505;3506.438969764838;3531.717462602992;5688.829335119943;5708.08670612702;5710;5724.08414653656;5746.942530574693;4353.86938391848;5332.200169974506;5684.175794509793;5690.975624387608;5698.344690360599;5163.122269214233;5220.425753167148;5228.686278823264;5277.683338999673;5297.973510132444;4275.957240427602;5115.462876397981;5238.1357076082595;5316.787328506855;5335.1997200420055;5244.239585260135;5271.525562699357;5272.499112584298;5274.077036518594;5341.30563026806;5228.372595288552;5234.450382709837;5235.58115350771;5248.134057880464;5285;5015.1975683890405;5054.469280725544;5145.382554093533;5164.070467315882;5183.844484665466;5173.758298008488;5442.292501974757;5593.720313984325;5638.4361563843695;5653.349864765563;5084.389873215201;6068.362821903681;6264.342244064399;6280.309165991737;6286.214223222098;6151.938480615226;6278.215223097113;6319.684015799192;6344.238691357066;6356.936430635681;5840.912386314209;5863.299643103494;5941.970290148522;5944.910826337608;5976.252968378996;6180.134781130645;6265.655388953652;6282.434580887742;6333.809985700436;6362.968185159045;5419.485148910847;6082.422169893853;6155.261368635793;6176.166217560572;6188.257409110935;6200.782972595937;6229.762984069446;6280.183576135073;6291.370862913764;6332.588381755171;6112.473085128069;6170.81487555374;6235.75056997715;6237.095621135;6265.8120256392385;6187.659678717624;6241.18864547615;6272.341404152498;6274.247090349188;6291.1192433059405;4886.853394398174;4926.187179115477;4943.2585112233455;4949.034938187019;4949.48030456804;4300.182965236595;4356.019895523482;4397.582229688174;4464.397306363664;4484.192845287815;5673.5461163107675;5713.114467257582;5722.513586345124;5731.914021289684;5760.8271751847515;5111.080005599028;6256.906146407808;6297.086922396227;6335;6338.778142764981;5590.748416321276;5703.914441283384;5756.597038207286;5807.535397168216;5839.124131380242;5393.703346315911;5442.700651464128;5452.100403433468;5453.727313634382;5460.126379779216;6380.138681277968;6425.293217746138;6428.132202152743;6434.0670602763275;6450.290468048511;5237.371515418175;6172.135900973957;6235.127082208403;6317;6329.873402531832;6301.149344838388;6303.306636269915;6304.84237894038;6312.5265605078885;6321.051842223703;5374.3013408256675;6049.455549000611;6065.3934606539;6072.30168530607;6074.787382441594;5893.292804863272;6279.497640188682;6281.246250449793;6282.151909492343;6310.684465776784;5988.191594134911;6099.237595300588;6152.50779937595;6158.168647232566;6176.474999625082;5921.289445266596;6071.119687645179;6100.572959892765;6101.328853826164;6154.41533054377;5569.746806968562;5870.26621672291;5871.6183448077445;5937.19847820556;6008.7296071676;4417.955820441754;5060.240963855304;6164.568480206342;6169.413905678954;6170.074488826712;5830.76676932918;6055.333913269449;6063.757449701962;6067.484263837623;6080 05:38:19 INFO - PROCESS | 1940 | |1;ext.html;466.97665116744156;534.7540131539491;560.0311461172172;565.146628590828;576.9509591684706;38741;39777.80111099448;39809.80095099524;39869.202615947666;39884.60115398845;34284.314313713774;39943.60197393092;40524.39213411801;41508.79245603768;41537;9403.529823508832;9522.285828562852;9660.758481037996;9680.854787178163;9697.224222062217;9249.583768730423;9404.529773511296;9411.152996230338;9432.24542036639;9497.335186536946;9386.389884657476;9394.718158455224;9445.338826282128;9457.527123643791;9477.95261023694;8079.272865442138;9355.532223388804;9427;9454.29092818038;9499.097585729345;9648.276379271512;9738.756531086708;9758.804823903552;9760.609575616967;9764.853527197098;39615.801920990496;40064;40109.598904010876;40125.799371002955;40205.597944020476;2790.916272511828;3019.4715924713087;3142.245860993368;3214.276787722767;3274.9345013099605;26358.23238559943;26375.73624263771;26809.329766755793;26925.730742692518;27487.45025099487;2670.8186118929216;2700.3249187703072;2700.9594856077174;2786.9303267418477;2823.5623478360885;319897;324261;326678;327141.36429317703;327789;33062.008139755846;34079.125648089095;34551.308973820385;34581.82709086438;34861.30277394438;34403.2798360086;34790.78232261859;34813.65186348155;34916.65083349134;35153.82423087868;15333.769993450107;15519;15541.533753987398;15553.066815991077;15571.065736055873;10748.677539673823;13094.214347139203;13104.606861794162;13134.277614731282;13179.736405272033;8112.716054938049;8179.672813087529;8192.54940978252;8343.582820858932;8367.790805229917;2787.28924124347;3457.928042306893;3467.1678797088516;3479.913002174966;3500.1424650960676;949.8812648418948;971.4074414607084;972.2465089555651;1139.1684070628487;1150.2408410449063;25681.74318256831;29267.39029353406;29967.25081872927;30235.395292094036;30854;1103.4868786014542;1111;1137.3460260350332;1167.2529581068122;1191.8152686333633;13551.864481355258;13625.84180344662;13819.825314848149;14873.851261487463;15362.385504579916;360071.1996440001;395993;435773;436389;659152;61171;61848.381516184265;62610.68694656497;62639;62749.68625156845;1311.7179806341683;1476.793248945127;1497.4384605772834;1502.4065494129757;1512.5840393891547;28995.85502072476;30368.84815575908;30447.695523044487;30643;31081.68918310788;51915.2212716802;52416.73791631017;52672;52679.73660131675;52728;577631;593086;594268;594797;595696;23138.3058508245;27343.179704608894;28059.43881122407;28275.151745446845;28321.716782831907;44371.556284436745;45105.32342014938;45368.31947520724;45911.3113303294;46234.07531849411;37638.81180594189;37704.245915080995;37767.62232377641;38215.80892095522;38357.232855343296;28082.85958570194;36998;37650.81174594109;37757.244855103294;37828.43257351087;35999;36393.63606363902;36424.63575364213;36556.903292901254;36608;29379;30607.2348191297;32774.34451311008;33179.83870564518;33424;20719.860407677723;24894.12870549522;25144.74855251497;25148.5413905563;25313.62029569521 05:38:19 INFO - PROCESS | 1940 | |2;jquery.html;404.8239016028028;405.19973053219894;405.46073721949807;411.2392074661874;414.8672424824056;11816.350100744456;12385;19684.704729429053;20983.37049888504;21037.579248415022;18122.093895305246;21838.672419913673;22610;22946.885265573692;23930;6717.462602991774;6819.352161544658;7038.225795162528;7086.858262834766;7133.429325653961;6903.827404314882;6904.378605925465;6949.166100067999;6954.269801670827;6967.442604591646;5605.7197140143035;6831.590104593715;6901.206361268468;6947.374736273734;6971.511994160411;6725.2602213756445;6809.931900680979;6881.9655901720425;6895.862082758342;6935.271796461375;6684.465242780566;6735.124433823572;6762.087118239073;6780.457563394966;6795.762148324785;28214.153575392567;28701.712982870115;29886.85056574725;30437.39125217483;30451.086467406014;2275.24916777463;2303.424143964009;2382.6783384243076;2439.829211955164;2740.6026126211787;13873.58379248624;15371.769423458658;16781.32874685011;17054.403095891583;17257.791954563185;1535.9385624575004;1893.4035778729794;1921.6060707554918;1950.6293804177303;1965.341610560464;101152.98847011509;120084.7991520091;137578;140240;142247.28876355552;22276.220332288292;22395.664065038713;22496.100155993903;22654.886725566266;22802.20192293295;14417.495387661382;36642.900712978655;37084.25831483409;37197;37238.696645617274;8926.196642302224;9071.954640226757;9124.17882390588;9162.81674366509;9193.080691930754;9103.772405689911;9107;9139.314551408672;9147.2682185425;9183.943846457609;4706.670533062653;4741.336212930192;4759.333693282943;4762.214234651244;4807.302941073524;1769.8849574777598;2000.459875833517;2055.8560900736807;2060.546679730487;2062.7834077421953;853.833502467013;925.227435091706;937.779621788879;983.7098056073476;1056.5192837259028;11667.708307292216;19911.900440497706;20594.691079633816;20616.69074963877;21297.36107916765;435.95640435956784;446.17012357015926;455.5785898044362;459.7057882954912;471.3142377840234;5351.759170837322;5928.614640048383;6234.625922444668;6257.9687101564205;6315.147455093595;616174;625741;637319;639852;643933;116654.41672791581;122547;142296.28851855674;143616;145771.62685119463;395.29834543684615;454.8067071494602;468.9320048592935;468.9788959496899;470.27107982627473;11246.044086252434;11388.977699624278;11692.70768230801;11745.412729363668;11850.644480665595;52633.7368313156;55500.72249638726;56290;56404.153937691786;56678;81307;82653.58673206596;83299.5835020821;83755;84724.30551388815;7990.760277191694;8342.040665323322;8381.253091261593;8467.53428561435;8468.407211495136;10741.731456713645;11270.94364528172;12535.811962820748;12843.34229658393;12950.611481655566;10008.749781255527;10327;10899.455027248447;10907.618233362111;11147.777044459228;9633.22934165251;10416.270861039957;10889.618863340062;10948.835767463654;10977.780444391226;11044.613438529614;11086.778264434828;11093.057090147106;11564.479598417924;11618.477168527268;5506.697131657797;8369.49783013021;8385.874211886705;8390.160983901562;8523.275521580736;8395.748127556182;10519.631812886511;11025.283356581796;11202.775944481227;11368.261063030877 05:38:19 INFO - PROCESS | 1940 | |3;mootools.html;1564.6244901223706;1592.482443205958;1596.5210436868945;1930.787613362529;1965.4594986378736;7939.444238903279;8524.573771311425;8548.829023419536;8879.934407871056;8924.643014279429;8512.106228845974;8556.614952327145;8557.529335886524;8650.610722517486;8656.697015604455;2434.099383228204;2453.472503411775;2470.06137667686;2477.331120597438;2481.3548477395916;2424.187897054489;2476.8885400157037;2482.795844015651;2484.354067942339;2498.3379404457833;2445.4131008557906;2470.308313672175;2503.086373473678;2512.773850353468;2517.2070797698666;2460.6678098456746;2466.087547607379;2486.614574740918;2501.5997440409446;2504.461540768725;2472.6909136357954;2474.6411770293385;2492.028109037477;2499.175272160201;2503.949921001588;8775.25410340122;8832.646694132227;8841.292696584258;8857.247133993618;8864.290856731446;5267.841964741064;5460.781568737246;5470.425605311425;5495.065838807413;5507.256520369739;2730.5221586222333;3484.61669216386;3661.139632186434;3668.981655091734;3705.7776533408082;1031.9638812641597;1041.3959903256093;1048.4810019040624;1061.0821639282042;1077.0629552289479;11392;11452.255603385753;11466.94266528662;11484.253523520945;11492.885071149265;4829.082474329866;4843.588294994895;4909.116359055404;4964.8758781030765;5042.016806722654;9889.208863291064;9917.752056198653;9976.351537150062;9987.101160895554;9997.050280223368;5365.704886231217;5393.6224464287125;5415.566754659618;5486.807961721401;5494.313210848644;4453.643708503312;4830;4929.827456039022;4965.627577931669;4987.152184128707;4093.099518106012;4125.580190840472;4140.192662430797;4150.107726838744;4161.375793630919;1159.675290918544;1232.8027515597462;1327.7543654423769;1346.8282594143159;1381.0263764046324;910.6676063236871;912.771807048238;925.0703043441298;928.79102202004;945.4847108325841;5705.771769129188;5727.821487044002;5838.79564215259;5865.824025279248;5884.382139875252;915.6631318275392;933.3000249812641;1018.5976539267097;1026.0201507560262;1031.6853359725205;3091.2271932016997;3407.216340241758;3660.908477288089;3905.1603905160528;3978.8209530570616;8663.436876603;10388.23400021998;10830.458477076272;10867.510962006729;10910.836337454944;8919.331050171264;9123.589438475285;10658.893411065945;10688.038076573146;10874.836877446845;8092.635831387602;8162.7143049992965;8203.466774659628;8206.712765053195;8331.16688331162;4335.544767799462;4794.801299675081;5521.917171242354;5732.57005724564;5742.397048309867;7875.330596899099;9673.467959262027;9774.657886973922;10045.045720656526;10098.29311948186;9955.552000159865;10142;10156.949215253877;10290.331128476626;10388.792224155626;6447.4197322241225;6888.690008949709;6938.098047253825;7105.715771369294;7109.573425594481;6820.93179068203;8279.751607451786;8331.708390206313;8391.035030971272;8391.07698153215;7292.96353518229;7462.10454745434;7536.24637536242;7558.357539608975;7628.961855190689;6933.341332573397;7252.87580425035;7333.559986400833;7380.926190738024;7447.329740323397;6828.658567071528;6889.069975553437;6891.586504809728;6917.723291068301;6941.201761797458;4756.143894099096;4766.070616229802;4781.880452988703;4822.807087716452;4867.975660121677;4823.03539292136;4851.75741212945;4884.316195732671;4946.554810067111;5030.5975521957425 05:38:19 INFO - PROCESS | 1940 | |4;prototype.html;321.17458132599216;323.6278279978025;326.32939309718535;331.3952037531505;332.72383921345283;5532.529734972527;5640.602743833875;5641.323041235048;5683.914741278879;5738.426157384265;5554.750036248369;5559.416261292557;5586.692731899744;5587.189857470665;5602.327720673525;3749.587545370021;3874.8762858770924;4067.4137086536202;4159.500859896817;4249.638780703628;3432.5366075579727;4206.390073439349;4235;4259.233337999159;4286.292761694317;4253.80857861397;4265.765382903939;4268.253055715253;4269.509006464265;4301.204277208711;3406.574178227709;4044.57531959143;4254.723722808314;4284.61438470536;4287.592678695519;3268.509723541464;3658.6707196352454;3708.6662200402093;3726.720495962814;3733.9439908401396;5371.301730774974;5402.459754024605;5430.511253987121;5440.183972404171;5441;4086.6934979876332;4500.257457519538;4856.514348565151;4859.809346710043;4893.779779909877;5515.393306736256;5875.559333049996;5898.469137777578;5900.911486327708;5917.615355001911;3999.680025597945;4002.5397079335958;4011.618896204856;4011.959880401188;4014.2573623879675;5367.973160134214;6207.87584248317;6234.314225435199;6257.873582755103;6279.591826531307;3556.5554305711785;3863.768173909574;4055.0876052887866;4058.5332686740812;4059.4722686050623;3797.1266608680157;3833.3325003622954;3888.958296894153;4101.974506373406;4133;4112.650424713873;4478.7088839226;4522.841700540531;4807.61539076873;4825.348577942003;4922.892349221396;5399.7300134993875;5476.534494568006;5937.715433264398;5939.881202375928;1232.963011109668;1319.8746119118669;1388.9097208681605;1469.6987117640858;1526.4581073718891;917.4495302818256;959.4147569982305;966.3090890013674;982.7248370456284;1001.7245257554085;5350.170723537858;5528.452033430646;5901.527877769769;5924.348321684612;5955.285365756137;5353.491418315254;6515.762005218993;6602.514434252351;6629;6642.5090591028365;5468.043092458804;5549.112142057252;5666.914996275059;5924.5852790305125;5946.583739138218;3031.9393612127633;3473.8610455581997;3500.0574681051753;3508.4035713928693;3517.557801301454;5607.887842243133;6234.501239900797;6622.205335359788;6846.212685541226;6910.8617827644175;5610.5231055360755;6718.260991290954;6740.764073257413;6742.764003259862;6804.8298792529595;6626.569272997143;6722.9663851680425;6848.7260509579055;6860.878300373318;6942.652867356612;5675.517581005661;6477.578957367756;6536.084948107174;6566.310537393505;6594.934050659432;5328.413874473882;5955.523558115254;6449.7420103195345;6479.287278399278;6491.578047426902;5013.418089683742;6240.939040363059;6353.587016844075;6500.804975850732;6830.385265326145;4271.957280427156;4288.941308172032;4309.806058727302;4314.654827613721;4320.330348795816;4945.653804233813;5984.281886173687;6448.581781831813;6526.282108968105;6553;5041.4454410015605;5533.6126471148245;6241.564440178875;6284.151639528605;6300.621962682254;5046.924296135488;5483.561315094863;5836.941630583639;6069.2716873975405;6324.3991820776955;4374.671899607606;6151.2618485781995;6197.907031394442;6221.475738443929;6233.065040244;6076.781974579335;6322.715477803602;6324.873502530016;6348.523860710373;6393.881070812682;5527.309086364205;5879.2944846618675;6228.688565571612;6246.312905580474;6258.906116408349 05:38:19 INFO - PROCESS | 1940 | |5;yui.html;644.2848438233561;647.3332467558416;653.5425202358348;655.9868802623947;673.7574473189654;14456.27718614069;16305.429309974154;16697;16819.57951051224;16892.66214675706;12445.377731113453;15564.922175389136;15633.530994070168;15673.84326156741;15756.84243157571;726.556800351785;783.6826085435408;786.2137862137862;929.8837645294338;933.5612262236738;784.9646765895549;901.2204443156655;923.2337160157088;929.0802105915129;930.9348345615809;867.4751775176022;945.8439357506038;962.7448726087592;990.64336838738;1004.2518323848743;942.4533770413212;972.9805403891918;979.2215188924822;979.3878825733916;980.1129977370491;723.5730918757905;725.2892165677665;728.5483000539699;730.2259604818909;734.2217249715319;15101.395944162221;15170.165640889747;15176.620584485367;15240.847591524054;15289.464868729652;12173.939130304381;12340.197887137307;12369.505219791197;12429.937850310691;12496.187747796455;9734.610615575368;10065.295429319951;10126.240531960133;10128.291019628628;10131.290809643328;3436.9656303436896;3446.9482957755654;3607.260511595117;3621.692156166729;3762.9275656437785;74126.62936685336;74602.7171266436;75472.99999999945;76404;76786;9135.31485138604;9236.8614470783;9275.886893572815;9290;9463.90536094644;20213.292534761214;20316.18735250603;20331.288404905757;20435.4891127723;20483.28308509195;6944.7222111116;7134.286571342928;7333.926660733431;7467.029286192869;7622.466427350143;4027.3153563894207;4070.3690927906223;4102.856400026045;4170.124273902454;4184.518780340239;3440.913977150591;3504.5268888699698;3511.438169892805;3514.5606799150105;3520.1023738946406;576.4552497889454;589.4164776870867;595.9362537869881;600.2017316968421;602.4969150759165;502.70302619242267;555.1561626328039;566.2949627713441;576.2048373244895;577.6668622050744;7415.8146046349275;7868.449208555347;7961;8001.8799718004275;8198;504.553470178887;526.0714838310386;561.3036474854938;583.1660725162973;583.897817881869;3566.6076731559506;3694.704423646102;3721.5162028936606;3751.1559899023046;3755.1550901047594;86996.69504957431;90409;90679.09320906838;93589;104765;79362;80194.39611207743;82054;83046;85903.28193436221;69915.30084699088;70198;70310.64844675847;70703.64648176727;71278.64360678164;7844.568548729646;7900.881486777588;7934.841303174019;7961.840763184588;8132.552709600792;45091.54908450873;45607.77196113998;45679.31481027721;45836.54163458323;45897.31154032626;63688.17870927759;65605.67197164174;66275.33724662692;66340.3414914631;66409.33590664032;8909.331800114887;10545.9472702636;10800.576454803024;10918.672439826818;11082.61210857616;9522.714318570454;11861.644150675493;12036.699082523008;12040.819387709367;12195.695107622381;10649.680509584725;10708.839367409651;10724.195685323475;10945.671629851116;11158.721031974266;11418.972292493716;11537.653870383901;11539.711507212387;11597.246178998337;11955.103367247317;10160.898391015995;10293.794124117327;10532.315399499008;10572.471376431302;10654.57381704723;9248.491332976482;9346.719598411786;9554.426734395958;9773.85339219898;9983.301168918106;8136.349092072502;9237.861432078658;9298.349115562116;9321.394109382869;9346.345755797029 05:38:19 INFO - PROCESS | 1940 | __end_tp_report 05:38:19 INFO - PROCESS | 1940 | __start_cc_report 05:38:19 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,424 05:38:19 INFO - PROCESS | 1940 | __end_cc_report 05:38:19 INFO - PROCESS | 1940 | __startTimestamp1462538299101__endTimestamp 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | ------- Summary: start ------- 05:38:19 INFO - PROCESS | 1940 | Number of tests: 6 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5699.46 Median:5871.62 stddev:661.51 (11.3%) stddev-sans-first:638.90 05:38:19 INFO - PROCESS | 1940 | Values: 3419.4 3475.3 3505.0 3506.4 3531.7 5688.8 5708.1 5710.0 5724.1 5746.9 4353.9 5332.2 5684.2 5691.0 5698.3 5163.1 5220.4 5228.7 5277.7 5298.0 4276.0 5115.5 5238.1 5316.8 5335.2 5244.2 5271.5 5272.5 5274.1 5341.3 5228.4 5234.5 5235.6 5248.1 5285.0 5015.2 5054.5 5145.4 5164.1 5183.8 5173.8 5442.3 5593.7 5638.4 5653.3 5084.4 6068.4 6264.3 6280.3 6286.2 6151.9 6278.2 6319.7 6344.2 6356.9 5840.9 5863.3 5942.0 5944.9 5976.3 6180.1 6265.7 6282.4 6333.8 6363.0 5419.5 6082.4 6155.3 6176.2 6188.3 6200.8 6229.8 6280.2 6291.4 6332.6 6112.5 6170.8 6235.8 6237.1 6265.8 6187.7 6241.2 6272.3 6274.2 6291.1 4886.9 4926.2 4943.3 4949.0 4949.5 4300.2 4356.0 4397.6 4464.4 4484.2 5673.5 5713.1 5722.5 5731.9 5760.8 5111.1 6256.9 6297.1 6335.0 6338.8 5590.7 5703.9 5756.6 5807.5 5839.1 5393.7 5442.7 5452.1 5453.7 5460.1 6380.1 6425.3 6428.1 6434.1 6450.3 5237.4 6172.1 6235.1 6317.0 6329.9 6301.1 6303.3 6304.8 6312.5 6321.1 5374.3 6049.5 6065.4 6072.3 6074.8 5893.3 6279.5 6281.2 6282.2 6310.7 5988.2 6099.2 6152.5 6158.2 6176.5 5921.3 6071.1 6100.6 6101.3 6154.4 5569.7 5870.3 5871.6 5937.2 6008.7 4418.0 5060.2 6164.6 6169.4 6170.1 5830.8 6055.3 6063.8 6067.5 6080.0 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:58688.88 Median:198232.42 stddev:126985.40 (64.1%) stddev-sans-first:127266.39 05:38:19 INFO - PROCESS | 1940 | Values: 467.0 534.8 560.0 565.1 577.0 38741.0 39777.8 39809.8 39869.2 39884.6 34284.3 39943.6 40524.4 41508.8 41537.0 9403.5 9522.3 9660.8 9680.9 9697.2 9249.6 9404.5 9411.2 9432.2 9497.3 9386.4 9394.7 9445.3 9457.5 9478.0 8079.3 9355.5 9427.0 9454.3 9499.1 9648.3 9738.8 9758.8 9760.6 9764.9 39615.8 40064.0 40109.6 40125.8 40205.6 2790.9 3019.5 3142.2 3214.3 3274.9 26358.2 26375.7 26809.3 26925.7 27487.5 2670.8 2700.3 2701.0 2786.9 2823.6 319897.0 324261.0 326678.0 327141.4 327789.0 33062.0 34079.1 34551.3 34581.8 34861.3 34403.3 34790.8 34813.7 34916.7 35153.8 15333.8 15519.0 15541.5 15553.1 15571.1 10748.7 13094.2 13104.6 13134.3 13179.7 8112.7 8179.7 8192.5 8343.6 8367.8 2787.3 3457.9 3467.2 3479.9 3500.1 949.9 971.4 972.2 1139.2 1150.2 25681.7 29267.4 29967.3 30235.4 30854.0 1103.5 1111.0 1137.3 1167.3 1191.8 13551.9 13625.8 13819.8 14873.9 15362.4 360071.2 395993.0 435773.0 436389.0 659152.0 61171.0 61848.4 62610.7 62639.0 62749.7 1311.7 1476.8 1497.4 1502.4 1512.6 28995.9 30368.8 30447.7 30643.0 31081.7 51915.2 52416.7 52672.0 52679.7 52728.0 577631.0 593086.0 594268.0 594797.0 595696.0 23138.3 27343.2 28059.4 28275.2 28321.7 44371.6 45105.3 45368.3 45911.3 46234.1 37638.8 37704.2 37767.6 38215.8 38357.2 28082.9 36998.0 37650.8 37757.2 37828.4 35999.0 36393.6 36424.6 36556.9 36608.0 29379.0 30607.2 32774.3 33179.8 33424.0 20719.9 24894.1 25144.7 25148.5 25313.6 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:37409.70 Median:405.01 stddev:105709.57 (26100.4%) stddev-sans-first:105969.60 05:38:19 INFO - PROCESS | 1940 | Values: 404.8 405.2 405.5 411.2 414.9 11816.4 12385.0 19684.7 20983.4 21037.6 18122.1 21838.7 22610.0 22946.9 23930.0 6717.5 6819.4 7038.2 7086.9 7133.4 6903.8 6904.4 6949.2 6954.3 6967.4 5605.7 6831.6 6901.2 6947.4 6971.5 6725.3 6809.9 6882.0 6895.9 6935.3 6684.5 6735.1 6762.1 6780.5 6795.8 28214.2 28701.7 29886.9 30437.4 30451.1 2275.2 2303.4 2382.7 2439.8 2740.6 13873.6 15371.8 16781.3 17054.4 17257.8 1535.9 1893.4 1921.6 1950.6 1965.3 101153.0 120084.8 137578.0 140240.0 142247.3 22276.2 22395.7 22496.1 22654.9 22802.2 14417.5 36642.9 37084.3 37197.0 37238.7 8926.2 9072.0 9124.2 9162.8 9193.1 9103.8 9107.0 9139.3 9147.3 9183.9 4706.7 4741.3 4759.3 4762.2 4807.3 1769.9 2000.5 2055.9 2060.5 2062.8 853.8 925.2 937.8 983.7 1056.5 11667.7 19911.9 20594.7 20616.7 21297.4 436.0 446.2 455.6 459.7 471.3 5351.8 5928.6 6234.6 6258.0 6315.1 616174.0 625741.0 637319.0 639852.0 643933.0 116654.4 122547.0 142296.3 143616.0 145771.6 395.3 454.8 468.9 469.0 470.3 11246.0 11389.0 11692.7 11745.4 11850.6 52633.7 55500.7 56290.0 56404.2 56678.0 81307.0 82653.6 83299.6 83755.0 84724.3 7990.8 8342.0 8381.3 8467.5 8468.4 10741.7 11270.9 12535.8 12843.3 12950.6 10008.7 10327.0 10899.5 10907.6 11147.8 9633.2 10416.3 10889.6 10948.8 10977.8 11044.6 11086.8 11093.1 11564.5 11618.5 5506.7 8369.5 8385.9 8390.2 8523.3 8395.7 10519.6 11025.3 11202.8 11368.3 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5644.20 Median:4919.47 stddev:3103.91 (63.1%) stddev-sans-first:3097.48 05:38:19 INFO - PROCESS | 1940 | Values: 1564.6 1592.5 1596.5 1930.8 1965.5 7939.4 8524.6 8548.8 8879.9 8924.6 8512.1 8556.6 8557.5 8650.6 8656.7 2434.1 2453.5 2470.1 2477.3 2481.4 2424.2 2476.9 2482.8 2484.4 2498.3 2445.4 2470.3 2503.1 2512.8 2517.2 2460.7 2466.1 2486.6 2501.6 2504.5 2472.7 2474.6 2492.0 2499.2 2503.9 8775.3 8832.6 8841.3 8857.2 8864.3 5267.8 5460.8 5470.4 5495.1 5507.3 2730.5 3484.6 3661.1 3669.0 3705.8 1032.0 1041.4 1048.5 1061.1 1077.1 11392.0 11452.3 11466.9 11484.3 11492.9 4829.1 4843.6 4909.1 4964.9 5042.0 9889.2 9917.8 9976.4 9987.1 9997.1 5365.7 5393.6 5415.6 5486.8 5494.3 4453.6 4830.0 4929.8 4965.6 4987.2 4093.1 4125.6 4140.2 4150.1 4161.4 1159.7 1232.8 1327.8 1346.8 1381.0 910.7 912.8 925.1 928.8 945.5 5705.8 5727.8 5838.8 5865.8 5884.4 915.7 933.3 1018.6 1026.0 1031.7 3091.2 3407.2 3660.9 3905.2 3978.8 8663.4 10388.2 10830.5 10867.5 10910.8 8919.3 9123.6 10658.9 10688.0 10874.8 8092.6 8162.7 8203.5 8206.7 8331.2 4335.5 4794.8 5521.9 5732.6 5742.4 7875.3 9673.5 9774.7 10045.0 10098.3 9955.6 10142.0 10156.9 10290.3 10388.8 6447.4 6888.7 6938.1 7105.7 7109.6 6820.9 8279.8 8331.7 8391.0 8391.1 7293.0 7462.1 7536.2 7558.4 7629.0 6933.3 7252.9 7333.6 7380.9 7447.3 6828.7 6889.1 6891.6 6917.7 6941.2 4756.1 4766.1 4781.9 4822.8 4868.0 4823.0 4851.8 4884.3 4946.6 5030.6 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4936.49 Median:5521.35 stddev:1591.50 (28.8%) stddev-sans-first:1557.81 05:38:19 INFO - PROCESS | 1940 | Values: 321.2 323.6 326.3 331.4 332.7 5532.5 5640.6 5641.3 5683.9 5738.4 5554.8 5559.4 5586.7 5587.2 5602.3 3749.6 3874.9 4067.4 4159.5 4249.6 3432.5 4206.4 4235.0 4259.2 4286.3 4253.8 4265.8 4268.3 4269.5 4301.2 3406.6 4044.6 4254.7 4284.6 4287.6 3268.5 3658.7 3708.7 3726.7 3733.9 5371.3 5402.5 5430.5 5440.2 5441.0 4086.7 4500.3 4856.5 4859.8 4893.8 5515.4 5875.6 5898.5 5900.9 5917.6 3999.7 4002.5 4011.6 4012.0 4014.3 5368.0 6207.9 6234.3 6257.9 6279.6 3556.6 3863.8 4055.1 4058.5 4059.5 3797.1 3833.3 3889.0 4102.0 4133.0 4112.7 4478.7 4522.8 4807.6 4825.3 4922.9 5399.7 5476.5 5937.7 5939.9 1233.0 1319.9 1388.9 1469.7 1526.5 917.4 959.4 966.3 982.7 1001.7 5350.2 5528.5 5901.5 5924.3 5955.3 5353.5 6515.8 6602.5 6629.0 6642.5 5468.0 5549.1 5666.9 5924.6 5946.6 3031.9 3473.9 3500.1 3508.4 3517.6 5607.9 6234.5 6622.2 6846.2 6910.9 5610.5 6718.3 6740.8 6742.8 6804.8 6626.6 6723.0 6848.7 6860.9 6942.7 5675.5 6477.6 6536.1 6566.3 6594.9 5328.4 5955.5 6449.7 6479.3 6491.6 5013.4 6240.9 6353.6 6500.8 6830.4 4272.0 4288.9 4309.8 4314.7 4320.3 4945.7 5984.3 6448.6 6526.3 6553.0 5041.4 5533.6 6241.6 6284.2 6300.6 5046.9 5483.6 5836.9 6069.3 6324.4 4374.7 6151.3 6197.9 6221.5 6233.1 6076.8 6322.7 6324.9 6348.5 6393.9 5527.3 5879.3 6228.7 6246.3 6258.9 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:18039.15 Median:592.68 stddev:25527.31 (4307.1%) stddev-sans-first:25565.50 05:38:19 INFO - PROCESS | 1940 | Values: 644.3 647.3 653.5 656.0 673.8 14456.3 16305.4 16697.0 16819.6 16892.7 12445.4 15564.9 15633.5 15673.8 15756.8 726.6 783.7 786.2 929.9 933.6 785.0 901.2 923.2 929.1 930.9 867.5 945.8 962.7 990.6 1004.3 942.5 973.0 979.2 979.4 980.1 723.6 725.3 728.5 730.2 734.2 15101.4 15170.2 15176.6 15240.8 15289.5 12173.9 12340.2 12369.5 12429.9 12496.2 9734.6 10065.3 10126.2 10128.3 10131.3 3437.0 3446.9 3607.3 3621.7 3762.9 74126.6 74602.7 75473.0 76404.0 76786.0 9135.3 9236.9 9275.9 9290.0 9463.9 20213.3 20316.2 20331.3 20435.5 20483.3 6944.7 7134.3 7333.9 7467.0 7622.5 4027.3 4070.4 4102.9 4170.1 4184.5 3440.9 3504.5 3511.4 3514.6 3520.1 576.5 589.4 595.9 600.2 602.5 502.7 555.2 566.3 576.2 577.7 7415.8 7868.4 7961.0 8001.9 8198.0 504.6 526.1 561.3 583.2 583.9 3566.6 3694.7 3721.5 3751.2 3755.2 86996.7 90409.0 90679.1 93589.0 104765.0 79362.0 80194.4 82054.0 83046.0 85903.3 69915.3 70198.0 70310.6 70703.6 71278.6 7844.6 7900.9 7934.8 7961.8 8132.6 45091.5 45607.8 45679.3 45836.5 45897.3 63688.2 65605.7 66275.3 66340.3 66409.3 8909.3 10545.9 10800.6 10918.7 11082.6 9522.7 11861.6 12036.7 12040.8 12195.7 10649.7 10708.8 10724.2 10945.7 11158.7 11419.0 11537.7 11539.7 11597.2 11955.1 10160.9 10293.8 10532.3 10572.5 10654.6 9248.5 9346.7 9554.4 9773.9 9983.3 8136.3 9237.9 9298.3 9321.4 9346.3 05:38:19 INFO - PROCESS | 1940 | -------- Summary: end -------- 05:38:19 INFO - PROCESS | 1940 | 05:38:19 INFO - TEST-INFO | 1940: exit 0 05:38:19 INFO - TEST-OK | dromaeo_css | took 1111508ms 05:38:19 INFO - TEST-START | kraken 05:38:19 INFO - operating with platform_type : mac_ 05:38:19 INFO - Initialising browser for kraken test... 05:38:19 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpMBvIWQ/profile http://localhost:53793/getInfo.html) 05:38:22 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 05:38:22 INFO - PROCESS | 1947 | colorDepth:24 05:38:22 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 05:38:22 INFO - PROCESS | 1947 | __metrics 05:38:22 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:38:23 INFO - TEST-INFO | 1947: exit 0 05:38:23 INFO - Browser initialized. 05:38:23 INFO - Running cycle 1/1 for kraken test... 05:38:23 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpMBvIWQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:35 INFO - PROCESS | 1950 | RSS: Main: 221573120 05:38:35 INFO - PROCESS | 1950 | 05:38:38 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53793/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:39 INFO - PROCESS | 1950 | RSS: Main: 233435136 05:38:39 INFO - PROCESS | 1950 | 05:38:42 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53793/tests/kraken/driver.html?testName=audio-dft) 05:38:42 INFO - PROCESS | 1950 | RSS: Main: 242229248 05:38:42 INFO - PROCESS | 1950 | 05:38:45 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53793/tests/kraken/driver.html?testName=audio-fft) 05:38:46 INFO - PROCESS | 1950 | RSS: Main: 245862400 05:38:46 INFO - PROCESS | 1950 | 05:38:48 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53793/tests/kraken/driver.html?testName=audio-oscillator) 05:38:49 INFO - PROCESS | 1950 | RSS: Main: 246140928 05:38:49 INFO - PROCESS | 1950 | 05:38:51 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53793/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:51 INFO - PROCESS | 1950 | RSS: Main: 246894592 05:38:51 INFO - PROCESS | 1950 | 05:38:54 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53793/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:55 INFO - PROCESS | 1950 | RSS: Main: 247275520 05:38:55 INFO - PROCESS | 1950 | 05:38:58 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53793/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:59 INFO - PROCESS | 1950 | RSS: Main: 247291904 05:38:59 INFO - PROCESS | 1950 | 05:39:01 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53793/tests/kraken/driver.html?testName=json-parse-financial) 05:39:02 INFO - PROCESS | 1950 | RSS: Main: 248672256 05:39:02 INFO - PROCESS | 1950 | 05:39:04 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53793/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:04 INFO - PROCESS | 1950 | RSS: Main: 247967744 05:39:04 INFO - PROCESS | 1950 | 05:39:06 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:07 INFO - PROCESS | 1950 | RSS: Main: 247844864 05:39:07 INFO - PROCESS | 1950 | 05:39:09 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:10 INFO - PROCESS | 1950 | RSS: Main: 248238080 05:39:10 INFO - PROCESS | 1950 | 05:39:13 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:13 INFO - PROCESS | 1950 | RSS: Main: 248233984 05:39:13 INFO - PROCESS | 1950 | 05:39:16 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:17 INFO - PROCESS | 1950 | RSS: Main: 248172544 05:39:17 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:53793/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53793/tests/kraken/driver.html?testName=ai-astar) 05:39:19 INFO - PROCESS | 1950 | RSS: Main: 250142720 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | __start_tp_report 05:39:19 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 05:39:19 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 05:39:19 INFO - PROCESS | 1950 | |i|pagename|runs| 05:39:19 INFO - PROCESS | 1950 | |0;ai-astar;121;121;101;166;169;171;116;116;116;126 05:39:19 INFO - PROCESS | 1950 | |1;audio-beat-detection;155;123;155;153;123;146;143;120;173;120 05:39:19 INFO - PROCESS | 1950 | |2;audio-dft;194;195;199;203;196;196;198;197;201;195 05:39:19 INFO - PROCESS | 1950 | |3;audio-fft;68;74;82;139;57;55;56;64;123;55 05:39:19 INFO - PROCESS | 1950 | |4;audio-oscillator;80;83;128;76;62;99;160;119;117;106 05:39:19 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;90;87;88;87;90;86;118;106;90;88 05:39:19 INFO - PROCESS | 1950 | |6;imaging-darkroom;95;95;96;95;95;96;109;151;153;109 05:39:19 INFO - PROCESS | 1950 | |7;imaging-desaturate;94;93;74;143;148;90;91;92;89;91 05:39:19 INFO - PROCESS | 1950 | |8;json-parse-financial;60;66;64;66;66;65;67;82;64;71 05:39:19 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;64;66;69;66;106;68;61;81;103;105 05:39:19 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;81;83;81;82;81;83;80;84;83;86 05:39:19 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;149;163;156;136;138;159;151;143;241;168 05:39:19 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;175;170;168;169;171;170;175;168;184;226 05:39:19 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;69;66;75;71;96;79;72;69;69;71 05:39:19 INFO - PROCESS | 1950 | __end_tp_report 05:39:19 INFO - PROCESS | 1950 | __start_cc_report 05:39:19 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,1095 05:39:19 INFO - PROCESS | 1950 | __end_cc_report 05:39:19 INFO - PROCESS | 1950 | __startTimestamp1462538359460__endTimestamp 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | ------- Summary: start ------- 05:39:19 INFO - PROCESS | 1950 | Number of tests: 14 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:132.30 Median:123.50 stddev:25.93 (21.0%) stddev-sans-first:27.18 05:39:19 INFO - PROCESS | 1950 | Values: 121.0 121.0 101.0 166.0 169.0 171.0 116.0 116.0 116.0 126.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:149.50 stddev:18.62 (12.5%) stddev-sans-first:19.05 05:39:19 INFO - PROCESS | 1950 | Values: 155.0 123.0 155.0 153.0 123.0 146.0 143.0 120.0 173.0 120.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:197.40 Median:197.50 stddev:2.88 (1.5%) stddev-sans-first:2.77 05:39:19 INFO - PROCESS | 1950 | Values: 194.0 195.0 199.0 203.0 196.0 196.0 198.0 197.0 201.0 195.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:77.30 Median:60.50 stddev:29.91 (49.4%) stddev-sans-first:31.54 05:39:19 INFO - PROCESS | 1950 | Values: 68.0 74.0 82.0 139.0 57.0 55.0 56.0 64.0 123.0 55.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:69.00 stddev:29.23 (42.4%) stddev-sans-first:29.80 05:39:19 INFO - PROCESS | 1950 | Values: 80.0 83.0 128.0 76.0 62.0 99.0 160.0 119.0 117.0 106.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:88.00 stddev:10.50 (11.9%) stddev-sans-first:11.08 05:39:19 INFO - PROCESS | 1950 | Values: 90.0 87.0 88.0 87.0 90.0 86.0 118.0 106.0 90.0 88.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:95.00 stddev:23.14 (24.4%) stddev-sans-first:23.95 05:39:19 INFO - PROCESS | 1950 | Values: 95.0 95.0 96.0 95.0 95.0 96.0 109.0 151.0 153.0 109.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:91.00 stddev:24.40 (26.8%) stddev-sans-first:25.77 05:39:19 INFO - PROCESS | 1950 | Values: 94.0 93.0 74.0 143.0 148.0 90.0 91.0 92.0 89.0 91.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:66.00 stddev:5.92 (9.0%) stddev-sans-first:5.69 05:39:19 INFO - PROCESS | 1950 | Values: 60.0 66.0 64.0 66.0 66.0 65.0 67.0 82.0 64.0 71.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:66.00 stddev:18.54 (28.1%) stddev-sans-first:18.86 05:39:19 INFO - PROCESS | 1950 | Values: 64.0 66.0 69.0 66.0 106.0 68.0 61.0 81.0 103.0 105.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:83.00 stddev:1.78 (2.1%) stddev-sans-first:1.81 05:39:19 INFO - PROCESS | 1950 | Values: 81.0 83.0 81.0 82.0 81.0 83.0 80.0 84.0 83.0 86.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:160.40 Median:157.50 stddev:30.19 (19.2%) stddev-sans-first:31.73 05:39:19 INFO - PROCESS | 1950 | Values: 149.0 163.0 156.0 136.0 138.0 159.0 151.0 143.0 241.0 168.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:173.00 stddev:17.68 (10.2%) stddev-sans-first:18.73 05:39:19 INFO - PROCESS | 1950 | Values: 175.0 170.0 168.0 169.0 171.0 170.0 175.0 168.0 184.0 226.0 05:39:19 INFO - PROCESS | 1950 | 05:39:19 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:71.50 stddev:8.63 (12.1%) stddev-sans-first:8.98 05:39:19 INFO - PROCESS | 1950 | Values: 69.0 66.0 75.0 71.0 96.0 79.0 72.0 69.0 69.0 71.0 05:39:19 INFO - PROCESS | 1950 | -------- Summary: end -------- 05:39:19 INFO - PROCESS | 1950 | 05:39:20 INFO - TEST-INFO | 1950: exit 0 05:39:20 INFO - TEST-OK | kraken | took 60741ms 05:39:20 INFO - SUITE-END | took 1172s 05:39:20 INFO - Completed test suite (00:19:32) 05:39:20 INFO - javascript benchmark 05:39:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3419.4186988212, 3475.2875660489594, 3505.0, 3506.438969764838, 3531.717462602992, 5688.829335119943, 5708.08670612702, 5710.0, 5724.08414653656, 5746.942530574693, 4353.86938391848, 5332.200169974506, 5684.175794509793, 5690.975624387608, 5698.344690360599, 5163.122269214233, 5220.425753167148, 5228.686278823264, 5277.683338999673, 5297.973510132444, 4275.957240427602, 5115.462876397981, 5238.1357076082595, 5316.787328506855, 5335.1997200420055, 5244.239585260135, 5271.525562699357, 5272.499112584298, 5274.077036518594, 5341.30563026806, 5228.372595288552, 5234.450382709837, 5235.58115350771, 5248.134057880464, 5285.0, 5015.1975683890405, 5054.469280725544, 5145.382554093533, 5164.070467315882, 5183.844484665466, 5173.758298008488, 5442.292501974757, 5593.720313984325, 5638.4361563843695, 5653.349864765563, 5084.389873215201, 6068.362821903681, 6264.342244064399, 6280.309165991737, 6286.214223222098, 6151.938480615226, 6278.215223097113, 6319.684015799192, 6344.238691357066, 6356.936430635681, 5840.912386314209, 5863.299643103494, 5941.970290148522, 5944.910826337608, 5976.252968378996, 6180.134781130645, 6265.655388953652, 6282.434580887742, 6333.809985700436, 6362.968185159045, 5419.485148910847, 6082.422169893853, 6155.261368635793, 6176.166217560572, 6188.257409110935, 6200.782972595937, 6229.762984069446, 6280.183576135073, 6291.370862913764, 6332.588381755171, 6112.473085128069, 6170.81487555374, 6235.75056997715, 6237.095621135, 6265.8120256392385, 6187.659678717624, 6241.18864547615, 6272.341404152498, 6274.247090349188, 6291.1192433059405, 4886.853394398174, 4926.187179115477, 4943.2585112233455, 4949.034938187019, 4949.48030456804, 4300.182965236595, 4356.019895523482, 4397.582229688174, 4464.397306363664, 4484.192845287815, 5673.5461163107675, 5713.114467257582, 5722.513586345124, 5731.914021289684, 5760.8271751847515, 5111.080005599028, 6256.906146407808, 6297.086922396227, 6335.0, 6338.778142764981, 5590.748416321276, 5703.914441283384, 5756.597038207286, 5807.535397168216, 5839.124131380242, 5393.703346315911, 5442.700651464128, 5452.100403433468, 5453.727313634382, 5460.126379779216, 6380.138681277968, 6425.293217746138, 6428.132202152743, 6434.0670602763275, 6450.290468048511, 5237.371515418175, 6172.135900973957, 6235.127082208403, 6317.0, 6329.873402531832, 6301.149344838388, 6303.306636269915, 6304.84237894038, 6312.5265605078885, 6321.051842223703, 5374.3013408256675, 6049.455549000611, 6065.3934606539, 6072.30168530607, 6074.787382441594, 5893.292804863272, 6279.497640188682, 6281.246250449793, 6282.151909492343, 6310.684465776784, 5988.191594134911, 6099.237595300588, 6152.50779937595, 6158.168647232566, 6176.474999625082, 5921.289445266596, 6071.119687645179, 6100.572959892765, 6101.328853826164, 6154.41533054377, 5569.746806968562, 5870.26621672291, 5871.6183448077445, 5937.19847820556, 6008.7296071676, 4417.955820441754, 5060.240963855304, 6164.568480206342, 6169.413905678954, 6170.074488826712, 5830.76676932918, 6055.333913269449, 6063.757449701962, 6067.484263837623, 6080.0], "value": 5660.959433748723, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.97665116744156, 534.7540131539491, 560.0311461172172, 565.146628590828, 576.9509591684706, 38741.0, 39777.80111099448, 39809.80095099524, 39869.202615947666, 39884.60115398845, 34284.314313713774, 39943.60197393092, 40524.39213411801, 41508.79245603768, 41537.0, 9403.529823508832, 9522.285828562852, 9660.758481037996, 9680.854787178163, 9697.224222062217, 9249.583768730423, 9404.529773511296, 9411.152996230338, 9432.24542036639, 9497.335186536946, 9386.389884657476, 9394.718158455224, 9445.338826282128, 9457.527123643791, 9477.95261023694, 8079.272865442138, 9355.532223388804, 9427.0, 9454.29092818038, 9499.097585729345, 9648.276379271512, 9738.756531086708, 9758.804823903552, 9760.609575616967, 9764.853527197098, 39615.801920990496, 40064.0, 40109.598904010876, 40125.799371002955, 40205.597944020476, 2790.916272511828, 3019.4715924713087, 3142.245860993368, 3214.276787722767, 3274.9345013099605, 26358.23238559943, 26375.73624263771, 26809.329766755793, 26925.730742692518, 27487.45025099487, 2670.8186118929216, 2700.3249187703072, 2700.9594856077174, 2786.9303267418477, 2823.5623478360885, 319897.0, 324261.0, 326678.0, 327141.36429317703, 327789.0, 33062.008139755846, 34079.125648089095, 34551.308973820385, 34581.82709086438, 34861.30277394438, 34403.2798360086, 34790.78232261859, 34813.65186348155, 34916.65083349134, 35153.82423087868, 15333.769993450107, 15519.0, 15541.533753987398, 15553.066815991077, 15571.065736055873, 10748.677539673823, 13094.214347139203, 13104.606861794162, 13134.277614731282, 13179.736405272033, 8112.716054938049, 8179.672813087529, 8192.54940978252, 8343.582820858932, 8367.790805229917, 2787.28924124347, 3457.928042306893, 3467.1678797088516, 3479.913002174966, 3500.1424650960676, 949.8812648418948, 971.4074414607084, 972.2465089555651, 1139.1684070628487, 1150.2408410449063, 25681.74318256831, 29267.39029353406, 29967.25081872927, 30235.395292094036, 30854.0, 1103.4868786014542, 1111.0, 1137.3460260350332, 1167.2529581068122, 1191.8152686333633, 13551.864481355258, 13625.84180344662, 13819.825314848149, 14873.851261487463, 15362.385504579916, 360071.1996440001, 395993.0, 435773.0, 436389.0, 659152.0, 61171.0, 61848.381516184265, 62610.68694656497, 62639.0, 62749.68625156845, 1311.7179806341683, 1476.793248945127, 1497.4384605772834, 1502.4065494129757, 1512.5840393891547, 28995.85502072476, 30368.84815575908, 30447.695523044487, 30643.0, 31081.68918310788, 51915.2212716802, 52416.73791631017, 52672.0, 52679.73660131675, 52728.0, 577631.0, 593086.0, 594268.0, 594797.0, 595696.0, 23138.3058508245, 27343.179704608894, 28059.43881122407, 28275.151745446845, 28321.716782831907, 44371.556284436745, 45105.32342014938, 45368.31947520724, 45911.3113303294, 46234.07531849411, 37638.81180594189, 37704.245915080995, 37767.62232377641, 38215.80892095522, 38357.232855343296, 28082.85958570194, 36998.0, 37650.81174594109, 37757.244855103294, 37828.43257351087, 35999.0, 36393.63606363902, 36424.63575364213, 36556.903292901254, 36608.0, 29379.0, 30607.2348191297, 32774.34451311008, 33179.83870564518, 33424.0, 20719.860407677723, 24894.12870549522, 25144.74855251497, 25148.5413905563, 25313.62029569521], "value": 17829.697998271065, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.8239016028028, 405.19973053219894, 405.46073721949807, 411.2392074661874, 414.8672424824056, 11816.350100744456, 12385.0, 19684.704729429053, 20983.37049888504, 21037.579248415022, 18122.093895305246, 21838.672419913673, 22610.0, 22946.885265573692, 23930.0, 6717.462602991774, 6819.352161544658, 7038.225795162528, 7086.858262834766, 7133.429325653961, 6903.827404314882, 6904.378605925465, 6949.166100067999, 6954.269801670827, 6967.442604591646, 5605.7197140143035, 6831.590104593715, 6901.206361268468, 6947.374736273734, 6971.511994160411, 6725.2602213756445, 6809.931900680979, 6881.9655901720425, 6895.862082758342, 6935.271796461375, 6684.465242780566, 6735.124433823572, 6762.087118239073, 6780.457563394966, 6795.762148324785, 28214.153575392567, 28701.712982870115, 29886.85056574725, 30437.39125217483, 30451.086467406014, 2275.24916777463, 2303.424143964009, 2382.6783384243076, 2439.829211955164, 2740.6026126211787, 13873.58379248624, 15371.769423458658, 16781.32874685011, 17054.403095891583, 17257.791954563185, 1535.9385624575004, 1893.4035778729794, 1921.6060707554918, 1950.6293804177303, 1965.341610560464, 101152.98847011509, 120084.7991520091, 137578.0, 140240.0, 142247.28876355552, 22276.220332288292, 22395.664065038713, 22496.100155993903, 22654.886725566266, 22802.20192293295, 14417.495387661382, 36642.900712978655, 37084.25831483409, 37197.0, 37238.696645617274, 8926.196642302224, 9071.954640226757, 9124.17882390588, 9162.81674366509, 9193.080691930754, 9103.772405689911, 9107.0, 9139.314551408672, 9147.2682185425, 9183.943846457609, 4706.670533062653, 4741.336212930192, 4759.333693282943, 4762.214234651244, 4807.302941073524, 1769.8849574777598, 2000.459875833517, 2055.8560900736807, 2060.546679730487, 2062.7834077421953, 853.833502467013, 925.227435091706, 937.779621788879, 983.7098056073476, 1056.5192837259028, 11667.708307292216, 19911.900440497706, 20594.691079633816, 20616.69074963877, 21297.36107916765, 435.95640435956784, 446.17012357015926, 455.5785898044362, 459.7057882954912, 471.3142377840234, 5351.759170837322, 5928.614640048383, 6234.625922444668, 6257.9687101564205, 6315.147455093595, 616174.0, 625741.0, 637319.0, 639852.0, 643933.0, 116654.41672791581, 122547.0, 142296.28851855674, 143616.0, 145771.62685119463, 395.29834543684615, 454.8067071494602, 468.9320048592935, 468.9788959496899, 470.27107982627473, 11246.044086252434, 11388.977699624278, 11692.70768230801, 11745.412729363668, 11850.644480665595, 52633.7368313156, 55500.72249638726, 56290.0, 56404.153937691786, 56678.0, 81307.0, 82653.58673206596, 83299.5835020821, 83755.0, 84724.30551388815, 7990.760277191694, 8342.040665323322, 8381.253091261593, 8467.53428561435, 8468.407211495136, 10741.731456713645, 11270.94364528172, 12535.811962820748, 12843.34229658393, 12950.611481655566, 10008.749781255527, 10327.0, 10899.455027248447, 10907.618233362111, 11147.777044459228, 9633.22934165251, 10416.270861039957, 10889.618863340062, 10948.835767463654, 10977.780444391226, 11044.613438529614, 11086.778264434828, 11093.057090147106, 11564.479598417924, 11618.477168527268, 5506.697131657797, 8369.49783013021, 8385.874211886705, 8390.160983901562, 8523.275521580736, 8395.748127556182, 10519.631812886511, 11025.283356581796, 11202.775944481227, 11368.261063030877], "value": 9736.363535542574, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.6244901223706, 1592.482443205958, 1596.5210436868945, 1930.787613362529, 1965.4594986378736, 7939.444238903279, 8524.573771311425, 8548.829023419536, 8879.934407871056, 8924.643014279429, 8512.106228845974, 8556.614952327145, 8557.529335886524, 8650.610722517486, 8656.697015604455, 2434.099383228204, 2453.472503411775, 2470.06137667686, 2477.331120597438, 2481.3548477395916, 2424.187897054489, 2476.8885400157037, 2482.795844015651, 2484.354067942339, 2498.3379404457833, 2445.4131008557906, 2470.308313672175, 2503.086373473678, 2512.773850353468, 2517.2070797698666, 2460.6678098456746, 2466.087547607379, 2486.614574740918, 2501.5997440409446, 2504.461540768725, 2472.6909136357954, 2474.6411770293385, 2492.028109037477, 2499.175272160201, 2503.949921001588, 8775.25410340122, 8832.646694132227, 8841.292696584258, 8857.247133993618, 8864.290856731446, 5267.841964741064, 5460.781568737246, 5470.425605311425, 5495.065838807413, 5507.256520369739, 2730.5221586222333, 3484.61669216386, 3661.139632186434, 3668.981655091734, 3705.7776533408082, 1031.9638812641597, 1041.3959903256093, 1048.4810019040624, 1061.0821639282042, 1077.0629552289479, 11392.0, 11452.255603385753, 11466.94266528662, 11484.253523520945, 11492.885071149265, 4829.082474329866, 4843.588294994895, 4909.116359055404, 4964.8758781030765, 5042.016806722654, 9889.208863291064, 9917.752056198653, 9976.351537150062, 9987.101160895554, 9997.050280223368, 5365.704886231217, 5393.6224464287125, 5415.566754659618, 5486.807961721401, 5494.313210848644, 4453.643708503312, 4830.0, 4929.827456039022, 4965.627577931669, 4987.152184128707, 4093.099518106012, 4125.580190840472, 4140.192662430797, 4150.107726838744, 4161.375793630919, 1159.675290918544, 1232.8027515597462, 1327.7543654423769, 1346.8282594143159, 1381.0263764046324, 910.6676063236871, 912.771807048238, 925.0703043441298, 928.79102202004, 945.4847108325841, 5705.771769129188, 5727.821487044002, 5838.79564215259, 5865.824025279248, 5884.382139875252, 915.6631318275392, 933.3000249812641, 1018.5976539267097, 1026.0201507560262, 1031.6853359725205, 3091.2271932016997, 3407.216340241758, 3660.908477288089, 3905.1603905160528, 3978.8209530570616, 8663.436876603, 10388.23400021998, 10830.458477076272, 10867.510962006729, 10910.836337454944, 8919.331050171264, 9123.589438475285, 10658.893411065945, 10688.038076573146, 10874.836877446845, 8092.635831387602, 8162.7143049992965, 8203.466774659628, 8206.712765053195, 8331.16688331162, 4335.544767799462, 4794.801299675081, 5521.917171242354, 5732.57005724564, 5742.397048309867, 7875.330596899099, 9673.467959262027, 9774.657886973922, 10045.045720656526, 10098.29311948186, 9955.552000159865, 10142.0, 10156.949215253877, 10290.331128476626, 10388.792224155626, 6447.4197322241225, 6888.690008949709, 6938.098047253825, 7105.715771369294, 7109.573425594481, 6820.93179068203, 8279.751607451786, 8331.708390206313, 8391.035030971272, 8391.07698153215, 7292.96353518229, 7462.10454745434, 7536.24637536242, 7558.357539608975, 7628.961855190689, 6933.341332573397, 7252.87580425035, 7333.559986400833, 7380.926190738024, 7447.329740323397, 6828.658567071528, 6889.069975553437, 6891.586504809728, 6917.723291068301, 6941.201761797458, 4756.143894099096, 4766.070616229802, 4781.880452988703, 4822.807087716452, 4867.975660121677, 4823.03539292136, 4851.75741212945, 4884.316195732671, 4946.554810067111, 5030.5975521957425], "value": 4588.276581462476, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.17458132599216, 323.6278279978025, 326.32939309718535, 331.3952037531505, 332.72383921345283, 5532.529734972527, 5640.602743833875, 5641.323041235048, 5683.914741278879, 5738.426157384265, 5554.750036248369, 5559.416261292557, 5586.692731899744, 5587.189857470665, 5602.327720673525, 3749.587545370021, 3874.8762858770924, 4067.4137086536202, 4159.500859896817, 4249.638780703628, 3432.5366075579727, 4206.390073439349, 4235.0, 4259.233337999159, 4286.292761694317, 4253.80857861397, 4265.765382903939, 4268.253055715253, 4269.509006464265, 4301.204277208711, 3406.574178227709, 4044.57531959143, 4254.723722808314, 4284.61438470536, 4287.592678695519, 3268.509723541464, 3658.6707196352454, 3708.6662200402093, 3726.720495962814, 3733.9439908401396, 5371.301730774974, 5402.459754024605, 5430.511253987121, 5440.183972404171, 5441.0, 4086.6934979876332, 4500.257457519538, 4856.514348565151, 4859.809346710043, 4893.779779909877, 5515.393306736256, 5875.559333049996, 5898.469137777578, 5900.911486327708, 5917.615355001911, 3999.680025597945, 4002.5397079335958, 4011.618896204856, 4011.959880401188, 4014.2573623879675, 5367.973160134214, 6207.87584248317, 6234.314225435199, 6257.873582755103, 6279.591826531307, 3556.5554305711785, 3863.768173909574, 4055.0876052887866, 4058.5332686740812, 4059.4722686050623, 3797.1266608680157, 3833.3325003622954, 3888.958296894153, 4101.974506373406, 4133.0, 4112.650424713873, 4478.7088839226, 4522.841700540531, 4807.61539076873, 4825.348577942003, 4922.892349221396, 5399.7300134993875, 5476.534494568006, 5937.715433264398, 5939.881202375928, 1232.963011109668, 1319.8746119118669, 1388.9097208681605, 1469.6987117640858, 1526.4581073718891, 917.4495302818256, 959.4147569982305, 966.3090890013674, 982.7248370456284, 1001.7245257554085, 5350.170723537858, 5528.452033430646, 5901.527877769769, 5924.348321684612, 5955.285365756137, 5353.491418315254, 6515.762005218993, 6602.514434252351, 6629.0, 6642.5090591028365, 5468.043092458804, 5549.112142057252, 5666.914996275059, 5924.5852790305125, 5946.583739138218, 3031.9393612127633, 3473.8610455581997, 3500.0574681051753, 3508.4035713928693, 3517.557801301454, 5607.887842243133, 6234.501239900797, 6622.205335359788, 6846.212685541226, 6910.8617827644175, 5610.5231055360755, 6718.260991290954, 6740.764073257413, 6742.764003259862, 6804.8298792529595, 6626.569272997143, 6722.9663851680425, 6848.7260509579055, 6860.878300373318, 6942.652867356612, 5675.517581005661, 6477.578957367756, 6536.084948107174, 6566.310537393505, 6594.934050659432, 5328.413874473882, 5955.523558115254, 6449.7420103195345, 6479.287278399278, 6491.578047426902, 5013.418089683742, 6240.939040363059, 6353.587016844075, 6500.804975850732, 6830.385265326145, 4271.957280427156, 4288.941308172032, 4309.806058727302, 4314.654827613721, 4320.330348795816, 4945.653804233813, 5984.281886173687, 6448.581781831813, 6526.282108968105, 6553.0, 5041.4454410015605, 5533.6126471148245, 6241.564440178875, 6284.151639528605, 6300.621962682254, 5046.924296135488, 5483.561315094863, 5836.941630583639, 6069.2716873975405, 6324.3991820776955, 4374.671899607606, 6151.2618485781995, 6197.907031394442, 6221.475738443929, 6233.065040244, 6076.781974579335, 6322.715477803602, 6324.873502530016, 6348.523860710373, 6393.881070812682, 5527.309086364205, 5879.2944846618675, 6228.688565571612, 6246.312905580474, 6258.906116408349], "value": 4432.407349067087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.2848438233561, 647.3332467558416, 653.5425202358348, 655.9868802623947, 673.7574473189654, 14456.27718614069, 16305.429309974154, 16697.0, 16819.57951051224, 16892.66214675706, 12445.377731113453, 15564.922175389136, 15633.530994070168, 15673.84326156741, 15756.84243157571, 726.556800351785, 783.6826085435408, 786.2137862137862, 929.8837645294338, 933.5612262236738, 784.9646765895549, 901.2204443156655, 923.2337160157088, 929.0802105915129, 930.9348345615809, 867.4751775176022, 945.8439357506038, 962.7448726087592, 990.64336838738, 1004.2518323848743, 942.4533770413212, 972.9805403891918, 979.2215188924822, 979.3878825733916, 980.1129977370491, 723.5730918757905, 725.2892165677665, 728.5483000539699, 730.2259604818909, 734.2217249715319, 15101.395944162221, 15170.165640889747, 15176.620584485367, 15240.847591524054, 15289.464868729652, 12173.939130304381, 12340.197887137307, 12369.505219791197, 12429.937850310691, 12496.187747796455, 9734.610615575368, 10065.295429319951, 10126.240531960133, 10128.291019628628, 10131.290809643328, 3436.9656303436896, 3446.9482957755654, 3607.260511595117, 3621.692156166729, 3762.9275656437785, 74126.62936685336, 74602.7171266436, 75472.99999999945, 76404.0, 76786.0, 9135.31485138604, 9236.8614470783, 9275.886893572815, 9290.0, 9463.90536094644, 20213.292534761214, 20316.18735250603, 20331.288404905757, 20435.4891127723, 20483.28308509195, 6944.7222111116, 7134.286571342928, 7333.926660733431, 7467.029286192869, 7622.466427350143, 4027.3153563894207, 4070.3690927906223, 4102.856400026045, 4170.124273902454, 4184.518780340239, 3440.913977150591, 3504.5268888699698, 3511.438169892805, 3514.5606799150105, 3520.1023738946406, 576.4552497889454, 589.4164776870867, 595.9362537869881, 600.2017316968421, 602.4969150759165, 502.70302619242267, 555.1561626328039, 566.2949627713441, 576.2048373244895, 577.6668622050744, 7415.8146046349275, 7868.449208555347, 7961.0, 8001.8799718004275, 8198.0, 504.553470178887, 526.0714838310386, 561.3036474854938, 583.1660725162973, 583.897817881869, 3566.6076731559506, 3694.704423646102, 3721.5162028936606, 3751.1559899023046, 3755.1550901047594, 86996.69504957431, 90409.0, 90679.09320906838, 93589.0, 104765.0, 79362.0, 80194.39611207743, 82054.0, 83046.0, 85903.28193436221, 69915.30084699088, 70198.0, 70310.64844675847, 70703.64648176727, 71278.64360678164, 7844.568548729646, 7900.881486777588, 7934.841303174019, 7961.840763184588, 8132.552709600792, 45091.54908450873, 45607.77196113998, 45679.31481027721, 45836.54163458323, 45897.31154032626, 63688.17870927759, 65605.67197164174, 66275.33724662692, 66340.3414914631, 66409.33590664032, 8909.331800114887, 10545.9472702636, 10800.576454803024, 10918.672439826818, 11082.61210857616, 9522.714318570454, 11861.644150675493, 12036.699082523008, 12040.819387709367, 12195.695107622381, 10649.680509584725, 10708.839367409651, 10724.195685323475, 10945.671629851116, 11158.721031974266, 11418.972292493716, 11537.653870383901, 11539.711507212387, 11597.246178998337, 11955.103367247317, 10160.898391015995, 10293.794124117327, 10532.315399499008, 10572.471376431302, 10654.57381704723, 9248.491332976482, 9346.719598411786, 9554.426734395958, 9773.85339219898, 9983.301168918106, 8136.349092072502, 9237.861432078658, 9298.349115562116, 9321.394109382869, 9346.345755797029], "value": 6762.789860787265, "unit": "score"}], "name": "dromaeo_css", "value": 7163.866061972372, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 101.0, 166.0, 169.0, 171.0, 116.0, 116.0, 116.0, 126.0], "value": 132.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 155.0, 153.0, 123.0, 146.0, 143.0, 120.0, 173.0, 120.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 195.0, 199.0, 203.0, 196.0, 196.0, 198.0, 197.0, 201.0, 195.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 82.0, 139.0, 57.0, 55.0, 56.0, 64.0, 123.0, 55.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 128.0, 76.0, 62.0, 99.0, 160.0, 119.0, 117.0, 106.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 87.0, 90.0, 86.0, 118.0, 106.0, 90.0, 88.0], "value": 93.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 109.0, 151.0, 153.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 74.0, 143.0, 148.0, 90.0, 91.0, 92.0, 89.0, 91.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 66.0, 64.0, 66.0, 66.0, 65.0, 67.0, 82.0, 64.0, 71.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 69.0, 66.0, 106.0, 68.0, 61.0, 81.0, 103.0, 105.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 81.0, 82.0, 81.0, 83.0, 80.0, 84.0, 83.0, 86.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 163.0, 156.0, 136.0, 138.0, 159.0, 151.0, 143.0, 241.0, 168.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 170.0, 168.0, 169.0, 171.0, 170.0, 175.0, 168.0, 184.0, 226.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 75.0, 71.0, 96.0, 79.0, 72.0, 69.0, 69.0, 71.0], "value": 73.7, "unit": "score"}], "name": "kraken", "value": 1594.1000000000001, "alertThreshold": 2.0}]} 05:39:20 INFO - Return code: 0 05:39:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:39:20 ERROR - # TBPL SUCCESS # 05:39:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:20 INFO - 2016-05-06 05:39:20.780 screenresolution[1955:17188] starting screenresolution argv=screenresolution get 05:39:20 INFO - 2016-05-06 05:39:20.783 screenresolution[1955:17188] Display 0: 1600x1200x32@60 05:39:20 INFO - 2016-05-06 05:39:20.790 screenresolution[1956:17191] starting screenresolution argv=screenresolution list 05:39:20 INFO - Available Modes on Display 0 05:39:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:21 INFO - Intel Iris: 05:39:21 INFO - Chipset Model: Intel Iris 05:39:21 INFO - Type: GPU 05:39:21 INFO - Bus: Built-In 05:39:21 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:21 INFO - Vendor: Intel (0x8086) 05:39:21 INFO - Device ID: 0x0a2e 05:39:21 INFO - Revision ID: 0x0009 05:39:21 INFO - Displays: 05:39:21 INFO - Display: 05:39:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:21 INFO - Display Serial Number: 1600x1200 60 05:39:21 INFO - Main Display: Yes 05:39:21 INFO - Mirror: Off 05:39:21 INFO - Online: Yes 05:39:21 INFO - Rotation: Supported 05:39:21 INFO - Return code: 0 05:39:21 INFO - Running post-action listener: _resource_record_post_action 05:39:21 INFO - Running post-run listener: _resource_record_post_run 05:39:22 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 544768; Write bytes: 654443520; Read time: 10; Write time: 2470 05:39:22 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345031168; Write bytes: 346883584; Read time: 26572; Write time: 714 05:39:22 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 544768; Write bytes: 297606656; Read time: 10; Write time: 1727 05:39:22 INFO - Running post-run listener: _upload_blobber_files 05:39:22 INFO - Blob upload gear active. 05:39:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:22 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 05:39:22 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'] 05:39:22 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 05:39:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:23 INFO - (blobuploader) - INFO - Done attempting. 05:39:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:39:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:24 INFO - (blobuploader) - INFO - Done attempting. 05:39:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:24 INFO - Return code: 0 05:39:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bd4dfd6df36e90171b2fe2ae70a87d19b8b63e7909fac693949565633f0153b920b5b3565eeb6e1934fc49531a62173978bd0173c9314eb8e98ed76768eea24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1630e3d092174ffcb1e4d603cfff74752d47d7edf019d544e4a193f4f965a71e99102fc6c4c070fabdbc3136b7a75602a3277646a4e8de57d3e3e5d666d7739d"} 05:39:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:24 INFO - Contents: 05:39:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bd4dfd6df36e90171b2fe2ae70a87d19b8b63e7909fac693949565633f0153b920b5b3565eeb6e1934fc49531a62173978bd0173c9314eb8e98ed76768eea24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1630e3d092174ffcb1e4d603cfff74752d47d7edf019d544e4a193f4f965a71e99102fc6c4c070fabdbc3136b7a75602a3277646a4e8de57d3e3e5d666d7739d"} 05:39:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:24 INFO - Copying logs to upload dir... 05:39:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.450614 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-06 05:39:24.282485) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:24.285716) ========= 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.tfvaqUsKos/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.N6n6FLSMtN/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bd4dfd6df36e90171b2fe2ae70a87d19b8b63e7909fac693949565633f0153b920b5b3565eeb6e1934fc49531a62173978bd0173c9314eb8e98ed76768eea24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1630e3d092174ffcb1e4d603cfff74752d47d7edf019d544e4a193f4f965a71e99102fc6c4c070fabdbc3136b7a75602a3277646a4e8de57d3e3e5d666d7739d"} build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010902 build_url: 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0bd4dfd6df36e90171b2fe2ae70a87d19b8b63e7909fac693949565633f0153b920b5b3565eeb6e1934fc49531a62173978bd0173c9314eb8e98ed76768eea24", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1630e3d092174ffcb1e4d603cfff74752d47d7edf019d544e4a193f4f965a71e99102fc6c4c070fabdbc3136b7a75602a3277646a4e8de57d3e3e5d666d7739d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:24.313239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:24.313550) ========= 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.tfvaqUsKos/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.N6n6FLSMtN/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.005100 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:24.362103) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:39:24.362418) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:39:24.362705) ========= ========= Total master_lag: 0.16 =========