builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0083 starttime: 1462537061.16 results: success (0) buildid: 20160506013122 builduid: edd2de8de4ca48969474bf5b1f92b92a revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.164784) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.165257) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.165530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.182808) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.183116) ========= 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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:17:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.31M=0.001s 2016-05-06 05:17:41 (8.31 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.345246) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.345558) ========= 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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.019786 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:41.393333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:17:41.393740) ========= 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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:17:41,466 truncating revision to first 12 chars 2016-05-06 05:17:41,466 Setting DEBUG logging. 2016-05-06 05:17:41,466 attempt 1/10 2016-05-06 05:17:41,466 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:17:43,060 unpacking tar archive at: mozilla-aurora-b14d8e23323e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.840750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:17:43.253160) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:43.253583) ========= script_repo_revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:43.254094) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:43.254443) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:17:43.266393) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-06 05:17:43.266778) ========= /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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:17:43 INFO - MultiFileLogger online at 20160506 05:17:43 in /builds/slave/test 05:17:43 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:17:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:43 INFO - {'append_to_log': False, 05:17:43 INFO - 'base_work_dir': '/builds/slave/test', 05:17:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:17:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:43 INFO - 'branch': 'Mozilla-Aurora', 05:17:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:43 INFO - 'config_files': ('talos/mac_config.py',), 05:17:43 INFO - 'default_actions': ('clobber', 05:17:43 INFO - 'read-buildbot-config', 05:17:43 INFO - 'download-and-extract', 05:17:43 INFO - 'populate-webroot', 05:17:43 INFO - 'create-virtualenv', 05:17:43 INFO - 'install', 05:17:43 INFO - 'run-tests'), 05:17:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:43 INFO - 'download_minidump_stackwalk': True, 05:17:43 INFO - 'download_symbols': 'ondemand', 05:17:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:43 INFO - 'installer_path': 'installer.exe', 05:17:43 INFO - 'log_level': 'info', 05:17:43 INFO - 'log_name': 'talos', 05:17:43 INFO - 'log_to_console': True, 05:17:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:43 INFO - 'opt_config_files': (), 05:17:43 INFO - 'pip_index': False, 05:17:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:43 INFO - 'cmd': ('bash', 05:17:43 INFO - '-c', 05:17:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:43 INFO - 'enabled': True, 05:17:43 INFO - 'halt_on_failure': False, 05:17:43 INFO - 'name': 'check_screen_resolution'},), 05:17:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:43 INFO - 'cmd': ('bash', 05:17:43 INFO - '-c', 05:17:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:43 INFO - 'enabled': True, 05:17:43 INFO - 'halt_on_failure': False, 05:17:43 INFO - 'name': 'check_screen_resolution'},), 05:17:43 INFO - 'run_cmd_checks_enabled': True, 05:17:43 INFO - 'sps_profile': False, 05:17:43 INFO - 'sps_profile_interval': 0, 05:17:43 INFO - 'suite': 'dromaeojs-e10s', 05:17:43 INFO - 'system_bits': '32', 05:17:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:43 INFO - 'title': 't-yosemite-r7-0083', 05:17:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:43 INFO - 'use_talos_json': True, 05:17:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:43 INFO - 'work_dir': 'build'} 05:17:43 INFO - ##### 05:17:43 INFO - ##### Running clobber step. 05:17:43 INFO - ##### 05:17:43 INFO - Running pre-action listener: _resource_record_pre_action 05:17:43 INFO - Running main action method: clobber 05:17:43 INFO - rmtree: /builds/slave/test/build 05:17:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:44 INFO - Running post-action listener: _resource_record_post_action 05:17:44 INFO - ##### 05:17:44 INFO - ##### Running read-buildbot-config step. 05:17:44 INFO - ##### 05:17:44 INFO - Running pre-action listener: _resource_record_pre_action 05:17:44 INFO - Running main action method: read_buildbot_config 05:17:44 INFO - Using buildbot properties: 05:17:44 INFO - { 05:17:44 INFO - "project": "", 05:17:44 INFO - "product": "firefox", 05:17:44 INFO - "script_repo_revision": "production", 05:17:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:17:44 INFO - "repository": "", 05:17:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:17:44 INFO - "buildid": "20160506013122", 05:17:44 INFO - "pgo_build": "False", 05:17:44 INFO - "basedir": "/builds/slave/test", 05:17:44 INFO - "buildnumber": 313, 05:17:44 INFO - "slavename": "t-yosemite-r7-0083", 05:17:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:17:44 INFO - "platform": "yosemite_r7", 05:17:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:17:44 INFO - "branch": "mozilla-aurora", 05:17:44 INFO - "repo_path": "releases/mozilla-aurora", 05:17:44 INFO - "revision": "b14d8e23323e95c5db3bc4cca69738edeee44302", 05:17:44 INFO - "stage_platform": "macosx64", 05:17:44 INFO - "builduid": "edd2de8de4ca48969474bf5b1f92b92a", 05:17:44 INFO - "slavebuilddir": "test" 05:17:44 INFO - } 05:17:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:17:44 INFO - Running post-action listener: _resource_record_post_action 05:17:44 INFO - ##### 05:17:44 INFO - ##### Running download-and-extract step. 05:17:44 INFO - ##### 05:17:44 INFO - Running pre-action listener: _resource_record_pre_action 05:17:44 INFO - Running main action method: download_and_extract 05:17:44 INFO - mkdir: /builds/slave/test/build/tests 05:17: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:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:45 INFO - Downloaded 1199 bytes. 05:17:45 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:17:45 INFO - Using the following test package requirements: 05:17:45 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:17:45 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:17:45 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:17:45 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'jsshell-mac.zip'], 05:17:45 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:17:45 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:17:45 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:17:45 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:17:45 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:17:45 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:17:45 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:17:45 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:46 INFO - Downloaded 18113035 bytes. 05:17:46 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:17:46 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:17:49 INFO - Return code: 0 05:17:49 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:17:49 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:17:49 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:17:49 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:17:49 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:17:49 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:17:49 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:17:51 INFO - Downloaded 18113035 bytes. 05:17:51 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:17:51 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:17:54 INFO - Return code: 0 05:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:55 INFO - Downloaded 10840145 bytes. 05:17:55 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:17:55 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:17:56 INFO - Return code: 0 05:17: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:17:56 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:17:56 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:17:56 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:17:56 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:17:56 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:17:59 INFO - Downloaded 114079746 bytes. 05:17:59 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:17:59 INFO - mkdir: /builds/slave/test/properties 05:17:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:59 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:59 INFO - Contents: 05:17:59 INFO - build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:00 INFO - Running post-action listener: _resource_record_post_action 05:18:00 INFO - Running post-action listener: set_extra_try_arguments 05:18:00 INFO - ##### 05:18:00 INFO - ##### Running populate-webroot step. 05:18:00 INFO - ##### 05:18:00 INFO - Running pre-action listener: _resource_record_pre_action 05:18:00 INFO - Running main action method: populate_webroot 05:18:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:00 INFO - '--tppagecycles', 05:18:00 INFO - '7'], 05:18:00 INFO - 'tests': ['tsvgm']}}, 05:18:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['damp', 'tps']}, 05:18:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'tests': ['damp', 'tps']}, 05:18:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['dromaeo_dom']}, 05:18:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['a11yr', 05:18:00 INFO - 'ts_paint', 05:18:00 INFO - 'tpaint', 05:18:00 INFO - 'sessionrestore', 05:18:00 INFO - 'sessionrestore_no_auto_restore', 05:18:00 INFO - 'tabpaint']}, 05:18:00 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:00 INFO - 'ts_paint', 05:18:00 INFO - 'tpaint', 05:18:00 INFO - 'sessionrestore', 05:18:00 INFO - 'sessionrestore_no_auto_restore', 05:18:00 INFO - 'tabpaint']}, 05:18:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:00 INFO - 'ts_paint', 05:18:00 INFO - 'tpaint', 05:18:00 INFO - 'sessionrestore', 05:18:00 INFO - 'sessionrestore_no_auto_restore', 05:18:00 INFO - 'tabpaint']}, 05:18:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:00 INFO - 'ts_paint', 05:18:00 INFO - 'tpaint', 05:18:00 INFO - 'sessionrestore', 05:18:00 INFO - 'sessionrestore_no_auto_restore', 05:18:00 INFO - 'tabpaint']}, 05:18:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['a11yr', 05:18:00 INFO - 'ts_paint', 05:18:00 INFO - 'tpaint', 05:18:00 INFO - 'sessionrestore', 05:18:00 INFO - 'sessionrestore_no_auto_restore', 05:18:00 INFO - 'tabpaint']}, 05:18:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['a11yr', 05:18:00 INFO - 'ts_paint', 05:18:00 INFO - 'tpaint', 05:18:00 INFO - 'sessionrestore', 05:18:00 INFO - 'sessionrestore_no_auto_restore', 05:18:00 INFO - 'tabpaint']}, 05:18:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['tsvgx', 05:18:00 INFO - 'tsvgr_opacity', 05:18:00 INFO - 'tart', 05:18:00 INFO - 'tscrollx', 05:18:00 INFO - 'cart']}, 05:18:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:00 INFO - 'tsvgr_opacity', 05:18:00 INFO - 'tart', 05:18:00 INFO - 'tscrollx', 05:18:00 INFO - 'cart']}, 05:18:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'talos_options': ['--disable-e10s'], 05:18:00 INFO - 'tests': ['tp5o']}, 05:18:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'tests': ['tp5o']}, 05:18:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'talos_options': ['--disable-e10s', 05:18:00 INFO - '--xperf_path', 05:18:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:00 INFO - 'tests': ['tp5n']}, 05:18:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:00 INFO - 'talos_options': ['--xperf_path', 05:18:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:00 INFO - 'tests': ['tp5n']}}, 05:18:00 INFO - 'talos.zip': {'path': '', 05:18:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:00 INFO - Running post-action listener: _resource_record_post_action 05:18:00 INFO - ##### 05:18:00 INFO - ##### Running create-virtualenv step. 05:18:00 INFO - ##### 05:18:00 INFO - Running pre-action listener: _resource_record_pre_action 05:18:00 INFO - Running main action method: create_virtualenv 05:18:00 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:00 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:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:00 INFO - Using real prefix '/tools/python27' 05:18:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:01 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:03 INFO - Installing pip.................done. 05:18:03 INFO - Return code: 0 05:18:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:03 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:03 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:03 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:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:04 INFO - 'HOME': '/Users/cltbld', 05:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:04 INFO - 'LOGNAME': 'cltbld', 05:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:04 INFO - 'MOZ_NO_REMOTE': '1', 05:18:04 INFO - 'NO_EM_RESTART': '1', 05:18:04 INFO - 'PAGER': '/bin/cat', 05:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:04 INFO - 'PWD': '/builds/slave/test', 05:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:04 INFO - 'SHELL': '/bin/bash', 05:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:04 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:04 INFO - 'USER': 'cltbld', 05:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:04 INFO - 'XPC_FLAGS': '0x0', 05:18:04 INFO - 'XPC_SERVICE_NAME': '0', 05:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:04 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:04 INFO - Cleaning up... 05:18:04 INFO - Return code: 0 05:18:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:04 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:04 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:04 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:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:04 INFO - 'HOME': '/Users/cltbld', 05:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:04 INFO - 'LOGNAME': 'cltbld', 05:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:04 INFO - 'MOZ_NO_REMOTE': '1', 05:18:04 INFO - 'NO_EM_RESTART': '1', 05:18:04 INFO - 'PAGER': '/bin/cat', 05:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:04 INFO - 'PWD': '/builds/slave/test', 05:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:04 INFO - 'SHELL': '/bin/bash', 05:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:04 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:04 INFO - 'USER': 'cltbld', 05:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:04 INFO - 'XPC_FLAGS': '0x0', 05:18:04 INFO - 'XPC_SERVICE_NAME': '0', 05:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:04 INFO - Downloading/unpacking psutil>=0.7.1 05:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:07 INFO - Installing collected packages: psutil 05:18:07 INFO - Running setup.py install for psutil 05:18:07 INFO - building 'psutil._psutil_osx' extension 05:18:07 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:07 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:07 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:07 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:07 INFO - building 'psutil._psutil_posix' extension 05:18:07 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:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:07 INFO - ^ 05:18:07 INFO - 1 warning generated. 05:18:07 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:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:07 INFO - Successfully installed psutil 05:18:07 INFO - Cleaning up... 05:18:08 INFO - Return code: 0 05:18:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:18:08 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:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 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:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:08 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:08 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:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:08 INFO - 'HOME': '/Users/cltbld', 05:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:08 INFO - 'LOGNAME': 'cltbld', 05:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:08 INFO - 'MOZ_NO_REMOTE': '1', 05:18:08 INFO - 'NO_EM_RESTART': '1', 05:18:08 INFO - 'PAGER': '/bin/cat', 05:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:08 INFO - 'PWD': '/builds/slave/test', 05:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:08 INFO - 'SHELL': '/bin/bash', 05:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:08 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:08 INFO - 'USER': 'cltbld', 05:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:08 INFO - 'XPC_FLAGS': '0x0', 05:18:08 INFO - 'XPC_SERVICE_NAME': '0', 05:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:10 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:10 INFO - Installing collected packages: mozsystemmonitor 05:18:10 INFO - Running setup.py install for mozsystemmonitor 05:18:10 INFO - Successfully installed mozsystemmonitor 05:18:10 INFO - Cleaning up... 05:18:11 INFO - Return code: 0 05:18:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:11 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:11 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:11 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:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:11 INFO - 'HOME': '/Users/cltbld', 05:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:11 INFO - 'LOGNAME': 'cltbld', 05:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:11 INFO - 'MOZ_NO_REMOTE': '1', 05:18:11 INFO - 'NO_EM_RESTART': '1', 05:18:11 INFO - 'PAGER': '/bin/cat', 05:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:11 INFO - 'PWD': '/builds/slave/test', 05:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:11 INFO - 'SHELL': '/bin/bash', 05:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:11 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:11 INFO - 'USER': 'cltbld', 05:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:11 INFO - 'XPC_FLAGS': '0x0', 05:18:11 INFO - 'XPC_SERVICE_NAME': '0', 05:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:11 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:11 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:11 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:11 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:11 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:11 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:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18: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:18: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:18: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:18: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:18: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:18: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:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:14 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:14 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:14 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:14 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:14 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:14 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:14 INFO - Downloading docopt-0.6.1.tar.gz 05:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:14 INFO - Running setup.py install for blobuploader 05:18:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:15 INFO - Running setup.py install for requests 05:18:15 INFO - Running setup.py install for docopt 05:18:15 INFO - Successfully installed blobuploader requests docopt 05:18:15 INFO - Cleaning up... 05:18:15 INFO - Return code: 0 05:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:15 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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:15 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:15 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:15 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:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:15 INFO - 'HOME': '/Users/cltbld', 05:18:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:15 INFO - 'LOGNAME': 'cltbld', 05:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:15 INFO - 'MOZ_NO_REMOTE': '1', 05:18:15 INFO - 'NO_EM_RESTART': '1', 05:18:15 INFO - 'PAGER': '/bin/cat', 05:18:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:15 INFO - 'PWD': '/builds/slave/test', 05:18:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:15 INFO - 'SHELL': '/bin/bash', 05:18:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:15 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:15 INFO - 'USER': 'cltbld', 05:18:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:15 INFO - 'XPC_FLAGS': '0x0', 05:18:15 INFO - 'XPC_SERVICE_NAME': '0', 05:18:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-5doHWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-SZCJW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-yGYeRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-aQuD6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9rwszn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-OYczrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-kicbuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-NLZEj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:16 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-zdrrPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-DGcVKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-UoOYPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-F8o3nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-YDRhNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-kLA_WX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-EjlcDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-7QcThz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:17 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-tJDBi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:17 INFO - Running setup.py install for manifestparser 05:18:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:18 INFO - Running setup.py install for mozcrash 05:18:18 INFO - Running setup.py install for mozdebug 05:18:18 INFO - Running setup.py install for mozdevice 05:18:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:18 INFO - Running setup.py install for mozfile 05:18:18 INFO - Running setup.py install for mozhttpd 05:18:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:18 INFO - Running setup.py install for mozinfo 05:18:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:18 INFO - Running setup.py install for mozInstall 05:18:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Running setup.py install for mozleak 05:18:19 INFO - Running setup.py install for mozlog 05:18:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Running setup.py install for moznetwork 05:18:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Running setup.py install for mozprocess 05:18:19 INFO - Running setup.py install for mozprofile 05:18:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:19 INFO - Running setup.py install for mozrunner 05:18:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:20 INFO - Running setup.py install for mozscreenshot 05:18:20 INFO - Running setup.py install for moztest 05:18:20 INFO - Running setup.py install for mozversion 05:18:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:20 INFO - Cleaning up... 05:18:20 INFO - Return code: 0 05:18:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:20 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:20 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:18:20 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:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:20 INFO - 'HOME': '/Users/cltbld', 05:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:20 INFO - 'LOGNAME': 'cltbld', 05:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:20 INFO - 'MOZ_NO_REMOTE': '1', 05:18:20 INFO - 'NO_EM_RESTART': '1', 05:18:20 INFO - 'PAGER': '/bin/cat', 05:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:20 INFO - 'PWD': '/builds/slave/test', 05:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:20 INFO - 'SHELL': '/bin/bash', 05:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:20 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:20 INFO - 'USER': 'cltbld', 05:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:20 INFO - 'XPC_FLAGS': '0x0', 05:18:20 INFO - 'XPC_SERVICE_NAME': '0', 05:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:20 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-8qKsaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:20 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:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:20 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-zfkcPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:20 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:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:20 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-3fSO8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ngSzWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ruaSYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-g0413U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ugAMp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9bPBwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-IK6J6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-5EQsAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-fnTC2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:21 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:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:21 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-63ouyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:22 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:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Qb8sVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:22 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:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Yp1rNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:22 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:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Q0U6zU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:22 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:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-2NxNim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:22 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:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:22 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-MFuUaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:22 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:18:22 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:18:22 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:18:22 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:18:22 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:18:22 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:18:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:24 INFO - Downloading blessings-1.6.tar.gz 05:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:25 INFO - Installing collected packages: blessings 05:18:25 INFO - Running setup.py install for blessings 05:18:25 INFO - Successfully installed blessings 05:18:25 INFO - Cleaning up... 05:18:25 INFO - Return code: 0 05:18:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:25 INFO - Reading from file tmpfile_stdout 05:18:25 INFO - Current package versions: 05:18:25 INFO - blessings == 1.6 05:18:25 INFO - blobuploader == 1.2.4 05:18:25 INFO - docopt == 0.6.1 05:18:25 INFO - manifestparser == 1.1 05:18:25 INFO - mozInstall == 1.12 05:18:25 INFO - mozcrash == 0.17 05:18:25 INFO - mozdebug == 0.1 05:18:25 INFO - mozdevice == 0.48 05:18:25 INFO - mozfile == 1.2 05:18:25 INFO - mozhttpd == 0.7 05:18:25 INFO - mozinfo == 0.9 05:18:25 INFO - mozleak == 0.1 05:18:25 INFO - mozlog == 3.1 05:18:25 INFO - moznetwork == 0.27 05:18:25 INFO - mozprocess == 0.22 05:18:25 INFO - mozprofile == 0.28 05:18:25 INFO - mozrunner == 6.11 05:18:25 INFO - mozscreenshot == 0.1 05:18:25 INFO - mozsystemmonitor == 0.0 05:18:25 INFO - moztest == 0.7 05:18:25 INFO - mozversion == 1.4 05:18:25 INFO - psutil == 3.1.1 05:18:25 INFO - requests == 1.2.3 05:18:25 INFO - wsgiref == 0.1.2 05:18:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:25 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:25 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:18:25 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:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:25 INFO - 'HOME': '/Users/cltbld', 05:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:25 INFO - 'LOGNAME': 'cltbld', 05:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:25 INFO - 'MOZ_NO_REMOTE': '1', 05:18:25 INFO - 'NO_EM_RESTART': '1', 05:18:25 INFO - 'PAGER': '/bin/cat', 05:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:25 INFO - 'PWD': '/builds/slave/test', 05:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:25 INFO - 'SHELL': '/bin/bash', 05:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:25 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:25 INFO - 'USER': 'cltbld', 05:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:25 INFO - 'XPC_FLAGS': '0x0', 05:18:25 INFO - 'XPC_SERVICE_NAME': '0', 05:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 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:18:25 INFO - Cleaning up... 05:18:25 INFO - Return code: 0 05:18:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:25 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:25 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:18:25 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:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:25 INFO - 'HOME': '/Users/cltbld', 05:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:25 INFO - 'LOGNAME': 'cltbld', 05:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:25 INFO - 'MOZ_NO_REMOTE': '1', 05:18:25 INFO - 'NO_EM_RESTART': '1', 05:18:25 INFO - 'PAGER': '/bin/cat', 05:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:25 INFO - 'PWD': '/builds/slave/test', 05:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:25 INFO - 'SHELL': '/bin/bash', 05:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:25 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:25 INFO - 'USER': 'cltbld', 05:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:25 INFO - 'XPC_FLAGS': '0x0', 05:18:25 INFO - 'XPC_SERVICE_NAME': '0', 05:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:26 INFO - Downloading/unpacking jsonschema 05:18:26 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:26 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:26 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:26 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:26 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:26 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:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:28 INFO - Downloading/unpacking functools32 (from jsonschema) 05:18:28 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:28 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:28 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:28 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:28 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:28 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:28 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:29 INFO - warning: no files found matching '*.txt' 05:18:29 INFO - no previously-included directories found matching 'build' 05:18:29 INFO - no previously-included directories found matching 'dist' 05:18:29 INFO - no previously-included directories found matching '.git*' 05:18:29 INFO - Installing collected packages: jsonschema, functools32 05:18:29 INFO - Running setup.py install for functools32 05:18:29 INFO - warning: no files found matching '*.txt' 05:18:29 INFO - no previously-included directories found matching 'build' 05:18:29 INFO - no previously-included directories found matching 'dist' 05:18:29 INFO - no previously-included directories found matching '.git*' 05:18:29 INFO - Successfully installed jsonschema functools32 05:18:29 INFO - Cleaning up... 05:18:29 INFO - Return code: 0 05:18:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:29 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 0x10dd0da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd1d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd023574580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, '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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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:29 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:18:29 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:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/Render', 05:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:29 INFO - 'HOME': '/Users/cltbld', 05:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:29 INFO - 'LOGNAME': 'cltbld', 05:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:29 INFO - 'MOZ_NO_REMOTE': '1', 05:18:29 INFO - 'NO_EM_RESTART': '1', 05:18:29 INFO - 'PAGER': '/bin/cat', 05:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:29 INFO - 'PWD': '/builds/slave/test', 05:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:29 INFO - 'SHELL': '/bin/bash', 05:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ebfwPL1FBV/Listeners', 05:18:29 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 05:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:29 INFO - 'USER': 'cltbld', 05:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:29 INFO - 'XPC_FLAGS': '0x0', 05:18:29 INFO - 'XPC_SERVICE_NAME': '0', 05:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:29 INFO - Downloading/unpacking flake8 05:18:29 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:29 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:29 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:29 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:29 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:29 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:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:32 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:32 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:32 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:32 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:32 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:32 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:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:32 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:32 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:32 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:32 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:32 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:32 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:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:32 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:32 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:32 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:32 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:32 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:32 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:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:33 INFO - no previously-included directories found matching 'docs/_build' 05:18:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:33 INFO - Running setup.py install for pep8 05:18:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:33 INFO - no previously-included directories found matching 'docs/_build' 05:18:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:33 INFO - Cleaning up... 05:18:33 INFO - Return code: 0 05:18:33 INFO - Running post-action listener: _resource_record_post_action 05:18:33 INFO - Running post-action listener: _start_resource_monitoring 05:18:33 INFO - Starting resource monitoring. 05:18:33 INFO - ##### 05:18:33 INFO - ##### Running install step. 05:18:33 INFO - ##### 05:18:33 INFO - Running pre-action listener: _resource_record_pre_action 05:18:33 INFO - Running main action method: install 05:18:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:33 INFO - Reading from file tmpfile_stdout 05:18:33 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:34 INFO - Reading from file tmpfile_stdout 05:18:34 INFO - Output received: 05:18:34 INFO - Usage: mozinstall [options] installer 05:18:34 INFO - Options: 05:18:34 INFO - -h, --help show this help message and exit 05:18:34 INFO - -d DEST, --destination=DEST 05:18:34 INFO - Directory to install application into. [default: 05:18:34 INFO - "/builds/slave/test"] 05:18:34 INFO - --app=APP Application being installed. [default: firefox] 05:18:34 INFO - mkdir: /builds/slave/test/build/application 05:18:34 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:18:34 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:05 INFO - Reading from file tmpfile_stdout 05:19:05 INFO - Output received: 05:19:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:19:05 INFO - Running post-action listener: _resource_record_post_action 05:19:05 INFO - ##### 05:19:05 INFO - ##### Running run-tests step. 05:19:05 INFO - ##### 05:19:05 INFO - Running pre-action listener: _resource_record_pre_action 05:19:05 INFO - Running main action method: run_tests 05:19:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:05 INFO - 2016-05-06 05:19:05.436 screenresolution[1927:10879] starting screenresolution argv=screenresolution get 05:19:05 INFO - 2016-05-06 05:19:05.439 screenresolution[1927:10879] Display 0: 1600x1200x32@60 05:19:05 INFO - 2016-05-06 05:19:05.445 screenresolution[1928:10881] starting screenresolution argv=screenresolution list 05:19:05 INFO - Available Modes on Display 0 05:19:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:05 INFO - Intel Iris: 05:19:05 INFO - Chipset Model: Intel Iris 05:19:05 INFO - Type: GPU 05:19:05 INFO - Bus: Built-In 05:19:05 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:05 INFO - Vendor: Intel (0x8086) 05:19:05 INFO - Device ID: 0x0a2e 05:19:05 INFO - Revision ID: 0x0009 05:19:05 INFO - Displays: 05:19:05 INFO - Display: 05:19:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:05 INFO - Display Serial Number: 1600x1200 60 05:19:05 INFO - Main Display: Yes 05:19:05 INFO - Mirror: Off 05:19:05 INFO - Online: Yes 05:19:05 INFO - Rotation: Supported 05:19:05 INFO - Return code: 0 05:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:05 INFO - Python 2.7.3 05:19:05 INFO - Return code: 0 05:19:05 INFO - grabbing minidump binary from tooltool 05:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:05 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 0x10dbace00>, '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 0x10da15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:05 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:05 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:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:05 INFO - Return code: 0 05:19:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:19:06 INFO - Return code: 0 05:19:06 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-0083', '--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:06 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-0083 --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:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E37ZyxGFNo/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 - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 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:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 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.ebfwPL1FBV/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 - 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-0083', '--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:07 INFO - mozversion application_buildid: 20160506013122 05:19:07 INFO - mozversion application_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:19:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:19:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:07 INFO - mozversion application_name: Firefox 05:19:07 INFO - mozversion application_remotingname: firefox-dev 05:19:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:19:07 INFO - mozversion application_vendor: Mozilla 05:19:07 INFO - mozversion application_version: 48.0a2 05:19:07 INFO - mozversion platform_buildid: 20160506013122 05:19:07 INFO - mozversion platform_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:19:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:19:07 INFO - mozversion platform_version: 48.0a2 05:19:07 INFO - using testdate: 1462537147 05:19:07 INFO - actual date: 1462537147 05:19:07 INFO - starting webserver on 'localhost:49661' 05:19:07 INFO - SUITE-START | Running 2 tests 05:19:07 INFO - TEST-START | dromaeo_css 05:19:07 INFO - operating with platform_type : mac_ 05:19:07 INFO - Initialising browser for dromaeo_css test... 05:19:07 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpPycXLz/profile http://localhost:49661/getInfo.html) 05:19:09 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 05:19:09 INFO - PROCESS | 1946 | colorDepth:24 05:19:09 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 05:19:09 INFO - PROCESS | 1946 | __metrics 05:19:09 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:19:10 INFO - TEST-INFO | 1946: exit 0 05:19:10 INFO - Browser initialized. 05:19:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:10 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpPycXLz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:22 INFO - PROCESS | 1949 | RSS: Main: 221302784 05:19:22 INFO - PROCESS | 1949 | 05:20:12 INFO - PROCESS | 1949 | 1462537212018 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:22:22 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49661/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49661/tests/dromaeo/cssquery-ext.html) 05:22:24 INFO - PROCESS | 1949 | RSS: Main: 236179456 05:22:24 INFO - PROCESS | 1949 | 05:25:26 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49661/tests/dromaeo/cssquery-ext.html (next: http://localhost:49661/tests/dromaeo/cssquery-jquery.html) 05:25:27 INFO - PROCESS | 1949 | RSS: Main: 246419456 05:25:27 INFO - PROCESS | 1949 | 05:28:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49661/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49661/tests/dromaeo/cssquery-mootools.html) 05:28:29 INFO - PROCESS | 1949 | RSS: Main: 248426496 05:28:29 INFO - PROCESS | 1949 | 05:31:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49661/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49661/tests/dromaeo/cssquery-prototype.html) 05:31:31 INFO - PROCESS | 1949 | RSS: Main: 250658816 05:31:31 INFO - PROCESS | 1949 | 05:34:34 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49661/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49661/tests/dromaeo/cssquery-yui.html) 05:34:35 INFO - PROCESS | 1949 | RSS: Main: 252940288 05:34:35 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49661/tests/dromaeo/cssquery-yui.html (next: http://localhost:49661/tests/dromaeo/cssquery-dojo.html) 05:37:37 INFO - PROCESS | 1949 | RSS: Main: 253972480 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | __start_tp_report 05:37:37 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 05:37:37 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 05:37:37 INFO - PROCESS | 1949 | |i|pagename|runs| 05:37:37 INFO - PROCESS | 1949 | |0;dojo.html;3408.863645454182;3433.570803649544;3439.1058324835567;3456.8271586420674;3475.4439289713637;5720.8855822883515;5724.198612194291;5735.971320143393;5752.884942301162;5773.826785196446;5743.54051675866;5753.482186603205;5773.884522309551;5782.624129431584;5809.244798176232;5137.743112844352;5159.251908473269;5229.973850130744;5234.241035049916;5238.330070584948;5073.4926507349155;5202.089634313998;5209.088409528336;5210.947890521104;5311.123664595337;5178.404483484391;5205.349331333583;5219.86950326243;5233.738313084331;5319.468053194688;5166.896662066756;5247.553957913582;5262.789488420459;5268.604854635918;5290.841274761763;4333;5115.565176959963;5116.079105760961;5218.66078704883;5265.420803711589;4279.251131052053;5325.653832500914;5525.392206857243;5544.63959842609;5647.29408823897;6196.380361963767;6207.81376558704;6250.43746062852;6265.7493700251935;6305.0542418637115;6247.250329960434;6254.624722516618;6261.591141993061;6263.091851681526;6277.18396608438;5630.605857589971;5778.3354914184565;5817.7382017809305;5839.737211825478;5916.083007133901;6274.278457977391;6296.244450665858;6327.462165715873;6329.841753956188;6360.77737279193;6182.598131121463;6184.567080304426;6194;6201.968990155111;6211.596246243979;6137.8060289514;6138.1099740537375;6213.96893015532;6271.592346497463;6274.090256912722;6157.107219453154;6201.441870231611;6202.976508875985;6224.066390041439;6237.313895471494;5200.843974680765;5632.690202038927;6181.690915454209;6224.190855188796;6225.906611400833;3861.768293902375;4945.060438516671;4951.3810773653295;4953.8266160684925;4978.5768209701855;4443.888902777457;4445;4452.510223875371;4472.798724057425;4477.395551600557;4626.86119416418;5647.209390685307;5747.166660834239;5831.8336332733625;5860.296764388216;6134.069235337226;6160.691965401712;6228.190335256388;6259.655718935411;6278.4035516625845;5604.915926261109;5643.5767317451355;5652;5661.830145095653;5679.290088738908;5287.550558202596;5352.9732351338;5356.973215133978;5358.946410535923;5366.604682782427;6333.714982825784;6336.1762970814425;6360.045993101072;6370.872582548323;6385.936140638534;6236.781712640036;6251.437370636482;6259.186305780219;6260.593061450991;6269.5611307208055;6174.567780255339;6305.369463053658;6352.36476352361;6362.300146983921;6379.6810159490915;5950.928832810136;5986.491148252448;6011.0382338825575;6018.067199584071;6040.365761595145;5925.940740592711;6040.456358927718;6084.11780291864;6088.573799833969;6128.019516877278;5858.5898987072205;5938.61399009063;6094.75620975156;6128.9920470692405;6170.413810688157;6100.113629567553;6139.539534534839;6145.108959200801;6167.907481387693;6168.321484636596;5761.078227483583;5848.298204215522;6000.5799594028;6016.639001659914;6033.487153591994;4637.39713837199;5226.425093239816;5281.287026251407;6107.059952181533;6140.0134868502855;6041.9395806041375;6056.90914636272;6063.090536419572;6078.574499784972;6080.513558915188 05:37:37 INFO - PROCESS | 1949 | |1;ext.html;493.9481354457782;495.4451014863354;536.6431323170092;541.6479288462499;551.5504863536217;39799.801000994994;41333.79333103334;41366.7931660342;41366.96582585437;41429.96425089367;42813.7859310703;43384;43423;43734.781326093405;43837;9523.80952380952;9801.509924503782;9815.067568580973;9817.214622830157;9839.114479696826;9654.13112819846;9803.411795292306;9808.31341806074;9822.754431139207;9833.213342932548;9776.266779991494;9814.165795907356;9818.018198180196;9860.654877079303;9861.654842080528;9758.316917815755;9809.950950245275;9811.950940245253;9835.360701554411;9859.408435493893;9599.42403455795;9717.902820971842;9729.318947673666;9731.075547822946;9731.270154738351;39557.20885582272;39918.20163596712;39969.400458993136;40074.59925400738;40151.19697606032;2912.6213592232875;3238.0933338665213;3320.468725003988;3478.4260597001467;3509.175343794206;27564.724352756417;27825;28549;29166;29491.115266542038;2761.7100204478443;2785.470760555488;2804.1167032384733;2805.7895657825743;2837.9006734764184;443575;515895;518406;523216;523416.3829180868;34217.82891085579;35236.82381588127;67526.32473675201;67939.32060679428;68074.97887531691;35052.64947350545;35672.46491302633;35981.10047248787;36043.81978090093;36144;15379.923405361516;15387.692246155015;15590.14254216029;15600.531984040497;15620.609484762972;12793.232406055666;12997.870021299856;13080.803787943189;13084.542041028519;13121.343932803322;8185.672573097128;8295.170482951777;8362.080171181165;8392.20274073962;8459.450135741157;3364.7308215342714;3373.325334933023;3435.896923092311;3451.534042904226;3472.5485686860548;1004.8492726091145;1020.8417695257247;1090.340344091829;1106.7461662711603;1203.7291609387992;25754;25871.353216169748;27503.724962750515;30500;30757.000794948257;1103.172620534599;1124.8875112488686;1125.7917285302244;1135.1486385211092;1210.0138387214392;14353.569392918229;14355.210463424402;14651.194184319966;15750.423091621104;16109.19454027294;680800;681720;691019;691502.5424872944;692146;59430;63044;63894.36105638884;64092.679536602016;64101.038484422774;1504.097541475128;1529.1513210168464;1531.3338697666327;1539.9923000384927;1542;30092.398152037276;30679.846600767745;30892.845535772176;31050.534241985937;31355.90254341087;46398.53601463942;53972.46027539674;54185;54813.62965925724;54926;594507;597758;601100;602375;605098;25798.09706660258;26200.606990894772;39921.00197495086;41009.38485922654;41729.95675108147;43111.784441077594;43287;43981.12037759291;45097.32354014758;45824;35283.294334113685;35796.463053054744;36792.44811327779;36881.262374751816;37071.44392834056;26818.865905670347;35260.11849703778;36461.27077458489;36835.63164368322;38685.80657096697;30497.847510762305;35643.28713425769;36364.90905272846;36482.8175859119;37208.255834882606;25632.8718356407;31809.045728628178;32268;32823.83588082044;32929;24821.62767558452;24822.37944051413;25188.496230075663;25387;25527.361815954006 05:37:37 INFO - PROCESS | 1949 | |2;jquery.html;408.1775222925805;409.5679058593183;411.64392800227813;412.7564736805284;418.6086009581042;12911.67720806978;13143.868561314384;15679.529614111581;15689.980151290172;20839.895800521015;20025.599488010266;20306.390808275777;21002.26492072778;21050;21369.252076177338;5529.668219906798;5974.701264936736;6057;6091.14723938649;6098.847528811771;5381.919271210915;6003.819885403423;6017.097435384683;6068.6965651717455;6903.896441553381;6161.90757138641;7013.614251216181;7355.558666480001;7359.227281135484;7398.445116616248;6267.247930248376;7543.132539757943;7552.509086909388;7579.848403031963;7622.847543049135;6729.5625784323865;7142.2857714228685;7159.427245820322;7175.4977151599405;7364.04267445234;26445.867770661214;26548.070817521293;27427.451450970868;28813.991510297248;28853.990110346247;2077.2937201351615;2194.418479103039;2293.739260477066;2350.2831636350907;3047.54286856971;15116.09303441786;15153.318100685496;16247.756283655755;16437.424690135787;16459.83540164607;1860.7022876339856;1876.2213681322235;1894.2612381171355;1908.0459770114942;1946.0853398902493;128685.35657321653;133052;146901;147072;151906;17728.734068988975;20792.792072079388;21302.934853257277;21920.123195072018;21964.780352196594;14330.355134018993;15055.473058442902;15080.321385537678;37177.39758216899;37202.25595488129;7515.361194298546;8600.354973377021;9171.991080981086;9174.816503669888;9207;9033.81932361349;9121.498317592463;9129.954350228207;9156.404833685789;9185.586648600829;4237.216115018669;4481.484629267611;4704.764761761899;4760.071786001697;4783.545563171493;1989.2043182726736;2031.4819720971059;2054.2296638760463;2067.9114094764395;2084.3121769815925;888.7244954064261;942.5852624845046;1011.2365164300905;1075.5805235957982;1089.2701889733896;20073.698894516594;20622.27822026222;21026.369208923756;21038.474038148863;21046.894765526074;971.9222462203007;998.605550807427;1015.5582321690089;1078.0836289154313;1083.6532309660834;10684.038436540593;10923.890761092447;11129.610463633895;11141.387223702612;11155.442227888572;574609.1269543709;589514;599432;599515;617343;138541;139240;140091;140528;143421.28289358487;1003.0972125087334;1077.9245452818227;1079.9892001080045;1080.378782200238;1100.9889901100887;17847.73228401601;18176.818231817513;18526.888386696843;18822.34121805785;19300;54823.451765481834;55347.723261383435;56001;56094.43905560892;56841.14738278846;77553.61223193847;77596;77931.61034194793;78022.60988695247;79865.60067199628;7681.615919203906;7992.600369981594;8086.474379165335;8121.675132994614;8679.17547832955;6954.965225173842;8915.687950921687;11049.33703977764;12367.443465043914;12491.688372720733;8374.49753014821;10036.64871729486;10238.283320167517;10878.45607719595;11144.832827507431;10753.46232688378;10801.189910756882;10840.566377345134;10971.354296855216;10986.230963832455;9900.25748068912;11159.49782259784;11308.264962777394;11316.773664526829;11379;7740.729074482366;8236.299914507334;8351.24838764514;8359.707410240855;8511.106333834858;9510.429374237568;10795.028447439769;10863.967923047298;10949.50727217293;10959.561617535528 05:37:37 INFO - PROCESS | 1949 | |3;mootools.html;1603.7193491139049;1624.1960229686304;1632.0941877258122;1847.732078848566;1900.8384287335575;6982.755603553864;7021.262767409418;7948.403869709766;8862.980757212923;8875.556222188889;8593.828123437543;8645.783855403619;8708.172723591264;8709.172628600272;8726.778251044843;2440.853548787074;2466.6793316868784;2470.0984140788664;2477.0587176872764;2483.863387513695;2392.7846493815555;2452.4849781545895;2454.705435347752;2466.938326541842;2469.3826543364157;1821.9635607287846;1887.848972082237;2062.608104460155;2151.7633060363346;2481.85108893467;2457.1768457566736;2462.273629279358;2469.296250568588;2481.3796550862285;2486.527559763639;2450.534398464286;2466.48203877186;2475.6410320503605;2479.764422379871;2487.577111890983;7566.772996810105;8847.46915185091;8934.508602026886;8945.552722363856;8960.731178064669;5365.865853353658;5575.665460072409;5588;5600.271964644611;5614.494695477427;3579.158897659048;3615.9276814463565;3618.511500947391;3674.6141655126094;3675.7426980111136;1046.1212581431557;1061.7557961668783;1066.898644628759;1070.5610699613198;1085.2782899371907;11570.42147892602;11576.536938522533;11713.414329283587;11734.647960561197;11741.00201482875;4907.067657145131;4907.7300748458465;4992.775325110379;5047.747612619428;5076.213186956028;10080.495975201356;10135.797284054424;10138.03688649577;10147.9492602538;10178.440185789852;5457.645253058538;5499.202615620793;5541.722913854291;5571.9721401392735;5613.691246981373;4482.663800214997;4854.17479028566;4984.42679091909;5003.399592048978;5025.422076461254;4133.483314585676;4162.791860406967;4216.5572614875;4222.683298752544;4241.491021077491;1070.7323169207698;1376.9328770202972;1378.676011137382;1378.8690074443111;1399.2723783632455;818.3739439328918;834.9399451011973;915.8351496730519;919.4483310013939;922.7693076730817;5187.221916712523;5622.128570071645;5797.420257974169;5833.649981001068;5853.975554277984;946.3564775952418;950.4059962523423;995.929375920888;997.5860018092555;998.2413365841867;3731.7201209909367;3756.8497260109834;3811.27585758705;3830.272248272819;3849.172427928009;10500.894991050145;10875.293105948089;10911.83632245517;10920.672379828618;10994.395308258123;10765.83851242232;10773.407462589634;10856.240063195659;10925.726856828484;10932.672019839418;7846.568438735924;8105.878411823827;8259.091499935003;8279.213474720013;8302.750917472486;5197.3628307083145;5706.629069110494;5757.8560535986935;5827.009408400667;5857.58996870215;8901.821963560564;9478.715638530855;9599.32804703664;9828;9831.164351029956;9675.516224188621;9988.500574971367;9993;10030.398176109456;10113.29206955506;6913.412359949461;7287.890681639674;7472.252774722484;7542.509736867086;7657.540547567164;7018;8256.422050456411;8309.33525317961;8332.250097491255;8370.958145209235;7304.342609165201;7393.334599886036;7482.775516734507;7518.210587888193;7553;5975.25309336333;6183.258009038765;7317.231690672403;7411.184769675224;7455.7017719290625;6103.572749907464;6763.391294783493;6819.965900170468;6874.621895795579;6984.930150698631;4762.571368576845;4782.832600858953;4800;4890.217565189552;4916.827911023097;4824.951750482591;4826.565609095198;4840.2255639097575;4853.587445067209;4909.140900342497 05:37:37 INFO - PROCESS | 1949 | |4;prototype.html;328.5597299618511;331.6070456509036;334.89116037287886;338.8695352289368;339.77235252380905;4722.17503821904;5338.466153384659;5431.375391829941;5571.164325351194;5571.331440227168;4229.788510574466;5456.672599644025;5579.38626751057;5596.468335508121;5715.914261286073;4202.159568086379;4250.447441832557;4264.808083636229;4269.871903842874;4270.615644591986;4135.8833115058915;4263.339182426729;4276.208901353244;4278.016056307051;4324.956750432502;3634;4197.979010104945;4211.663066954651;4238.343056826181;4251.980309353064;3904.7071469639604;4205.432266644025;4233.343831706091;4238.597333253336;4240.533541310453;3078.39971205615;3714.0900479382453;3715.5727091384565;3740.251949610089;3745.3632882410056;4337.327714204303;4402.449693788277;5060.9493905060845;5277.947220527784;5350.197470379474;4713.976430117862;4889.584385327252;4895.902081958377;4900.975495122537;4983.750812459362;5176.818811341623;5254.894902101974;5904.61619994699;5906.852328691774;5937.643741375531;4105.466289382391;4109.732867363612;4110.17796440713;4123.299039163349;4128.174365126986;4958.752062396866;5729.5702822288495;6206.596571222856;6284.434400903895;6357.65032923189;3716.535433070866;3905.199434116056;4172.499300083949;4185.811638476276;4189.183109293658;3883.669888059547;4265.7440553566885;4266.935995960063;4269.807858646368;4279.957200428018;4483.529229430928;4542.0915816836105;4911.263310503453;4916.852494425173;4922.310876477296;4683.414573178353;4915.95084049155;4960.70235785854;5566.16316615524;5900.232969713909;1334.1328136711215;1406.6777229404531;1520.5438368489408;1561.3442354211259;1569.858712715861;936.5457752989795;936.6113063078747;1003.9508713394521;1021.5300961557618;1046.6336782126348;4684.81260749573;5766.769329226784;5872.559558033165;5878.941210587925;5913.704314784243;5629.24005259293;5731.185803924724;5879.3826648202175;6203.534734894901;6606.240282367493;5320.270911953553;5727.77088916447;5859.53123750099;5945.435183657545;5958.165856780054;2769.2384594236746;3434.4504879219203;3460.52927505809;3473;3473.7568370213844;5895.55783316253;6303.338149494329;6735.932640673628;6738.393544581011;6958.582485050913;5714.514266287412;6465;6766;6816.580114059537;6824.590524568443;6058.7273572688455;6498.415142637185;6908.723651054002;6918.446524278046;6940.826479337956;5932.673702946379;6164.260288765377;6169.198004259418;6177.9691101546005;6199.070139479114;5114.64197506171;5462.098753705753;5760.539156867357;5933.970330148321;6122.877542449215;5103.617228707936;5117.15566931467;6337.7148028337915;6457.5479716419395;6458.7093580789915;3578.173243137892;3794.734368594172;3844.2503711775935;4063.349864021742;4082.632563069338;4960.826371076995;5616.634918730269;5637.52081073097;5834.795782147605;6207.937920620736;5140.511651393112;5878.441548052928;5906.379830117776;5909.261342332208;5918.875413671389;5201.297824793604;5908.3500814911185;5922;5949.821505354673;6006.129111278928;4738.028704115579;4763.476017637988;4979.402471703419;5699.686517241592;5834.533237340917;5588.77644894216;5664.320281566238;5887.61730487517;5935.465808077293;5969.432903874125;5124.41069277023;5604.411536788579;5833.183354330312;5836.737346819488;5853.03424934881 05:37:37 INFO - PROCESS | 1949 | |5;yui.html;660.3561527510677;717.2039036669292;725.9491835571512;730.2843213650622;760.9885851712223;13656.317184140791;14025.43898244069;14090.29548522575;14092.22492762899;14144.222067786268;15056.774148387756;15599;15624;15629.531114066596;15650.843491565081;750.9849803003937;783.385042741448;943.0428115462811;953.918916892065;954.265215783846;914.2822884036033;942.5240253671886;959.6257459590765;962.0187408842976;964.7009427077593;994.3089552760835;994.3387647214594;995.945223012734;998.4608311511774;1000.5597537083696;970.3256236915347;980.5244456438621;990.6384169778052;992.4640694025254;992.6674564021025;723.3417589993081;725.7133432294214;728.4391018915459;743.4758495260828;746.3693179263568;15128.697426051418;15148.924255378653;15203.695926081527;15296.923515382352;15400.614984625361;11194.888051119466;11349.375784331858;12278.938605307005;12313.384330783425;12541.247525148521;9883.639562548768;10148.289619726622;10168.694939151837;10234.437105959169;10250.897491025069;3420.572428446444;3452.87914922979;3495.6679115484244;3501.509788629594;3530.346885826105;78282.21717782806;78379;79004;79214.41571168602;79857;9467.857982130274;9490;9602.231821454267;9621.374610650286;9788.461634610023;20974.89512552458;21111.838848863154;21171.47071323229;21212.363629091153;21245.256416025673;6871.14110736158;6873.005933744816;7432.888506672404;7487.363574096263;7655.464117511832;4072.6334629883454;4115.855945041909;4125.968507873032;4131.710780245414;4138.8344466221615;3453.8445769940413;3536.6816986470826;3543.9013905689317;3544.769589976643;3551.94672079919;625.0624063904144;638.8211300835774;641.9005054216603;645.4868379638199;648.6075924065966;473.5785151215421;486.8369096352691;491.61162681481943;502.8717676992417;566.4448840136689;7880.093789214199;7974.880376794353;8018.198180181935;8037.638306276114;8244.628991695388;475.7050628610215;507.97206153661165;552.8700755322496;575.0942265931092;584.272580637101;3571.375009373401;3810.2950954073585;3816.2367526494813;3826.9043273918373;3901.2197560488016;99923;101333.49333253434;101975.98024019813;104581.47709261558;105906.47046764717;59792.40207597955;60104.39895600988;77645.61177194078;78593;82038;73171.63414182895;74573;74602;74803.62598187083;74932.42133245757;7341.889871651822;7394.445416593884;7730.420218483749;7772.883406748791;8006.879896801669;43995.78002109969;44761.776191118835;44952.42666506657;45063.873403165184;45737.77131114456;58191.4180858186;58402.7079864598;60220.69889650524;62261.68869155625;62635;10827.296225745302;10856.436728918032;10957.561697532328;11177.85218130009;11346.602868899548;11613.419329033684;12017.278963262232;12046.397680115784;12249.632511024683;12305.384730763604;10852.782944341227;10895.945520272347;10911.618093366695;10922.945385273022;10930.890691092987;10394.636187733393;10402.687919362432;10497.2127090467;10504.737381565523;10646.361218326616;9633.08485693858;10435;10547.419891906018;10798.13614910821;11193.832092518782;9581.568829402555;9673.854892176481;9747;10010.399376037461;10105.494725263854;9225.24857335089;9560.378575392577;9561.760955976157;9582.775214565605;9589.760255993657 05:37:37 INFO - PROCESS | 1949 | __end_tp_report 05:37:37 INFO - PROCESS | 1949 | __start_cc_report 05:37:37 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,416 05:37:37 INFO - PROCESS | 1949 | __end_cc_report 05:37:37 INFO - PROCESS | 1949 | __startTimestamp1462538257403__endTimestamp 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | ------- Summary: start ------- 05:37:37 INFO - PROCESS | 1949 | Number of tests: 6 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | [#0] dojo.html Cycles:165 Average:5691.35 Median:5916.08 stddev:667.18 (11.3%) stddev-sans-first:644.75 05:37:37 INFO - PROCESS | 1949 | Values: 3408.9 3433.6 3439.1 3456.8 3475.4 5720.9 5724.2 5736.0 5752.9 5773.8 5743.5 5753.5 5773.9 5782.6 5809.2 5137.7 5159.3 5230.0 5234.2 5238.3 5073.5 5202.1 5209.1 5210.9 5311.1 5178.4 5205.3 5219.9 5233.7 5319.5 5166.9 5247.6 5262.8 5268.6 5290.8 4333.0 5115.6 5116.1 5218.7 5265.4 4279.3 5325.7 5525.4 5544.6 5647.3 6196.4 6207.8 6250.4 6265.7 6305.1 6247.3 6254.6 6261.6 6263.1 6277.2 5630.6 5778.3 5817.7 5839.7 5916.1 6274.3 6296.2 6327.5 6329.8 6360.8 6182.6 6184.6 6194.0 6202.0 6211.6 6137.8 6138.1 6214.0 6271.6 6274.1 6157.1 6201.4 6203.0 6224.1 6237.3 5200.8 5632.7 6181.7 6224.2 6225.9 3861.8 4945.1 4951.4 4953.8 4978.6 4443.9 4445.0 4452.5 4472.8 4477.4 4626.9 5647.2 5747.2 5831.8 5860.3 6134.1 6160.7 6228.2 6259.7 6278.4 5604.9 5643.6 5652.0 5661.8 5679.3 5287.6 5353.0 5357.0 5358.9 5366.6 6333.7 6336.2 6360.0 6370.9 6385.9 6236.8 6251.4 6259.2 6260.6 6269.6 6174.6 6305.4 6352.4 6362.3 6379.7 5950.9 5986.5 6011.0 6018.1 6040.4 5925.9 6040.5 6084.1 6088.6 6128.0 5858.6 5938.6 6094.8 6129.0 6170.4 6100.1 6139.5 6145.1 6167.9 6168.3 5761.1 5848.3 6000.6 6016.6 6033.5 4637.4 5226.4 5281.3 6107.1 6140.0 6041.9 6056.9 6063.1 6078.6 6080.5 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | [#1] ext.html Cycles:180 Average:71303.87 Median:36976.35 stddev:161567.74 (436.9%) stddev-sans-first:161933.51 05:37:37 INFO - PROCESS | 1949 | Values: 493.9 495.4 536.6 541.6 551.6 39799.8 41333.8 41366.8 41367.0 41430.0 42813.8 43384.0 43423.0 43734.8 43837.0 9523.8 9801.5 9815.1 9817.2 9839.1 9654.1 9803.4 9808.3 9822.8 9833.2 9776.3 9814.2 9818.0 9860.7 9861.7 9758.3 9810.0 9812.0 9835.4 9859.4 9599.4 9717.9 9729.3 9731.1 9731.3 39557.2 39918.2 39969.4 40074.6 40151.2 2912.6 3238.1 3320.5 3478.4 3509.2 27564.7 27825.0 28549.0 29166.0 29491.1 2761.7 2785.5 2804.1 2805.8 2837.9 443575.0 515895.0 518406.0 523216.0 523416.4 34217.8 35236.8 67526.3 67939.3 68075.0 35052.6 35672.5 35981.1 36043.8 36144.0 15379.9 15387.7 15590.1 15600.5 15620.6 12793.2 12997.9 13080.8 13084.5 13121.3 8185.7 8295.2 8362.1 8392.2 8459.5 3364.7 3373.3 3435.9 3451.5 3472.5 1004.8 1020.8 1090.3 1106.7 1203.7 25754.0 25871.4 27503.7 30500.0 30757.0 1103.2 1124.9 1125.8 1135.1 1210.0 14353.6 14355.2 14651.2 15750.4 16109.2 680800.0 681720.0 691019.0 691502.5 692146.0 59430.0 63044.0 63894.4 64092.7 64101.0 1504.1 1529.2 1531.3 1540.0 1542.0 30092.4 30679.8 30892.8 31050.5 31355.9 46398.5 53972.5 54185.0 54813.6 54926.0 594507.0 597758.0 601100.0 602375.0 605098.0 25798.1 26200.6 39921.0 41009.4 41730.0 43111.8 43287.0 43981.1 45097.3 45824.0 35283.3 35796.5 36792.4 36881.3 37071.4 26818.9 35260.1 36461.3 36835.6 38685.8 30497.8 35643.3 36364.9 36482.8 37208.3 25632.9 31809.0 32268.0 32823.8 32929.0 24821.6 24822.4 25188.5 25387.0 25527.4 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | [#2] jquery.html Cycles:180 Average:36718.22 Median:12129.26 stddev:100398.93 (827.7%) stddev-sans-first:100643.55 05:37:37 INFO - PROCESS | 1949 | Values: 408.2 409.6 411.6 412.8 418.6 12911.7 13143.9 15679.5 15690.0 20839.9 20025.6 20306.4 21002.3 21050.0 21369.3 5529.7 5974.7 6057.0 6091.1 6098.8 5381.9 6003.8 6017.1 6068.7 6903.9 6161.9 7013.6 7355.6 7359.2 7398.4 6267.2 7543.1 7552.5 7579.8 7622.8 6729.6 7142.3 7159.4 7175.5 7364.0 26445.9 26548.1 27427.5 28814.0 28854.0 2077.3 2194.4 2293.7 2350.3 3047.5 15116.1 15153.3 16247.8 16437.4 16459.8 1860.7 1876.2 1894.3 1908.0 1946.1 128685.4 133052.0 146901.0 147072.0 151906.0 17728.7 20792.8 21302.9 21920.1 21964.8 14330.4 15055.5 15080.3 37177.4 37202.3 7515.4 8600.4 9172.0 9174.8 9207.0 9033.8 9121.5 9130.0 9156.4 9185.6 4237.2 4481.5 4704.8 4760.1 4783.5 1989.2 2031.5 2054.2 2067.9 2084.3 888.7 942.6 1011.2 1075.6 1089.3 20073.7 20622.3 21026.4 21038.5 21046.9 971.9 998.6 1015.6 1078.1 1083.7 10684.0 10923.9 11129.6 11141.4 11155.4 574609.1 589514.0 599432.0 599515.0 617343.0 138541.0 139240.0 140091.0 140528.0 143421.3 1003.1 1077.9 1080.0 1080.4 1101.0 17847.7 18176.8 18526.9 18822.3 19300.0 54823.5 55347.7 56001.0 56094.4 56841.1 77553.6 77596.0 77931.6 78022.6 79865.6 7681.6 7992.6 8086.5 8121.7 8679.2 6955.0 8915.7 11049.3 12367.4 12491.7 8374.5 10036.6 10238.3 10878.5 11144.8 10753.5 10801.2 10840.6 10971.4 10986.2 9900.3 11159.5 11308.3 11316.8 11379.0 7740.7 8236.3 8351.2 8359.7 8511.1 9510.4 10795.0 10864.0 10949.5 10959.6 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | [#3] mootools.html Cycles:180 Average:5676.43 Median:4950.63 stddev:3151.89 (63.7%) stddev-sans-first:3145.87 05:37:37 INFO - PROCESS | 1949 | Values: 1603.7 1624.2 1632.1 1847.7 1900.8 6982.8 7021.3 7948.4 8863.0 8875.6 8593.8 8645.8 8708.2 8709.2 8726.8 2440.9 2466.7 2470.1 2477.1 2483.9 2392.8 2452.5 2454.7 2466.9 2469.4 1822.0 1887.8 2062.6 2151.8 2481.9 2457.2 2462.3 2469.3 2481.4 2486.5 2450.5 2466.5 2475.6 2479.8 2487.6 7566.8 8847.5 8934.5 8945.6 8960.7 5365.9 5575.7 5588.0 5600.3 5614.5 3579.2 3615.9 3618.5 3674.6 3675.7 1046.1 1061.8 1066.9 1070.6 1085.3 11570.4 11576.5 11713.4 11734.6 11741.0 4907.1 4907.7 4992.8 5047.7 5076.2 10080.5 10135.8 10138.0 10147.9 10178.4 5457.6 5499.2 5541.7 5572.0 5613.7 4482.7 4854.2 4984.4 5003.4 5025.4 4133.5 4162.8 4216.6 4222.7 4241.5 1070.7 1376.9 1378.7 1378.9 1399.3 818.4 834.9 915.8 919.4 922.8 5187.2 5622.1 5797.4 5833.6 5854.0 946.4 950.4 995.9 997.6 998.2 3731.7 3756.8 3811.3 3830.3 3849.2 10500.9 10875.3 10911.8 10920.7 10994.4 10765.8 10773.4 10856.2 10925.7 10932.7 7846.6 8105.9 8259.1 8279.2 8302.8 5197.4 5706.6 5757.9 5827.0 5857.6 8901.8 9478.7 9599.3 9828.0 9831.2 9675.5 9988.5 9993.0 10030.4 10113.3 6913.4 7287.9 7472.3 7542.5 7657.5 7018.0 8256.4 8309.3 8332.3 8371.0 7304.3 7393.3 7482.8 7518.2 7553.0 5975.3 6183.3 7317.2 7411.2 7455.7 6103.6 6763.4 6820.0 6874.6 6984.9 4762.6 4782.8 4800.0 4890.2 4916.8 4825.0 4826.6 4840.2 4853.6 4909.1 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | [#4] prototype.html Cycles:180 Average:4813.75 Median:5132.46 stddev:1493.89 (29.1%) stddev-sans-first:1459.65 05:37:37 INFO - PROCESS | 1949 | Values: 328.6 331.6 334.9 338.9 339.8 4722.2 5338.5 5431.4 5571.2 5571.3 4229.8 5456.7 5579.4 5596.5 5715.9 4202.2 4250.4 4264.8 4269.9 4270.6 4135.9 4263.3 4276.2 4278.0 4325.0 3634.0 4198.0 4211.7 4238.3 4252.0 3904.7 4205.4 4233.3 4238.6 4240.5 3078.4 3714.1 3715.6 3740.3 3745.4 4337.3 4402.4 5060.9 5277.9 5350.2 4714.0 4889.6 4895.9 4901.0 4983.8 5176.8 5254.9 5904.6 5906.9 5937.6 4105.5 4109.7 4110.2 4123.3 4128.2 4958.8 5729.6 6206.6 6284.4 6357.7 3716.5 3905.2 4172.5 4185.8 4189.2 3883.7 4265.7 4266.9 4269.8 4280.0 4483.5 4542.1 4911.3 4916.9 4922.3 4683.4 4916.0 4960.7 5566.2 5900.2 1334.1 1406.7 1520.5 1561.3 1569.9 936.5 936.6 1004.0 1021.5 1046.6 4684.8 5766.8 5872.6 5878.9 5913.7 5629.2 5731.2 5879.4 6203.5 6606.2 5320.3 5727.8 5859.5 5945.4 5958.2 2769.2 3434.5 3460.5 3473.0 3473.8 5895.6 6303.3 6735.9 6738.4 6958.6 5714.5 6465.0 6766.0 6816.6 6824.6 6058.7 6498.4 6908.7 6918.4 6940.8 5932.7 6164.3 6169.2 6178.0 6199.1 5114.6 5462.1 5760.5 5934.0 6122.9 5103.6 5117.2 6337.7 6457.5 6458.7 3578.2 3794.7 3844.3 4063.3 4082.6 4960.8 5616.6 5637.5 5834.8 6207.9 5140.5 5878.4 5906.4 5909.3 5918.9 5201.3 5908.4 5922.0 5949.8 6006.1 4738.0 4763.5 4979.4 5699.7 5834.5 5588.8 5664.3 5887.6 5935.5 5969.4 5124.4 5604.4 5833.2 5836.7 5853.0 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - PROCESS | 1949 | [#5] yui.html Cycles:180 Average:18046.22 Median:530.42 stddev:25880.58 (4879.3%) stddev-sans-first:25920.26 05:37:37 INFO - PROCESS | 1949 | Values: 660.4 717.2 725.9 730.3 761.0 13656.3 14025.4 14090.3 14092.2 14144.2 15056.8 15599.0 15624.0 15629.5 15650.8 751.0 783.4 943.0 953.9 954.3 914.3 942.5 959.6 962.0 964.7 994.3 994.3 995.9 998.5 1000.6 970.3 980.5 990.6 992.5 992.7 723.3 725.7 728.4 743.5 746.4 15128.7 15148.9 15203.7 15296.9 15400.6 11194.9 11349.4 12278.9 12313.4 12541.2 9883.6 10148.3 10168.7 10234.4 10250.9 3420.6 3452.9 3495.7 3501.5 3530.3 78282.2 78379.0 79004.0 79214.4 79857.0 9467.9 9490.0 9602.2 9621.4 9788.5 20974.9 21111.8 21171.5 21212.4 21245.3 6871.1 6873.0 7432.9 7487.4 7655.5 4072.6 4115.9 4126.0 4131.7 4138.8 3453.8 3536.7 3543.9 3544.8 3551.9 625.1 638.8 641.9 645.5 648.6 473.6 486.8 491.6 502.9 566.4 7880.1 7974.9 8018.2 8037.6 8244.6 475.7 508.0 552.9 575.1 584.3 3571.4 3810.3 3816.2 3826.9 3901.2 99923.0 101333.5 101976.0 104581.5 105906.5 59792.4 60104.4 77645.6 78593.0 82038.0 73171.6 74573.0 74602.0 74803.6 74932.4 7341.9 7394.4 7730.4 7772.9 8006.9 43995.8 44761.8 44952.4 45063.9 45737.8 58191.4 58402.7 60220.7 62261.7 62635.0 10827.3 10856.4 10957.6 11177.9 11346.6 11613.4 12017.3 12046.4 12249.6 12305.4 10852.8 10895.9 10911.6 10922.9 10930.9 10394.6 10402.7 10497.2 10504.7 10646.4 9633.1 10435.0 10547.4 10798.1 11193.8 9581.6 9673.9 9747.0 10010.4 10105.5 9225.2 9560.4 9561.8 9582.8 9589.8 05:37:37 INFO - PROCESS | 1949 | -------- Summary: end -------- 05:37:37 INFO - PROCESS | 1949 | 05:37:37 INFO - TEST-INFO | 1949: exit 0 05:37:37 INFO - TEST-OK | dromaeo_css | took 1110887ms 05:37:37 INFO - TEST-START | kraken 05:37:37 INFO - operating with platform_type : mac_ 05:37:37 INFO - Initialising browser for kraken test... 05:37:37 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpW25nor/profile http://localhost:49661/getInfo.html) 05:37:40 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 05:37:40 INFO - PROCESS | 1956 | colorDepth:24 05:37:40 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 05:37:40 INFO - PROCESS | 1956 | __metrics 05:37:40 ERROR - PROCESS | 1956 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:37:41 INFO - TEST-INFO | 1956: exit 0 05:37:41 INFO - Browser initialized. 05:37:41 INFO - Running cycle 1/1 for kraken test... 05:37:41 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpW25nor/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:53 INFO - PROCESS | 1959 | RSS: Main: 221413376 05:37:53 INFO - PROCESS | 1959 | 05:37:56 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49661/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:57 INFO - PROCESS | 1959 | RSS: Main: 233295872 05:37:57 INFO - PROCESS | 1959 | 05:38:00 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49661/tests/kraken/driver.html?testName=audio-dft) 05:38:01 INFO - PROCESS | 1959 | RSS: Main: 242044928 05:38:01 INFO - PROCESS | 1959 | 05:38:04 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49661/tests/kraken/driver.html?testName=audio-fft) 05:38:04 INFO - PROCESS | 1959 | RSS: Main: 245731328 05:38:04 INFO - PROCESS | 1959 | 05:38:06 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49661/tests/kraken/driver.html?testName=audio-oscillator) 05:38:07 INFO - PROCESS | 1959 | RSS: Main: 246087680 05:38:07 INFO - PROCESS | 1959 | 05:38:09 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49661/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:10 INFO - PROCESS | 1959 | RSS: Main: 247595008 05:38:10 INFO - PROCESS | 1959 | 05:38:12 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49661/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:13 INFO - PROCESS | 1959 | RSS: Main: 248172544 05:38:13 INFO - PROCESS | 1959 | 05:38:16 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49661/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:17 INFO - PROCESS | 1959 | RSS: Main: 248270848 05:38:17 INFO - PROCESS | 1959 | 05:38:20 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49661/tests/kraken/driver.html?testName=json-parse-financial) 05:38:21 INFO - PROCESS | 1959 | RSS: Main: 248340480 05:38:21 INFO - PROCESS | 1959 | 05:38:22 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49661/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:23 INFO - PROCESS | 1959 | RSS: Main: 248401920 05:38:23 INFO - PROCESS | 1959 | 05:38:25 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:25 INFO - PROCESS | 1959 | RSS: Main: 247877632 05:38:25 INFO - PROCESS | 1959 | 05:38:28 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:28 INFO - PROCESS | 1959 | RSS: Main: 248127488 05:38:28 INFO - PROCESS | 1959 | 05:38:31 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:32 INFO - PROCESS | 1959 | RSS: Main: 248205312 05:38:32 INFO - PROCESS | 1959 | 05:38:35 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:35 INFO - PROCESS | 1959 | RSS: Main: 248078336 05:38:35 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49661/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49661/tests/kraken/driver.html?testName=ai-astar) 05:38:37 INFO - PROCESS | 1959 | RSS: Main: 249520128 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | __start_tp_report 05:38:37 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 05:38:37 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 05:38:37 INFO - PROCESS | 1959 | |i|pagename|runs| 05:38:37 INFO - PROCESS | 1959 | |0;ai-astar;161;165;129;127;116;130;116;115;112;138 05:38:37 INFO - PROCESS | 1959 | |1;audio-beat-detection;154;125;149;165;123;164;142;111;163;147 05:38:37 INFO - PROCESS | 1959 | |2;audio-dft;195;202;191;201;200;205;200;197;197;194 05:38:37 INFO - PROCESS | 1959 | |3;audio-fft;67;83;76;147;58;56;55;66;123;58 05:38:37 INFO - PROCESS | 1959 | |4;audio-oscillator;80;81;125;75;80;81;124;84;80;121 05:38:37 INFO - PROCESS | 1959 | |5;imaging-gaussian-blur;89;87;88;85;88;86;90;86;90;87 05:38:37 INFO - PROCESS | 1959 | |6;imaging-darkroom;95;126;108;153;133;155;94;121;103;151 05:38:37 INFO - PROCESS | 1959 | |7;imaging-desaturate;99;93;115;74;142;146;141;90;91;106 05:38:37 INFO - PROCESS | 1959 | |8;json-parse-financial;60;65;64;66;67;66;65;81;61;61 05:38:37 INFO - PROCESS | 1959 | |9;json-stringify-tinderbox;64;77;58;69;77;116;106;67;69;69 05:38:37 INFO - PROCESS | 1959 | |10;stanford-crypto-aes;84;84;82;82;82;83;83;79;83;85 05:38:37 INFO - PROCESS | 1959 | |11;stanford-crypto-ccm;139;163;154;161;159;161;133;135;172;154 05:38:37 INFO - PROCESS | 1959 | |12;stanford-crypto-pbkdf2;170;184;169;178;163;168;168;227;228;167 05:38:37 INFO - PROCESS | 1959 | |13;stanford-crypto-sha256-iterative;63;58;75;98;74;70;70;67;69;71 05:38:37 INFO - PROCESS | 1959 | __end_tp_report 05:38:37 INFO - PROCESS | 1959 | __start_cc_report 05:38:37 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,1102 05:38:37 INFO - PROCESS | 1959 | __end_cc_report 05:38:37 INFO - PROCESS | 1959 | __startTimestamp1462538317916__endTimestamp 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | ------- Summary: start ------- 05:38:37 INFO - PROCESS | 1959 | Number of tests: 14 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#0] ai-astar Cycles:10 Average:130.90 Median:129.50 stddev:18.84 (14.5%) stddev-sans-first:16.53 05:38:37 INFO - PROCESS | 1959 | Values: 161.0 165.0 129.0 127.0 116.0 130.0 116.0 115.0 112.0 138.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:151.50 stddev:18.94 (12.5%) stddev-sans-first:19.77 05:38:37 INFO - PROCESS | 1959 | Values: 154.0 125.0 149.0 165.0 123.0 164.0 142.0 111.0 163.0 147.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#2] audio-dft Cycles:10 Average:198.20 Median:200.00 stddev:4.18 (2.1%) stddev-sans-first:4.28 05:38:37 INFO - PROCESS | 1959 | Values: 195.0 202.0 191.0 201.0 200.0 205.0 200.0 197.0 197.0 194.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#3] audio-fft Cycles:10 Average:78.90 Median:62.00 stddev:31.42 (50.7%) stddev-sans-first:33.03 05:38:37 INFO - PROCESS | 1959 | Values: 67.0 83.0 76.0 147.0 58.0 56.0 55.0 66.0 123.0 58.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:80.00 stddev:21.00 (26.2%) stddev-sans-first:21.73 05:38:37 INFO - PROCESS | 1959 | Values: 80.0 81.0 125.0 75.0 80.0 81.0 124.0 84.0 80.0 121.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:1.71 (1.9%) stddev-sans-first:1.74 05:38:37 INFO - PROCESS | 1959 | Values: 89.0 87.0 88.0 85.0 88.0 86.0 90.0 86.0 90.0 87.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#6] imaging-darkroom Cycles:10 Average:123.90 Median:152.00 stddev:23.72 (15.6%) stddev-sans-first:22.73 05:38:37 INFO - PROCESS | 1959 | Values: 95.0 126.0 108.0 153.0 133.0 155.0 94.0 121.0 103.0 151.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#7] imaging-desaturate Cycles:10 Average:109.70 Median:82.00 stddev:25.36 (30.9%) stddev-sans-first:26.60 05:38:37 INFO - PROCESS | 1959 | Values: 99.0 93.0 115.0 74.0 142.0 146.0 141.0 90.0 91.0 106.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:65.50 stddev:5.93 (9.1%) stddev-sans-first:5.93 05:38:37 INFO - PROCESS | 1959 | Values: 60.0 65.0 64.0 66.0 67.0 66.0 65.0 81.0 61.0 61.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#9] json-stringify-tinderbox Cycles:10 Average:77.20 Median:69.00 stddev:18.81 (27.3%) stddev-sans-first:19.33 05:38:37 INFO - PROCESS | 1959 | Values: 64.0 77.0 58.0 69.0 77.0 116.0 106.0 67.0 69.0 69.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.64 (2.0%) stddev-sans-first:1.67 05:38:37 INFO - PROCESS | 1959 | Values: 84.0 84.0 82.0 82.0 82.0 83.0 83.0 79.0 83.0 85.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:160.00 stddev:13.11 (8.2%) stddev-sans-first:12.87 05:38:37 INFO - PROCESS | 1959 | Values: 139.0 163.0 154.0 161.0 159.0 161.0 133.0 135.0 172.0 154.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:174.00 stddev:24.61 (14.1%) stddev-sans-first:25.71 05:38:37 INFO - PROCESS | 1959 | Values: 170.0 184.0 169.0 178.0 163.0 168.0 168.0 227.0 228.0 167.0 05:38:37 INFO - PROCESS | 1959 | 05:38:37 INFO - PROCESS | 1959 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:70.50 stddev:10.58 (15.0%) stddev-sans-first:10.76 05:38:37 INFO - PROCESS | 1959 | Values: 63.0 58.0 75.0 98.0 74.0 70.0 70.0 67.0 69.0 71.0 05:38:37 INFO - PROCESS | 1959 | -------- Summary: end -------- 05:38:37 INFO - PROCESS | 1959 | 05:38:38 INFO - TEST-INFO | 1959: exit 0 05:38:38 INFO - TEST-OK | kraken | took 60713ms 05:38:38 INFO - SUITE-END | took 1171s 05:38:39 INFO - Completed test suite (00:19:32) 05:38:39 INFO - javascript benchmark 05:38:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.863645454182, 3433.570803649544, 3439.1058324835567, 3456.8271586420674, 3475.4439289713637, 5720.8855822883515, 5724.198612194291, 5735.971320143393, 5752.884942301162, 5773.826785196446, 5743.54051675866, 5753.482186603205, 5773.884522309551, 5782.624129431584, 5809.244798176232, 5137.743112844352, 5159.251908473269, 5229.973850130744, 5234.241035049916, 5238.330070584948, 5073.4926507349155, 5202.089634313998, 5209.088409528336, 5210.947890521104, 5311.123664595337, 5178.404483484391, 5205.349331333583, 5219.86950326243, 5233.738313084331, 5319.468053194688, 5166.896662066756, 5247.553957913582, 5262.789488420459, 5268.604854635918, 5290.841274761763, 4333.0, 5115.565176959963, 5116.079105760961, 5218.66078704883, 5265.420803711589, 4279.251131052053, 5325.653832500914, 5525.392206857243, 5544.63959842609, 5647.29408823897, 6196.380361963767, 6207.81376558704, 6250.43746062852, 6265.7493700251935, 6305.0542418637115, 6247.250329960434, 6254.624722516618, 6261.591141993061, 6263.091851681526, 6277.18396608438, 5630.605857589971, 5778.3354914184565, 5817.7382017809305, 5839.737211825478, 5916.083007133901, 6274.278457977391, 6296.244450665858, 6327.462165715873, 6329.841753956188, 6360.77737279193, 6182.598131121463, 6184.567080304426, 6194.0, 6201.968990155111, 6211.596246243979, 6137.8060289514, 6138.1099740537375, 6213.96893015532, 6271.592346497463, 6274.090256912722, 6157.107219453154, 6201.441870231611, 6202.976508875985, 6224.066390041439, 6237.313895471494, 5200.843974680765, 5632.690202038927, 6181.690915454209, 6224.190855188796, 6225.906611400833, 3861.768293902375, 4945.060438516671, 4951.3810773653295, 4953.8266160684925, 4978.5768209701855, 4443.888902777457, 4445.0, 4452.510223875371, 4472.798724057425, 4477.395551600557, 4626.86119416418, 5647.209390685307, 5747.166660834239, 5831.8336332733625, 5860.296764388216, 6134.069235337226, 6160.691965401712, 6228.190335256388, 6259.655718935411, 6278.4035516625845, 5604.915926261109, 5643.5767317451355, 5652.0, 5661.830145095653, 5679.290088738908, 5287.550558202596, 5352.9732351338, 5356.973215133978, 5358.946410535923, 5366.604682782427, 6333.714982825784, 6336.1762970814425, 6360.045993101072, 6370.872582548323, 6385.936140638534, 6236.781712640036, 6251.437370636482, 6259.186305780219, 6260.593061450991, 6269.5611307208055, 6174.567780255339, 6305.369463053658, 6352.36476352361, 6362.300146983921, 6379.6810159490915, 5950.928832810136, 5986.491148252448, 6011.0382338825575, 6018.067199584071, 6040.365761595145, 5925.940740592711, 6040.456358927718, 6084.11780291864, 6088.573799833969, 6128.019516877278, 5858.5898987072205, 5938.61399009063, 6094.75620975156, 6128.9920470692405, 6170.413810688157, 6100.113629567553, 6139.539534534839, 6145.108959200801, 6167.907481387693, 6168.321484636596, 5761.078227483583, 5848.298204215522, 6000.5799594028, 6016.639001659914, 6033.487153591994, 4637.39713837199, 5226.425093239816, 5281.287026251407, 6107.059952181533, 6140.0134868502855, 6041.9395806041375, 6056.90914636272, 6063.090536419572, 6078.574499784972, 6080.513558915188], "value": 5650.463803395939, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.9481354457782, 495.4451014863354, 536.6431323170092, 541.6479288462499, 551.5504863536217, 39799.801000994994, 41333.79333103334, 41366.7931660342, 41366.96582585437, 41429.96425089367, 42813.7859310703, 43384.0, 43423.0, 43734.781326093405, 43837.0, 9523.80952380952, 9801.509924503782, 9815.067568580973, 9817.214622830157, 9839.114479696826, 9654.13112819846, 9803.411795292306, 9808.31341806074, 9822.754431139207, 9833.213342932548, 9776.266779991494, 9814.165795907356, 9818.018198180196, 9860.654877079303, 9861.654842080528, 9758.316917815755, 9809.950950245275, 9811.950940245253, 9835.360701554411, 9859.408435493893, 9599.42403455795, 9717.902820971842, 9729.318947673666, 9731.075547822946, 9731.270154738351, 39557.20885582272, 39918.20163596712, 39969.400458993136, 40074.59925400738, 40151.19697606032, 2912.6213592232875, 3238.0933338665213, 3320.468725003988, 3478.4260597001467, 3509.175343794206, 27564.724352756417, 27825.0, 28549.0, 29166.0, 29491.115266542038, 2761.7100204478443, 2785.470760555488, 2804.1167032384733, 2805.7895657825743, 2837.9006734764184, 443575.0, 515895.0, 518406.0, 523216.0, 523416.3829180868, 34217.82891085579, 35236.82381588127, 67526.32473675201, 67939.32060679428, 68074.97887531691, 35052.64947350545, 35672.46491302633, 35981.10047248787, 36043.81978090093, 36144.0, 15379.923405361516, 15387.692246155015, 15590.14254216029, 15600.531984040497, 15620.609484762972, 12793.232406055666, 12997.870021299856, 13080.803787943189, 13084.542041028519, 13121.343932803322, 8185.672573097128, 8295.170482951777, 8362.080171181165, 8392.20274073962, 8459.450135741157, 3364.7308215342714, 3373.325334933023, 3435.896923092311, 3451.534042904226, 3472.5485686860548, 1004.8492726091145, 1020.8417695257247, 1090.340344091829, 1106.7461662711603, 1203.7291609387992, 25754.0, 25871.353216169748, 27503.724962750515, 30500.0, 30757.000794948257, 1103.172620534599, 1124.8875112488686, 1125.7917285302244, 1135.1486385211092, 1210.0138387214392, 14353.569392918229, 14355.210463424402, 14651.194184319966, 15750.423091621104, 16109.19454027294, 680800.0, 681720.0, 691019.0, 691502.5424872944, 692146.0, 59430.0, 63044.0, 63894.36105638884, 64092.679536602016, 64101.038484422774, 1504.097541475128, 1529.1513210168464, 1531.3338697666327, 1539.9923000384927, 1542.0, 30092.398152037276, 30679.846600767745, 30892.845535772176, 31050.534241985937, 31355.90254341087, 46398.53601463942, 53972.46027539674, 54185.0, 54813.62965925724, 54926.0, 594507.0, 597758.0, 601100.0, 602375.0, 605098.0, 25798.09706660258, 26200.606990894772, 39921.00197495086, 41009.38485922654, 41729.95675108147, 43111.784441077594, 43287.0, 43981.12037759291, 45097.32354014758, 45824.0, 35283.294334113685, 35796.463053054744, 36792.44811327779, 36881.262374751816, 37071.44392834056, 26818.865905670347, 35260.11849703778, 36461.27077458489, 36835.63164368322, 38685.80657096697, 30497.847510762305, 35643.28713425769, 36364.90905272846, 36482.8175859119, 37208.255834882606, 25632.8718356407, 31809.045728628178, 32268.0, 32823.83588082044, 32929.0, 24821.62767558452, 24822.37944051413, 25188.496230075663, 25387.0, 25527.361815954006], "value": 18908.242413015127, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.1775222925805, 409.5679058593183, 411.64392800227813, 412.7564736805284, 418.6086009581042, 12911.67720806978, 13143.868561314384, 15679.529614111581, 15689.980151290172, 20839.895800521015, 20025.599488010266, 20306.390808275777, 21002.26492072778, 21050.0, 21369.252076177338, 5529.668219906798, 5974.701264936736, 6057.0, 6091.14723938649, 6098.847528811771, 5381.919271210915, 6003.819885403423, 6017.097435384683, 6068.6965651717455, 6903.896441553381, 6161.90757138641, 7013.614251216181, 7355.558666480001, 7359.227281135484, 7398.445116616248, 6267.247930248376, 7543.132539757943, 7552.509086909388, 7579.848403031963, 7622.847543049135, 6729.5625784323865, 7142.2857714228685, 7159.427245820322, 7175.4977151599405, 7364.04267445234, 26445.867770661214, 26548.070817521293, 27427.451450970868, 28813.991510297248, 28853.990110346247, 2077.2937201351615, 2194.418479103039, 2293.739260477066, 2350.2831636350907, 3047.54286856971, 15116.09303441786, 15153.318100685496, 16247.756283655755, 16437.424690135787, 16459.83540164607, 1860.7022876339856, 1876.2213681322235, 1894.2612381171355, 1908.0459770114942, 1946.0853398902493, 128685.35657321653, 133052.0, 146901.0, 147072.0, 151906.0, 17728.734068988975, 20792.792072079388, 21302.934853257277, 21920.123195072018, 21964.780352196594, 14330.355134018993, 15055.473058442902, 15080.321385537678, 37177.39758216899, 37202.25595488129, 7515.361194298546, 8600.354973377021, 9171.991080981086, 9174.816503669888, 9207.0, 9033.81932361349, 9121.498317592463, 9129.954350228207, 9156.404833685789, 9185.586648600829, 4237.216115018669, 4481.484629267611, 4704.764761761899, 4760.071786001697, 4783.545563171493, 1989.2043182726736, 2031.4819720971059, 2054.2296638760463, 2067.9114094764395, 2084.3121769815925, 888.7244954064261, 942.5852624845046, 1011.2365164300905, 1075.5805235957982, 1089.2701889733896, 20073.698894516594, 20622.27822026222, 21026.369208923756, 21038.474038148863, 21046.894765526074, 971.9222462203007, 998.605550807427, 1015.5582321690089, 1078.0836289154313, 1083.6532309660834, 10684.038436540593, 10923.890761092447, 11129.610463633895, 11141.387223702612, 11155.442227888572, 574609.1269543709, 589514.0, 599432.0, 599515.0, 617343.0, 138541.0, 139240.0, 140091.0, 140528.0, 143421.28289358487, 1003.0972125087334, 1077.9245452818227, 1079.9892001080045, 1080.378782200238, 1100.9889901100887, 17847.73228401601, 18176.818231817513, 18526.888386696843, 18822.34121805785, 19300.0, 54823.451765481834, 55347.723261383435, 56001.0, 56094.43905560892, 56841.14738278846, 77553.61223193847, 77596.0, 77931.61034194793, 78022.60988695247, 79865.60067199628, 7681.615919203906, 7992.600369981594, 8086.474379165335, 8121.675132994614, 8679.17547832955, 6954.965225173842, 8915.687950921687, 11049.33703977764, 12367.443465043914, 12491.688372720733, 8374.49753014821, 10036.64871729486, 10238.283320167517, 10878.45607719595, 11144.832827507431, 10753.46232688378, 10801.189910756882, 10840.566377345134, 10971.354296855216, 10986.230963832455, 9900.25748068912, 11159.49782259784, 11308.264962777394, 11316.773664526829, 11379.0, 7740.729074482366, 8236.299914507334, 8351.24838764514, 8359.707410240855, 8511.106333834858, 9510.429374237568, 10795.028447439769, 10863.967923047298, 10949.50727217293, 10959.561617535528], "value": 10292.637635401768, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1603.7193491139049, 1624.1960229686304, 1632.0941877258122, 1847.732078848566, 1900.8384287335575, 6982.755603553864, 7021.262767409418, 7948.403869709766, 8862.980757212923, 8875.556222188889, 8593.828123437543, 8645.783855403619, 8708.172723591264, 8709.172628600272, 8726.778251044843, 2440.853548787074, 2466.6793316868784, 2470.0984140788664, 2477.0587176872764, 2483.863387513695, 2392.7846493815555, 2452.4849781545895, 2454.705435347752, 2466.938326541842, 2469.3826543364157, 1821.9635607287846, 1887.848972082237, 2062.608104460155, 2151.7633060363346, 2481.85108893467, 2457.1768457566736, 2462.273629279358, 2469.296250568588, 2481.3796550862285, 2486.527559763639, 2450.534398464286, 2466.48203877186, 2475.6410320503605, 2479.764422379871, 2487.577111890983, 7566.772996810105, 8847.46915185091, 8934.508602026886, 8945.552722363856, 8960.731178064669, 5365.865853353658, 5575.665460072409, 5588.0, 5600.271964644611, 5614.494695477427, 3579.158897659048, 3615.9276814463565, 3618.511500947391, 3674.6141655126094, 3675.7426980111136, 1046.1212581431557, 1061.7557961668783, 1066.898644628759, 1070.5610699613198, 1085.2782899371907, 11570.42147892602, 11576.536938522533, 11713.414329283587, 11734.647960561197, 11741.00201482875, 4907.067657145131, 4907.7300748458465, 4992.775325110379, 5047.747612619428, 5076.213186956028, 10080.495975201356, 10135.797284054424, 10138.03688649577, 10147.9492602538, 10178.440185789852, 5457.645253058538, 5499.202615620793, 5541.722913854291, 5571.9721401392735, 5613.691246981373, 4482.663800214997, 4854.17479028566, 4984.42679091909, 5003.399592048978, 5025.422076461254, 4133.483314585676, 4162.791860406967, 4216.5572614875, 4222.683298752544, 4241.491021077491, 1070.7323169207698, 1376.9328770202972, 1378.676011137382, 1378.8690074443111, 1399.2723783632455, 818.3739439328918, 834.9399451011973, 915.8351496730519, 919.4483310013939, 922.7693076730817, 5187.221916712523, 5622.128570071645, 5797.420257974169, 5833.649981001068, 5853.975554277984, 946.3564775952418, 950.4059962523423, 995.929375920888, 997.5860018092555, 998.2413365841867, 3731.7201209909367, 3756.8497260109834, 3811.27585758705, 3830.272248272819, 3849.172427928009, 10500.894991050145, 10875.293105948089, 10911.83632245517, 10920.672379828618, 10994.395308258123, 10765.83851242232, 10773.407462589634, 10856.240063195659, 10925.726856828484, 10932.672019839418, 7846.568438735924, 8105.878411823827, 8259.091499935003, 8279.213474720013, 8302.750917472486, 5197.3628307083145, 5706.629069110494, 5757.8560535986935, 5827.009408400667, 5857.58996870215, 8901.821963560564, 9478.715638530855, 9599.32804703664, 9828.0, 9831.164351029956, 9675.516224188621, 9988.500574971367, 9993.0, 10030.398176109456, 10113.29206955506, 6913.412359949461, 7287.890681639674, 7472.252774722484, 7542.509736867086, 7657.540547567164, 7018.0, 8256.422050456411, 8309.33525317961, 8332.250097491255, 8370.958145209235, 7304.342609165201, 7393.334599886036, 7482.775516734507, 7518.210587888193, 7553.0, 5975.25309336333, 6183.258009038765, 7317.231690672403, 7411.184769675224, 7455.7017719290625, 6103.572749907464, 6763.391294783493, 6819.965900170468, 6874.621895795579, 6984.930150698631, 4762.571368576845, 4782.832600858953, 4800.0, 4890.217565189552, 4916.827911023097, 4824.951750482591, 4826.565609095198, 4840.2255639097575, 4853.587445067209, 4909.140900342497], "value": 4592.895776831341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.5597299618511, 331.6070456509036, 334.89116037287886, 338.8695352289368, 339.77235252380905, 4722.17503821904, 5338.466153384659, 5431.375391829941, 5571.164325351194, 5571.331440227168, 4229.788510574466, 5456.672599644025, 5579.38626751057, 5596.468335508121, 5715.914261286073, 4202.159568086379, 4250.447441832557, 4264.808083636229, 4269.871903842874, 4270.615644591986, 4135.8833115058915, 4263.339182426729, 4276.208901353244, 4278.016056307051, 4324.956750432502, 3634.0, 4197.979010104945, 4211.663066954651, 4238.343056826181, 4251.980309353064, 3904.7071469639604, 4205.432266644025, 4233.343831706091, 4238.597333253336, 4240.533541310453, 3078.39971205615, 3714.0900479382453, 3715.5727091384565, 3740.251949610089, 3745.3632882410056, 4337.327714204303, 4402.449693788277, 5060.9493905060845, 5277.947220527784, 5350.197470379474, 4713.976430117862, 4889.584385327252, 4895.902081958377, 4900.975495122537, 4983.750812459362, 5176.818811341623, 5254.894902101974, 5904.61619994699, 5906.852328691774, 5937.643741375531, 4105.466289382391, 4109.732867363612, 4110.17796440713, 4123.299039163349, 4128.174365126986, 4958.752062396866, 5729.5702822288495, 6206.596571222856, 6284.434400903895, 6357.65032923189, 3716.535433070866, 3905.199434116056, 4172.499300083949, 4185.811638476276, 4189.183109293658, 3883.669888059547, 4265.7440553566885, 4266.935995960063, 4269.807858646368, 4279.957200428018, 4483.529229430928, 4542.0915816836105, 4911.263310503453, 4916.852494425173, 4922.310876477296, 4683.414573178353, 4915.95084049155, 4960.70235785854, 5566.16316615524, 5900.232969713909, 1334.1328136711215, 1406.6777229404531, 1520.5438368489408, 1561.3442354211259, 1569.858712715861, 936.5457752989795, 936.6113063078747, 1003.9508713394521, 1021.5300961557618, 1046.6336782126348, 4684.81260749573, 5766.769329226784, 5872.559558033165, 5878.941210587925, 5913.704314784243, 5629.24005259293, 5731.185803924724, 5879.3826648202175, 6203.534734894901, 6606.240282367493, 5320.270911953553, 5727.77088916447, 5859.53123750099, 5945.435183657545, 5958.165856780054, 2769.2384594236746, 3434.4504879219203, 3460.52927505809, 3473.0, 3473.7568370213844, 5895.55783316253, 6303.338149494329, 6735.932640673628, 6738.393544581011, 6958.582485050913, 5714.514266287412, 6465.0, 6766.0, 6816.580114059537, 6824.590524568443, 6058.7273572688455, 6498.415142637185, 6908.723651054002, 6918.446524278046, 6940.826479337956, 5932.673702946379, 6164.260288765377, 6169.198004259418, 6177.9691101546005, 6199.070139479114, 5114.64197506171, 5462.098753705753, 5760.539156867357, 5933.970330148321, 6122.877542449215, 5103.617228707936, 5117.15566931467, 6337.7148028337915, 6457.5479716419395, 6458.7093580789915, 3578.173243137892, 3794.734368594172, 3844.2503711775935, 4063.349864021742, 4082.632563069338, 4960.826371076995, 5616.634918730269, 5637.52081073097, 5834.795782147605, 6207.937920620736, 5140.511651393112, 5878.441548052928, 5906.379830117776, 5909.261342332208, 5918.875413671389, 5201.297824793604, 5908.3500814911185, 5922.0, 5949.821505354673, 6006.129111278928, 4738.028704115579, 4763.476017637988, 4979.402471703419, 5699.686517241592, 5834.533237340917, 5588.77644894216, 5664.320281566238, 5887.61730487517, 5935.465808077293, 5969.432903874125, 5124.41069277023, 5604.411536788579, 5833.183354330312, 5836.737346819488, 5853.03424934881], "value": 4353.077657833498, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.3561527510677, 717.2039036669292, 725.9491835571512, 730.2843213650622, 760.9885851712223, 13656.317184140791, 14025.43898244069, 14090.29548522575, 14092.22492762899, 14144.222067786268, 15056.774148387756, 15599.0, 15624.0, 15629.531114066596, 15650.843491565081, 750.9849803003937, 783.385042741448, 943.0428115462811, 953.918916892065, 954.265215783846, 914.2822884036033, 942.5240253671886, 959.6257459590765, 962.0187408842976, 964.7009427077593, 994.3089552760835, 994.3387647214594, 995.945223012734, 998.4608311511774, 1000.5597537083696, 970.3256236915347, 980.5244456438621, 990.6384169778052, 992.4640694025254, 992.6674564021025, 723.3417589993081, 725.7133432294214, 728.4391018915459, 743.4758495260828, 746.3693179263568, 15128.697426051418, 15148.924255378653, 15203.695926081527, 15296.923515382352, 15400.614984625361, 11194.888051119466, 11349.375784331858, 12278.938605307005, 12313.384330783425, 12541.247525148521, 9883.639562548768, 10148.289619726622, 10168.694939151837, 10234.437105959169, 10250.897491025069, 3420.572428446444, 3452.87914922979, 3495.6679115484244, 3501.509788629594, 3530.346885826105, 78282.21717782806, 78379.0, 79004.0, 79214.41571168602, 79857.0, 9467.857982130274, 9490.0, 9602.231821454267, 9621.374610650286, 9788.461634610023, 20974.89512552458, 21111.838848863154, 21171.47071323229, 21212.363629091153, 21245.256416025673, 6871.14110736158, 6873.005933744816, 7432.888506672404, 7487.363574096263, 7655.464117511832, 4072.6334629883454, 4115.855945041909, 4125.968507873032, 4131.710780245414, 4138.8344466221615, 3453.8445769940413, 3536.6816986470826, 3543.9013905689317, 3544.769589976643, 3551.94672079919, 625.0624063904144, 638.8211300835774, 641.9005054216603, 645.4868379638199, 648.6075924065966, 473.5785151215421, 486.8369096352691, 491.61162681481943, 502.8717676992417, 566.4448840136689, 7880.093789214199, 7974.880376794353, 8018.198180181935, 8037.638306276114, 8244.628991695388, 475.7050628610215, 507.97206153661165, 552.8700755322496, 575.0942265931092, 584.272580637101, 3571.375009373401, 3810.2950954073585, 3816.2367526494813, 3826.9043273918373, 3901.2197560488016, 99923.0, 101333.49333253434, 101975.98024019813, 104581.47709261558, 105906.47046764717, 59792.40207597955, 60104.39895600988, 77645.61177194078, 78593.0, 82038.0, 73171.63414182895, 74573.0, 74602.0, 74803.62598187083, 74932.42133245757, 7341.889871651822, 7394.445416593884, 7730.420218483749, 7772.883406748791, 8006.879896801669, 43995.78002109969, 44761.776191118835, 44952.42666506657, 45063.873403165184, 45737.77131114456, 58191.4180858186, 58402.7079864598, 60220.69889650524, 62261.68869155625, 62635.0, 10827.296225745302, 10856.436728918032, 10957.561697532328, 11177.85218130009, 11346.602868899548, 11613.419329033684, 12017.278963262232, 12046.397680115784, 12249.632511024683, 12305.384730763604, 10852.782944341227, 10895.945520272347, 10911.618093366695, 10922.945385273022, 10930.890691092987, 10394.636187733393, 10402.687919362432, 10497.2127090467, 10504.737381565523, 10646.361218326616, 9633.08485693858, 10435.0, 10547.419891906018, 10798.13614910821, 11193.832092518782, 9581.568829402555, 9673.854892176481, 9747.0, 10010.399376037461, 10105.494725263854, 9225.24857335089, 9560.378575392577, 9561.760955976157, 9582.775214565605, 9589.760255993657], "value": 6796.768132150713, "unit": "score"}], "name": "dromaeo_css", "value": 7284.755652043919, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 129.0, 127.0, 116.0, 130.0, 116.0, 115.0, 112.0, 138.0], "value": 130.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 149.0, 165.0, 123.0, 164.0, 142.0, 111.0, 163.0, 147.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 202.0, 191.0, 201.0, 200.0, 205.0, 200.0, 197.0, 197.0, 194.0], "value": 198.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 83.0, 76.0, 147.0, 58.0, 56.0, 55.0, 66.0, 123.0, 58.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 125.0, 75.0, 80.0, 81.0, 124.0, 84.0, 80.0, 121.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 85.0, 88.0, 86.0, 90.0, 86.0, 90.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 126.0, 108.0, 153.0, 133.0, 155.0, 94.0, 121.0, 103.0, 151.0], "value": 123.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 93.0, 115.0, 74.0, 142.0, 146.0, 141.0, 90.0, 91.0, 106.0], "value": 109.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 64.0, 66.0, 67.0, 66.0, 65.0, 81.0, 61.0, 61.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 77.0, 58.0, 69.0, 77.0, 116.0, 106.0, 67.0, 69.0, 69.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 82.0, 82.0, 83.0, 83.0, 79.0, 83.0, 85.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 163.0, 154.0, 161.0, 159.0, 161.0, 133.0, 135.0, 172.0, 154.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 184.0, 169.0, 178.0, 163.0, 168.0, 168.0, 227.0, 228.0, 167.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 58.0, 75.0, 98.0, 74.0, 70.0, 70.0, 67.0, 69.0, 71.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1598.9, "alertThreshold": 2.0}]} 05:38:39 INFO - Return code: 0 05:38:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:38:39 ERROR - # TBPL SUCCESS # 05:38:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:39 INFO - 2016-05-06 05:38:39.263 screenresolution[1964:13723] starting screenresolution argv=screenresolution get 05:38:39 INFO - 2016-05-06 05:38:39.266 screenresolution[1964:13723] Display 0: 1600x1200x32@60 05:38:39 INFO - 2016-05-06 05:38:39.272 screenresolution[1965:13726] starting screenresolution argv=screenresolution list 05:38:39 INFO - Available Modes on Display 0 05:38:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:39 INFO - Intel Iris: 05:38:39 INFO - Chipset Model: Intel Iris 05:38:39 INFO - Type: GPU 05:38:39 INFO - Bus: Built-In 05:38:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:39 INFO - Vendor: Intel (0x8086) 05:38:39 INFO - Device ID: 0x0a2e 05:38:39 INFO - Revision ID: 0x0009 05:38:39 INFO - Displays: 05:38:39 INFO - Display: 05:38:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:39 INFO - Display Serial Number: 1600x1200 60 05:38:39 INFO - Main Display: Yes 05:38:39 INFO - Mirror: Off 05:38:39 INFO - Online: Yes 05:38:39 INFO - Rotation: Supported 05:38:39 INFO - Return code: 0 05:38:39 INFO - Running post-action listener: _resource_record_post_action 05:38:39 INFO - Running post-run listener: _resource_record_post_run 05:38:40 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 253952; Write bytes: 654744576; Read time: 8; Write time: 1938 05:38:40 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346604032; Write bytes: 346437120; Read time: 26496; Write time: 677 05:38:40 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 253952; Write bytes: 298354176; Read time: 8; Write time: 1233 05:38:40 INFO - Running post-run listener: _upload_blobber_files 05:38:40 INFO - Blob upload gear active. 05:38:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:40 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:38:40 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:38:40 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:38:40 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:41 INFO - (blobuploader) - INFO - Done attempting. 05:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:38:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:42 INFO - (blobuploader) - INFO - Done attempting. 05:38:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:42 INFO - Return code: 0 05:38:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66ebb7b73116044f2be747fa966c493a9315f0797cb93bb5f063174e4eb672b9dc2172a9a393e059d369b154417a311eb1a56b6a6940290a5699d2b1c5ef2371", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/778f471e4401b8a61d0c6473f67d29ddef4277d14e34f18ad815923d3d3cb6ea71f5e625ddb661a9a73510dd30207af55d65c07bef6632606ee3522f5c63c6d0"} 05:38:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:42 INFO - Contents: 05:38:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66ebb7b73116044f2be747fa966c493a9315f0797cb93bb5f063174e4eb672b9dc2172a9a393e059d369b154417a311eb1a56b6a6940290a5699d2b1c5ef2371", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/778f471e4401b8a61d0c6473f67d29ddef4277d14e34f18ad815923d3d3cb6ea71f5e625ddb661a9a73510dd30207af55d65c07bef6632606ee3522f5c63c6d0"} 05:38:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:42 INFO - Copying logs to upload dir... 05:38:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.374862 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-06 05:38:42.677974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:38:42.681144) ========= 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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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/66ebb7b73116044f2be747fa966c493a9315f0797cb93bb5f063174e4eb672b9dc2172a9a393e059d369b154417a311eb1a56b6a6940290a5699d2b1c5ef2371", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/778f471e4401b8a61d0c6473f67d29ddef4277d14e34f18ad815923d3d3cb6ea71f5e625ddb661a9a73510dd30207af55d65c07bef6632606ee3522f5c63c6d0"} 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.011329 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/66ebb7b73116044f2be747fa966c493a9315f0797cb93bb5f063174e4eb672b9dc2172a9a393e059d369b154417a311eb1a56b6a6940290a5699d2b1c5ef2371", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/778f471e4401b8a61d0c6473f67d29ddef4277d14e34f18ad815923d3d3cb6ea71f5e625ddb661a9a73510dd30207af55d65c07bef6632606ee3522f5c63c6d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:38:42.708290) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:38:42.708597) ========= 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.E37ZyxGFNo/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.ebfwPL1FBV/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.005273 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:38:42.757339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:38:42.757621) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:38:42.757923) ========= ========= Total master_lag: 0.16 =========