builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0003 starttime: 1462476727.27 results: success (0) buildid: 20160505103448 builduid: dd35c8d42e6745669048b66fbea75044 revision: cb425f373a802dc57c8fefffad95487334467ec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.265804) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.266282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.266590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.284482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.284878) ========= 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.dQkBqOCPSb/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.2tWXImbQRW/Listeners TMPDIR=/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/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-05 12:32:07-- 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% 7.95M=0.001s 2016-05-05 12:32:07 (7.95 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095725 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.397441) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.397786) ========= 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.dQkBqOCPSb/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.2tWXImbQRW/Listeners TMPDIR=/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/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.020753 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:07.446236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 12:32:07.446611) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cb425f373a802dc57c8fefffad95487334467ec2 --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 cb425f373a802dc57c8fefffad95487334467ec2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dQkBqOCPSb/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.2tWXImbQRW/Listeners TMPDIR=/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/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-05 12:32:07,509 truncating revision to first 12 chars 2016-05-05 12:32:07,509 Setting DEBUG logging. 2016-05-05 12:32:07,509 attempt 1/10 2016-05-05 12:32:07,509 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cb425f373a80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 12:32:09,021 unpacking tar archive at: mozilla-aurora-cb425f373a80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.746849 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 12:32:09.209982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:09.210306) ========= script_repo_revision: cb425f373a802dc57c8fefffad95487334467ec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:09.210669) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:09.210924) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 12:32:09.224434) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-05 12:32:09.224813) ========= /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.dQkBqOCPSb/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.2tWXImbQRW/Listeners TMPDIR=/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:32:09 INFO - MultiFileLogger online at 20160505 12:32:09 in /builds/slave/test 12:32:09 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 12:32:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:32:09 INFO - {'append_to_log': False, 12:32:09 INFO - 'base_work_dir': '/builds/slave/test', 12:32:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:32:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:32:09 INFO - 'branch': 'Mozilla-Aurora', 12:32:09 INFO - 'buildbot_json_path': 'buildprops.json', 12:32:09 INFO - 'config_files': ('talos/mac_config.py',), 12:32:09 INFO - 'default_actions': ('clobber', 12:32:09 INFO - 'read-buildbot-config', 12:32:09 INFO - 'download-and-extract', 12:32:09 INFO - 'populate-webroot', 12:32:09 INFO - 'create-virtualenv', 12:32:09 INFO - 'install', 12:32:09 INFO - 'run-tests'), 12:32:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:32:09 INFO - 'download_minidump_stackwalk': True, 12:32:09 INFO - 'download_symbols': 'ondemand', 12:32:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:32:09 INFO - 'tooltool.py': '/tools/tooltool.py', 12:32:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:32:09 INFO - '/tools/misc-python/virtualenv.py')}, 12:32:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:32:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:32:09 INFO - 'installer_path': 'installer.exe', 12:32:09 INFO - 'log_level': 'info', 12:32:09 INFO - 'log_name': 'talos', 12:32:09 INFO - 'log_to_console': True, 12:32:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:32:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:32:09 INFO - 'opt_config_files': (), 12:32:09 INFO - 'pip_index': False, 12:32:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:32:09 INFO - 'cmd': ('bash', 12:32:09 INFO - '-c', 12:32:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:32:09 INFO - 'enabled': True, 12:32:09 INFO - 'halt_on_failure': False, 12:32:09 INFO - 'name': 'check_screen_resolution'},), 12:32:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:32:09 INFO - 'cmd': ('bash', 12:32:09 INFO - '-c', 12:32:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:32:09 INFO - 'enabled': True, 12:32:09 INFO - 'halt_on_failure': False, 12:32:09 INFO - 'name': 'check_screen_resolution'},), 12:32:09 INFO - 'run_cmd_checks_enabled': True, 12:32:09 INFO - 'sps_profile': False, 12:32:09 INFO - 'sps_profile_interval': 0, 12:32:09 INFO - 'suite': 'dromaeojs-e10s', 12:32:09 INFO - 'system_bits': '32', 12:32:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:32:09 INFO - 'title': 't-yosemite-r7-0003', 12:32:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:32:09 INFO - 'use_talos_json': True, 12:32:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:32:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:32:09 INFO - 'work_dir': 'build'} 12:32:09 INFO - ##### 12:32:09 INFO - ##### Running clobber step. 12:32:09 INFO - ##### 12:32:09 INFO - Running pre-action listener: _resource_record_pre_action 12:32:09 INFO - Running main action method: clobber 12:32:09 INFO - rmtree: /builds/slave/test/build 12:32:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:32:10 INFO - Running post-action listener: _resource_record_post_action 12:32:10 INFO - ##### 12:32:10 INFO - ##### Running read-buildbot-config step. 12:32:10 INFO - ##### 12:32:10 INFO - Running pre-action listener: _resource_record_pre_action 12:32:10 INFO - Running main action method: read_buildbot_config 12:32:10 INFO - Using buildbot properties: 12:32:10 INFO - { 12:32:10 INFO - "project": "", 12:32:10 INFO - "product": "firefox", 12:32:10 INFO - "script_repo_revision": "production", 12:32:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:32:10 INFO - "repository": "", 12:32:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:32:10 INFO - "buildid": "20160505103448", 12:32:10 INFO - "pgo_build": "False", 12:32:10 INFO - "basedir": "/builds/slave/test", 12:32:10 INFO - "buildnumber": 0, 12:32:10 INFO - "slavename": "t-yosemite-r7-0003", 12:32:10 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:32:10 INFO - "platform": "yosemite_r7", 12:32:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:32:10 INFO - "branch": "mozilla-aurora", 12:32:10 INFO - "repo_path": "releases/mozilla-aurora", 12:32:10 INFO - "revision": "cb425f373a802dc57c8fefffad95487334467ec2", 12:32:10 INFO - "stage_platform": "macosx64", 12:32:10 INFO - "builduid": "dd35c8d42e6745669048b66fbea75044", 12:32:10 INFO - "slavebuilddir": "test" 12:32:10 INFO - } 12:32:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:32:10 INFO - Running post-action listener: _resource_record_post_action 12:32:10 INFO - ##### 12:32:10 INFO - ##### Running download-and-extract step. 12:32:10 INFO - ##### 12:32:10 INFO - Running pre-action listener: _resource_record_pre_action 12:32:10 INFO - Running main action method: download_and_extract 12:32:10 INFO - mkdir: /builds/slave/test/build/tests 12:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:10 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:32:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:32:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:32:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:32:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:32:12 INFO - Downloaded 1199 bytes. 12:32:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:32:12 INFO - Using the following test package requirements: 12:32:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:32:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:32:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:32:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'jsshell-mac.zip'], 12:32:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:32:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:32:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:32:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:32:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:32:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:32:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:32:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:12 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:32:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:32:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:32:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:32:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:32:13 INFO - Downloaded 18114337 bytes. 12:32:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:32:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:32:16 INFO - Return code: 0 12:32:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:16 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:32:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:32:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:32:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:32:17 INFO - Downloaded 18114337 bytes. 12:32:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:32:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:32:21 INFO - Return code: 0 12:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:21 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:32:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:32:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:32:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:32:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:32:22 INFO - Downloaded 10840145 bytes. 12:32:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:32:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:32:22 INFO - Return code: 0 12:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:22 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:32:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:32:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:32:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 12:32:25 INFO - Downloaded 114100985 bytes. 12:32:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:32:25 INFO - mkdir: /builds/slave/test/properties 12:32:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:32:25 INFO - Writing to file /builds/slave/test/properties/build_url 12:32:25 INFO - Contents: 12:32:25 INFO - build_url:https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:32:26 INFO - Running post-action listener: _resource_record_post_action 12:32:26 INFO - Running post-action listener: set_extra_try_arguments 12:32:26 INFO - ##### 12:32:26 INFO - ##### Running populate-webroot step. 12:32:26 INFO - ##### 12:32:26 INFO - Running pre-action listener: _resource_record_pre_action 12:32:26 INFO - Running main action method: populate_webroot 12:32:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:32:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:32:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:32:26 INFO - '--tppagecycles', 12:32:26 INFO - '7'], 12:32:26 INFO - 'tests': ['tsvgm']}}, 12:32:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:32:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:32:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:32:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:32:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:32:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:32:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['damp', 'tps']}, 12:32:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'tests': ['damp', 'tps']}, 12:32:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['dromaeo_dom']}, 12:32:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:32:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['a11yr', 12:32:26 INFO - 'ts_paint', 12:32:26 INFO - 'tpaint', 12:32:26 INFO - 'sessionrestore', 12:32:26 INFO - 'sessionrestore_no_auto_restore', 12:32:26 INFO - 'tabpaint']}, 12:32:26 INFO - 'other-e10s': {'tests': ['a11yr', 12:32:26 INFO - 'ts_paint', 12:32:26 INFO - 'tpaint', 12:32:26 INFO - 'sessionrestore', 12:32:26 INFO - 'sessionrestore_no_auto_restore', 12:32:26 INFO - 'tabpaint']}, 12:32:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:32:26 INFO - 'ts_paint', 12:32:26 INFO - 'tpaint', 12:32:26 INFO - 'sessionrestore', 12:32:26 INFO - 'sessionrestore_no_auto_restore', 12:32:26 INFO - 'tabpaint']}, 12:32:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:32:26 INFO - 'ts_paint', 12:32:26 INFO - 'tpaint', 12:32:26 INFO - 'sessionrestore', 12:32:26 INFO - 'sessionrestore_no_auto_restore', 12:32:26 INFO - 'tabpaint']}, 12:32:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['a11yr', 12:32:26 INFO - 'ts_paint', 12:32:26 INFO - 'tpaint', 12:32:26 INFO - 'sessionrestore', 12:32:26 INFO - 'sessionrestore_no_auto_restore', 12:32:26 INFO - 'tabpaint']}, 12:32:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['a11yr', 12:32:26 INFO - 'ts_paint', 12:32:26 INFO - 'tpaint', 12:32:26 INFO - 'sessionrestore', 12:32:26 INFO - 'sessionrestore_no_auto_restore', 12:32:26 INFO - 'tabpaint']}, 12:32:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['tsvgx', 12:32:26 INFO - 'tsvgr_opacity', 12:32:26 INFO - 'tart', 12:32:26 INFO - 'tscrollx', 12:32:26 INFO - 'cart']}, 12:32:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:32:26 INFO - 'tsvgr_opacity', 12:32:26 INFO - 'tart', 12:32:26 INFO - 'tscrollx', 12:32:26 INFO - 'cart']}, 12:32:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'talos_options': ['--disable-e10s'], 12:32:26 INFO - 'tests': ['tp5o']}, 12:32:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'tests': ['tp5o']}, 12:32:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'talos_options': ['--disable-e10s', 12:32:26 INFO - '--xperf_path', 12:32:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:32:26 INFO - 'tests': ['tp5n']}, 12:32:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:26 INFO - 'talos_options': ['--xperf_path', 12:32:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:32:26 INFO - 'tests': ['tp5n']}}, 12:32:26 INFO - 'talos.zip': {'path': '', 12:32:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:32:26 INFO - Running post-action listener: _resource_record_post_action 12:32:26 INFO - ##### 12:32:26 INFO - ##### Running create-virtualenv step. 12:32:26 INFO - ##### 12:32:26 INFO - Running pre-action listener: _resource_record_pre_action 12:32:26 INFO - Running main action method: create_virtualenv 12:32:26 INFO - Creating virtualenv /builds/slave/test/build/venv 12:32:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:32:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:32:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:32:26 INFO - Using real prefix '/tools/python27' 12:32:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:32:27 INFO - Installing distribute.............................................................................................................................................................................................done. 12:32:30 INFO - Installing pip.................done. 12:32:30 INFO - Return code: 0 12:32:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:30 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:32:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:30 INFO - 'HOME': '/Users/cltbld', 12:32:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:30 INFO - 'LOGNAME': 'cltbld', 12:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:30 INFO - 'MOZ_NO_REMOTE': '1', 12:32:30 INFO - 'NO_EM_RESTART': '1', 12:32:30 INFO - 'PAGER': '/bin/cat', 12:32:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:30 INFO - 'PWD': '/builds/slave/test', 12:32:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:30 INFO - 'SHELL': '/bin/bash', 12:32:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:30 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:30 INFO - 'USER': 'cltbld', 12:32:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:30 INFO - 'XPC_FLAGS': '0x0', 12:32:30 INFO - 'XPC_SERVICE_NAME': '0', 12:32:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:32:30 INFO - Cleaning up... 12:32:30 INFO - Return code: 0 12:32:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:30 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:32:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:30 INFO - 'HOME': '/Users/cltbld', 12:32:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:30 INFO - 'LOGNAME': 'cltbld', 12:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:30 INFO - 'MOZ_NO_REMOTE': '1', 12:32:30 INFO - 'NO_EM_RESTART': '1', 12:32:30 INFO - 'PAGER': '/bin/cat', 12:32:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:30 INFO - 'PWD': '/builds/slave/test', 12:32:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:30 INFO - 'SHELL': '/bin/bash', 12:32:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:30 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:30 INFO - 'USER': 'cltbld', 12:32:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:30 INFO - 'XPC_FLAGS': '0x0', 12:32:30 INFO - 'XPC_SERVICE_NAME': '0', 12:32:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:31 INFO - Downloading/unpacking psutil>=0.7.1 12:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:32:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:34 INFO - Installing collected packages: psutil 12:32:34 INFO - Running setup.py install for psutil 12:32:34 INFO - building 'psutil._psutil_osx' extension 12:32:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:32:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:32:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:32:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:32:34 INFO - building 'psutil._psutil_posix' extension 12:32:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:32:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:32:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:32:34 INFO - ^ 12:32:34 INFO - 1 warning generated. 12:32:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:32:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:34 INFO - Successfully installed psutil 12:32:34 INFO - Cleaning up... 12:32:34 INFO - Return code: 0 12:32:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:34 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:34 INFO - 'HOME': '/Users/cltbld', 12:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:34 INFO - 'LOGNAME': 'cltbld', 12:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:34 INFO - 'MOZ_NO_REMOTE': '1', 12:32:34 INFO - 'NO_EM_RESTART': '1', 12:32:34 INFO - 'PAGER': '/bin/cat', 12:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:34 INFO - 'PWD': '/builds/slave/test', 12:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:34 INFO - 'SHELL': '/bin/bash', 12:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:34 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:34 INFO - 'USER': 'cltbld', 12:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:34 INFO - 'XPC_FLAGS': '0x0', 12:32:34 INFO - 'XPC_SERVICE_NAME': '0', 12:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:32:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:32:37 INFO - Installing collected packages: mozsystemmonitor 12:32:37 INFO - Running setup.py install for mozsystemmonitor 12:32:37 INFO - Successfully installed mozsystemmonitor 12:32:37 INFO - Cleaning up... 12:32:37 INFO - Return code: 0 12:32:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:37 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:37 INFO - 'HOME': '/Users/cltbld', 12:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:37 INFO - 'LOGNAME': 'cltbld', 12:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:37 INFO - 'MOZ_NO_REMOTE': '1', 12:32:37 INFO - 'NO_EM_RESTART': '1', 12:32:37 INFO - 'PAGER': '/bin/cat', 12:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:37 INFO - 'PWD': '/builds/slave/test', 12:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:37 INFO - 'SHELL': '/bin/bash', 12:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:37 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:37 INFO - 'USER': 'cltbld', 12:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:37 INFO - 'XPC_FLAGS': '0x0', 12:32:37 INFO - 'XPC_SERVICE_NAME': '0', 12:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:38 INFO - Downloading/unpacking blobuploader==1.2.4 12:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:40 INFO - Downloading blobuploader-1.2.4.tar.gz 12:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:32:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:32:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:41 INFO - Downloading docopt-0.6.1.tar.gz 12:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:32:41 INFO - Installing collected packages: blobuploader, requests, docopt 12:32:41 INFO - Running setup.py install for blobuploader 12:32:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:32:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:32:42 INFO - Running setup.py install for requests 12:32:42 INFO - Running setup.py install for docopt 12:32:42 INFO - Successfully installed blobuploader requests docopt 12:32:42 INFO - Cleaning up... 12:32:42 INFO - Return code: 0 12:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:42 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:42 INFO - 'HOME': '/Users/cltbld', 12:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:42 INFO - 'LOGNAME': 'cltbld', 12:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:42 INFO - 'MOZ_NO_REMOTE': '1', 12:32:42 INFO - 'NO_EM_RESTART': '1', 12:32:42 INFO - 'PAGER': '/bin/cat', 12:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:42 INFO - 'PWD': '/builds/slave/test', 12:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:42 INFO - 'SHELL': '/bin/bash', 12:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:42 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:42 INFO - 'USER': 'cltbld', 12:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:42 INFO - 'XPC_FLAGS': '0x0', 12:32:42 INFO - 'XPC_SERVICE_NAME': '0', 12:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-DfyvrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-Uh24gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-8_CiQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-S2BXLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-JhIWoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-TOA59B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-zIr20f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-l5Y6K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:43 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-4RMEX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-FdPutW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-M3FpoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-mIgKkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-yNwO_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-rKnROT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-_pBKWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-kvuO5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:44 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-xQm4Ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:32:44 INFO - Running setup.py install for manifestparser 12:32:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:32:44 INFO - Running setup.py install for mozcrash 12:32:45 INFO - Running setup.py install for mozdebug 12:32:45 INFO - Running setup.py install for mozdevice 12:32:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Running setup.py install for mozfile 12:32:45 INFO - Running setup.py install for mozhttpd 12:32:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Running setup.py install for mozinfo 12:32:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Running setup.py install for mozInstall 12:32:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:32:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Running setup.py install for mozleak 12:32:46 INFO - Running setup.py install for mozlog 12:32:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Running setup.py install for moznetwork 12:32:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Running setup.py install for mozprocess 12:32:46 INFO - Running setup.py install for mozprofile 12:32:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Running setup.py install for mozrunner 12:32:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:32:46 INFO - Running setup.py install for mozscreenshot 12:32:47 INFO - Running setup.py install for moztest 12:32:47 INFO - Running setup.py install for mozversion 12:32:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:32:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:32:47 INFO - Cleaning up... 12:32:47 INFO - Return code: 0 12:32:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:47 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:47 INFO - 'HOME': '/Users/cltbld', 12:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:47 INFO - 'LOGNAME': 'cltbld', 12:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:47 INFO - 'MOZ_NO_REMOTE': '1', 12:32:47 INFO - 'NO_EM_RESTART': '1', 12:32:47 INFO - 'PAGER': '/bin/cat', 12:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:47 INFO - 'PWD': '/builds/slave/test', 12:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:47 INFO - 'SHELL': '/bin/bash', 12:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:47 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:47 INFO - 'USER': 'cltbld', 12:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:47 INFO - 'XPC_FLAGS': '0x0', 12:32:47 INFO - 'XPC_SERVICE_NAME': '0', 12:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:47 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-BKA92M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:47 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-40KehD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:47 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-gw8mFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-zzKWcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-KZahy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-Kl0H5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-VDVtWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-LrCNTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-6wK3Qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-LPdcgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-AF0aLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-tCRETH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:48 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-AXF9g_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:49 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-7e28Hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:49 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-ffgABq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:49 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-YQmB41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:49 INFO - Running setup.py (path:/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/pip-ABXvBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:32:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:51 INFO - Downloading blessings-1.6.tar.gz 12:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:32:52 INFO - Installing collected packages: blessings 12:32:52 INFO - Running setup.py install for blessings 12:32:52 INFO - Successfully installed blessings 12:32:52 INFO - Cleaning up... 12:32:52 INFO - Return code: 0 12:32:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:32:52 INFO - Reading from file tmpfile_stdout 12:32:52 INFO - Current package versions: 12:32:52 INFO - blessings == 1.6 12:32:52 INFO - blobuploader == 1.2.4 12:32:52 INFO - docopt == 0.6.1 12:32:52 INFO - manifestparser == 1.1 12:32:52 INFO - mozInstall == 1.12 12:32:52 INFO - mozcrash == 0.17 12:32:52 INFO - mozdebug == 0.1 12:32:52 INFO - mozdevice == 0.48 12:32:52 INFO - mozfile == 1.2 12:32:52 INFO - mozhttpd == 0.7 12:32:52 INFO - mozinfo == 0.9 12:32:52 INFO - mozleak == 0.1 12:32:52 INFO - mozlog == 3.1 12:32:52 INFO - moznetwork == 0.27 12:32:52 INFO - mozprocess == 0.22 12:32:52 INFO - mozprofile == 0.28 12:32:52 INFO - mozrunner == 6.11 12:32:52 INFO - mozscreenshot == 0.1 12:32:52 INFO - mozsystemmonitor == 0.0 12:32:52 INFO - moztest == 0.7 12:32:52 INFO - mozversion == 1.4 12:32:52 INFO - psutil == 3.1.1 12:32:52 INFO - requests == 1.2.3 12:32:52 INFO - wsgiref == 0.1.2 12:32:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:52 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:52 INFO - 'HOME': '/Users/cltbld', 12:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:52 INFO - 'LOGNAME': 'cltbld', 12:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:52 INFO - 'MOZ_NO_REMOTE': '1', 12:32:52 INFO - 'NO_EM_RESTART': '1', 12:32:52 INFO - 'PAGER': '/bin/cat', 12:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:52 INFO - 'PWD': '/builds/slave/test', 12:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:52 INFO - 'SHELL': '/bin/bash', 12:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:52 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:52 INFO - 'USER': 'cltbld', 12:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:52 INFO - 'XPC_FLAGS': '0x0', 12:32:52 INFO - 'XPC_SERVICE_NAME': '0', 12:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:32:52 INFO - Cleaning up... 12:32:52 INFO - Return code: 0 12:32:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:52 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:52 INFO - 'HOME': '/Users/cltbld', 12:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:52 INFO - 'LOGNAME': 'cltbld', 12:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:52 INFO - 'MOZ_NO_REMOTE': '1', 12:32:52 INFO - 'NO_EM_RESTART': '1', 12:32:52 INFO - 'PAGER': '/bin/cat', 12:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:52 INFO - 'PWD': '/builds/slave/test', 12:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:52 INFO - 'SHELL': '/bin/bash', 12:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:52 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:52 INFO - 'USER': 'cltbld', 12:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:52 INFO - 'XPC_FLAGS': '0x0', 12:32:52 INFO - 'XPC_SERVICE_NAME': '0', 12:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:53 INFO - Downloading/unpacking jsonschema 12:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:32:55 INFO - Downloading/unpacking functools32 (from jsonschema) 12:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:56 INFO - Downloading functools32-3.2.3-2.tar.gz 12:32:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:32:56 INFO - warning: no files found matching '*.txt' 12:32:56 INFO - no previously-included directories found matching 'build' 12:32:56 INFO - no previously-included directories found matching 'dist' 12:32:56 INFO - no previously-included directories found matching '.git*' 12:32:56 INFO - Installing collected packages: jsonschema, functools32 12:32:56 INFO - Running setup.py install for functools32 12:32:56 INFO - warning: no files found matching '*.txt' 12:32:56 INFO - no previously-included directories found matching 'build' 12:32:56 INFO - no previously-included directories found matching 'dist' 12:32:56 INFO - no previously-included directories found matching '.git*' 12:32:56 INFO - Successfully installed jsonschema functools32 12:32:56 INFO - Cleaning up... 12:32:56 INFO - Return code: 0 12:32:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:32: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')]} 12:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32: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')]} 12:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:56 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 0x10ed80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3348d6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, '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.dQkBqOCPSb/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.2tWXImbQRW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:56 INFO - 'HOME': '/Users/cltbld', 12:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:56 INFO - 'LOGNAME': 'cltbld', 12:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:56 INFO - 'MOZ_NO_REMOTE': '1', 12:32:56 INFO - 'NO_EM_RESTART': '1', 12:32:56 INFO - 'PAGER': '/bin/cat', 12:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:56 INFO - 'PWD': '/builds/slave/test', 12:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:56 INFO - 'SHELL': '/bin/bash', 12:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:32:56 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:56 INFO - 'USER': 'cltbld', 12:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:56 INFO - 'XPC_FLAGS': '0x0', 12:32:56 INFO - 'XPC_SERVICE_NAME': '0', 12:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:56 INFO - Downloading/unpacking flake8 12:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:32:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:32:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:33:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:33:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:33:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:33:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:33:00 INFO - no previously-included directories found matching 'docs/_build' 12:33:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:33:00 INFO - Running setup.py install for pep8 12:33:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:33:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:33:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:33:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:33:00 INFO - no previously-included directories found matching 'docs/_build' 12:33:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:33:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:33:00 INFO - Cleaning up... 12:33:00 INFO - Return code: 0 12:33:00 INFO - Running post-action listener: _resource_record_post_action 12:33:00 INFO - Running post-action listener: _start_resource_monitoring 12:33:00 INFO - Starting resource monitoring. 12:33:00 INFO - ##### 12:33:00 INFO - ##### Running install step. 12:33:00 INFO - ##### 12:33:00 INFO - Running pre-action listener: _resource_record_pre_action 12:33:00 INFO - Running main action method: install 12:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:33:01 INFO - Reading from file tmpfile_stdout 12:33:01 INFO - Detecting whether we're running mozinstall >=1.0... 12:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:33:01 INFO - Reading from file tmpfile_stdout 12:33:01 INFO - Output received: 12:33:01 INFO - Usage: mozinstall [options] installer 12:33:01 INFO - Options: 12:33:01 INFO - -h, --help show this help message and exit 12:33:01 INFO - -d DEST, --destination=DEST 12:33:01 INFO - Directory to install application into. [default: 12:33:01 INFO - "/builds/slave/test"] 12:33:01 INFO - --app=APP Application being installed. [default: firefox] 12:33:01 INFO - mkdir: /builds/slave/test/build/application 12:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:33:33 INFO - Reading from file tmpfile_stdout 12:33:33 INFO - Output received: 12:33:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:33:33 INFO - Running post-action listener: _resource_record_post_action 12:33:33 INFO - ##### 12:33:33 INFO - ##### Running run-tests step. 12:33:33 INFO - ##### 12:33:33 INFO - Running pre-action listener: _resource_record_pre_action 12:33:33 INFO - Running main action method: run_tests 12:33:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:33:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:33:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:33:33 INFO - 2016-05-05 12:33:33.317 screenresolution[2483:25120] starting screenresolution argv=screenresolution get 12:33:33 INFO - 2016-05-05 12:33:33.320 screenresolution[2483:25120] Display 0: 1600x1200x32@60 12:33:33 INFO - 2016-05-05 12:33:33.326 screenresolution[2484:25123] starting screenresolution argv=screenresolution list 12:33:33 INFO - Available Modes on Display 0 12:33:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:33:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:33:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:33:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:33:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:33:33 INFO - Intel Iris: 12:33:33 INFO - Chipset Model: Intel Iris 12:33:33 INFO - Type: GPU 12:33:33 INFO - Bus: Built-In 12:33:33 INFO - VRAM (Dynamic, Max): 1536 MB 12:33:33 INFO - Vendor: Intel (0x8086) 12:33:33 INFO - Device ID: 0x0a2e 12:33:33 INFO - Revision ID: 0x0009 12:33:33 INFO - Displays: 12:33:33 INFO - Display: 12:33:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:33:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:33:33 INFO - Display Serial Number: 1600x1200 60 12:33:33 INFO - Main Display: Yes 12:33:33 INFO - Mirror: Off 12:33:33 INFO - Online: Yes 12:33:33 INFO - Rotation: Supported 12:33:33 INFO - Return code: 0 12:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:33:33 INFO - Python 2.7.3 12:33:33 INFO - Return code: 0 12:33:33 INFO - grabbing minidump binary from tooltool 12:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:33:33 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 0x10ec1fe00>, '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 0x10ea88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec1ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:33:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:33:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:33:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:33:33 INFO - Return code: 0 12:33:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:33:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:33:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:33:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:33:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:33:34 INFO - Return code: 0 12:33:34 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/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 12:33:34 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/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0003 --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 12:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dQkBqOCPSb/Render', 12:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:34 INFO - 'HOME': '/Users/cltbld', 12:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:34 INFO - 'LOGNAME': 'cltbld', 12:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:34 INFO - 'MOZ_NO_REMOTE': '1', 12:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:33:34 INFO - 'NO_EM_RESTART': '1', 12:33:34 INFO - 'PAGER': '/bin/cat', 12:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:34 INFO - 'PWD': '/builds/slave/test', 12:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:34 INFO - 'SHELL': '/bin/bash', 12:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tWXImbQRW/Listeners', 12:33:34 INFO - 'TMPDIR': '/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/', 12:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:34 INFO - 'USER': 'cltbld', 12:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:34 INFO - 'XPC_FLAGS': '0x0', 12:33:34 INFO - 'XPC_SERVICE_NAME': '0', 12:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:34 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/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 12:33:34 INFO - mozversion application_buildid: 20160505103448 12:33:34 INFO - mozversion application_changeset: cb425f373a802dc57c8fefffad95487334467ec2 12:33:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:33:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:33:34 INFO - mozversion application_name: Firefox 12:33:34 INFO - mozversion application_remotingname: firefox-dev 12:33:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:33:34 INFO - mozversion application_vendor: Mozilla 12:33:34 INFO - mozversion application_version: 48.0a2 12:33:34 INFO - mozversion platform_buildid: 20160505103448 12:33:34 INFO - mozversion platform_changeset: cb425f373a802dc57c8fefffad95487334467ec2 12:33:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:33:34 INFO - mozversion platform_version: 48.0a2 12:33:34 INFO - using testdate: 1462476814 12:33:34 INFO - actual date: 1462476814 12:33:34 INFO - starting webserver on 'localhost:55828' 12:33:34 INFO - SUITE-START | Running 2 tests 12:33:34 INFO - TEST-START | dromaeo_css 12:33:34 INFO - operating with platform_type : mac_ 12:33:34 INFO - Initialising browser for dromaeo_css test... 12:33:34 INFO - TEST-INFO | started process 2502 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/tmpLSlEzz/profile http://localhost:55828/getInfo.html) 12:33:37 INFO - PROCESS | 2502 | __metrics Screen width/height:1600/1200 12:33:37 INFO - PROCESS | 2502 | colorDepth:24 12:33:37 INFO - PROCESS | 2502 | Browser inner width/height: 1024/689 12:33:37 INFO - PROCESS | 2502 | __metrics 12:33:37 ERROR - PROCESS | 2502 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 12:33:38 INFO - TEST-INFO | 2502: exit 0 12:33:38 INFO - Browser initialized. 12:33:38 INFO - Running cycle 1/1 for dromaeo_css test... 12:33:38 INFO - TEST-INFO | started process 2505 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/tmpLSlEzz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:50 INFO - PROCESS | 2505 | RSS: Main: 221663232 12:33:50 INFO - PROCESS | 2505 | 12:34:40 INFO - PROCESS | 2505 | 1462476880445 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:36:51 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:55828/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55828/tests/dromaeo/cssquery-ext.html) 12:36:53 INFO - PROCESS | 2505 | RSS: Main: 232046592 12:36:53 INFO - PROCESS | 2505 | 12:39:55 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:55828/tests/dromaeo/cssquery-ext.html (next: http://localhost:55828/tests/dromaeo/cssquery-jquery.html) 12:39:55 INFO - PROCESS | 2505 | RSS: Main: 243617792 12:39:55 INFO - PROCESS | 2505 | 12:42:57 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:55828/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55828/tests/dromaeo/cssquery-mootools.html) 12:42:58 INFO - PROCESS | 2505 | RSS: Main: 245428224 12:42:58 INFO - PROCESS | 2505 | 12:45:59 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:55828/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55828/tests/dromaeo/cssquery-prototype.html) 12:46:00 INFO - PROCESS | 2505 | RSS: Main: 246992896 12:46:00 INFO - PROCESS | 2505 | 12:49:03 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:55828/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55828/tests/dromaeo/cssquery-yui.html) 12:49:03 INFO - PROCESS | 2505 | RSS: Main: 250408960 12:49:03 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | Cycle 1(1): loaded http://localhost:55828/tests/dromaeo/cssquery-yui.html (next: http://localhost:55828/tests/dromaeo/cssquery-dojo.html) 12:52:06 INFO - PROCESS | 2505 | RSS: Main: 251097088 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | __start_tp_report 12:52:06 INFO - PROCESS | 2505 | _x_x_mozilla_page_load 12:52:06 INFO - PROCESS | 2505 | _x_x_mozilla_page_load_details 12:52:06 INFO - PROCESS | 2505 | |i|pagename|runs| 12:52:06 INFO - PROCESS | 2505 | |0;dojo.html;3369.4777309517026;3451.7066049385803;3461.619221885592;3508.6140524542297;3525.612182659907;5761.596688231825;5772.307323121221;5814.098814683725;5819.621724587904;5863.648181109136;5801.361850196475;5812.912806307909;5854.20968169297;5871.148683440899;5879.67661778602;5257.158854583267;5271.868203294929;5357.089294819871;5365.7853685852715;5398.8920221595545;5208.47915208478;5287.68273903567;5330.760115794798;5339.6262261641705;5481.588880833933;5277.525022747952;5298.14564902283;5302.840914772563;5321.840344789663;5327.786888524454;5256.947430525704;5293.867653308679;5312.946870531323;5327.786888524454;5330.173823057432;5263.36839579253;5285.127953887604;5321.068812957717;5347.732613369316;5416.133418653036;5740.138979153118;5752.626079304831;5761.827145185651;5811.27359080115;5813.622114562582;6125.050617154348;6132.754689812402;6174.289956654952;6275.09011193379;6306.400891915261;5230.947690523084;6212.161358216628;6301.905471417932;6308.842278943017;6319.526035547352;5900.144479050514;5937.643741375531;5948.31594366649;5967.5524335675;5995.640261584319;6175.166352542439;6211.782587609502;6330.968345158337;6351.745930162834;6397.424231819065;5688.943110568925;6160.1375807387;6246.562740608205;6256.186695729526;6272.655003974825;5307.946920530822;6144.016957286902;6315.684215789192;6333.904991425133;6342.560207854521;6100.389961003864;6195.876082478415;6254.061890716429;6264.9373506265265;6274.6548939808745;6156.476699480594;6171.5988460750805;6181.907271390933;6189.8452538686;6191.566590338633;4071.592840715905;4214.620684138442;5008.774605142778;5010.574101201439;5058.54473097423;4410.779461026936;4459.821607135743;4485.887852803706;4516.525764794715;4524.02733412318;5848.707564621752;5857.677827719431;5893.823185304448;5898.321693005273;5917.46742793142;6268.811935641938;6271.592346497463;6285.214348206474;6290.370962903765;6310.463610593151;4356.324769660708;5645.125005624135;5728.369879313273;5839.124131380242;5924.881502369928;4034.596540345942;4584.404027476407;5318.016167009116;5328.440513746078;5415.620906536505;5416.864578385572;6227.50179985591;6227.937720622826;6329.2404911409085;6351.4283714465;5252.579793616578;5777.162311464898;5816.854578635568;6213.285472170758;6302.779402720883;6360.300366959723;6367;6396.456301214449;6398.264199617103;6420.711068002044;5534.225208470737;6113;6202.069689546423;6234.875302493834;6277.968610157102;6128.203333566607;6217.533684973555;6292.716827742671;6300.432961033529;6305.148804911461;6142.877142457037;6168.537359698131;6180.196574445294;6185.443310102112;6194.628322300677;6039.849003774941;6093.055576385667;6105.9694701526205;6158.938410615837;6183.443490085914;5507.512971497753;5886.558508111823;5994;6034.064719968412;6078.787242446493;6123.84690382744;6186.10301506288;6188.535859810442;6203.844903877439;6210.534209934182;5889.705514724333;5981.491573216152;6062.090686397076;6068.544859135671;6122.785702500391 12:52:06 INFO - PROCESS | 2505 | |1;ext.html;514.6397521734784;551.3301338873268;558.4280341787062;560.7672815781451;604.1240201707525;38166.61833381669;39491.60508394919;39565.80217098918;39619.60380396195;39631;33969.66030339696;40892.59107408925;41652.16695666085;41653.7917310413;41847.16305673885;9334.113259240396;9577.904220957771;9592.040795920422;9611.711648650551;9614.230861531096;9435.198008169315;9672.419654820733;9702.466364349957;9724.756881077994;9766.9511652442;9661.90338096617;9733.707988760312;9743.415395076283;9751.219902407825;9768.120869121778;9628.855567166498;9642.855357169648;9652.034796520291;9707.61169553217;9734.951325243364;9505.477198754066;9624.230061595055;9631.229501639851;9649.372790768577;9658.420494770337;39331.803340983395;39985.80042598727;39994.400083998764;40064.799676001436;40073;3122.8750849965973;3371.645977172386;3474.3919814032442;3682.558093028827;3726.31063253299;26979;27778.305542361402;28851.27871803201;28906.27734306638;29005;2734.767544758698;2752.3119220194953;2766.294594878313;2781.123945957017;2814.3949050954143;518466;519117;521897;522674.3866280682;523859;34312.97061088171;62019.75960480765;62525;62537.74924501484;62570;31616.683833161373;35739.10652233715;35906;36022.819885900404;36205.7327993519;15272.770908436383;15676.372945082298;15685.215739212994;15693.921530392276;15701.293441795147;12913.225206487641;13138.211707297593;13158.473661053451;13175.736485270432;13217.801732974012;8071.071826739882;8195;8198.303144232688;8237.629306681214;8302.335813134934;3386.407378708716;3400.1329660936303;3428.9657103429145;3435.1927297085167;3458.412069948115;964.62379671928;1041.620121439609;1047.643801107627;1072.4744875011345;1174.6945794093597;29661.85169074141;30338.765757271845;30457.39085218283;30922.381552369276;30986.690133098833;1110.3337997201616;1122.5509796081633;1122.8989390954853;1136.4885801389407;1249.8500179978462;15305.15821629821;15610.14144222056;15687.372505099896;15688.294026768823;15865.920670396574;597412;622374;629583;636756;637002;52391.21413178805;59370.406295937355;60629.84646305025;61231.38768612346;61372.07941880875;1457.8177727784027;1476.9187694676682;1480.3560451203546;1482.2810936695694;1494.2827442827504;25389.74610253874;29758.702412975592;30158.84920575471;30269.091927242218;30495;53546.73226633842;54121;54128.72935635297;54144.72927635494;54220;552877;568779;568870;571340;573968;23907.641385378884;23939.76060239375;24311.149109781072;24714;24734.876325618978;46500;46946.29580556226;47157.528424716686;47517.76241118772;48601.784955376395;38340;38846.223075538896;38935.805320974345;38996;39202;38342.42898903542;38515.229695405375;38532.42201366926;38870.80564597159;39289.607103928596;35829.64170358367;35950.64049359473;36445.817770912035;36511.817440913685;38671.03322416962;32850.34299314048;33484.49773253354;33867;33914;34090.14774630654;21318.680219796406;25259.747402526475;25323.366915826515;25394.619080713434;25601.103961361263 12:52:06 INFO - PROCESS | 2505 | |2;jquery.html;317.8394910570162;334.7422484686791;391.72579194563815;398.13803116253314;399.16375193968634;18969;19089.42731718049;20017.399478015646;20376.388708338738;20401.99999999998;20150.899245503788;21064.578708425823;21237.57524849502;21488.785112148875;21489.892550537224;5338.492843179892;5405.27028851105;5517;5585.469380408855;5632.155176723483;4818;5348.304720386344;5362.570994320445;5465.754041068161;5479.04116779562;5224.843254702346;5419.620626556142;5490.149026900836;5497.670139791625;5505.752241149138;5472.425395333493;5473.698946557938;5492.423295553949;5504.311961004874;5523.585731070146;5043.42000669924;5241.86895327616;5250.81622143227;5295.973520132413;5370.087085195526;10545.578176872916;14078.788818167735;14165.433382664682;14277.78583321251;14302.141871487744;1970.8620396572246;2017.4754563813367;2225.6216443204694;2323.384303159664;2816.704246054155;9931.304808663397;10330.173586113093;10581.259311848175;11423.771524569545;15797.68404631901;1597.8162511311116;1711.9144042797811;1730.264637529045;1736.8263173682658;1776.3657435159685;96967;100402;115364.99999999916;124959.3752031243;130604.34697826546;11636.301821890713;11863.228890122115;11923.463444145034;12011;12023.939880300717;15258.931874768881;15364.769528457082;15462;15471.458498952708;15474.922625386802;8909.020007799138;8967.551622418852;9007.459552426875;9008.459492430475;9117.997020327759;7338.266173382619;9005.954970225106;9033.006369299372;9051.59267832949;9090.36367454285;4064.3700226464944;4762.571368576775;4775.976120119377;4803.279508073747;4816.903661926742;1698.7621732957396;1856.7559734977597;1887.452289122906;1974.4175468236904;2023.9494012649507;891.9420237684529;972.2853702528636;984.6356847966155;1023.9897601024044;1053.9736506587396;21083.578328433345;21245.043973021253;21262;21558.353249402542;21640.91795410223;876.2289185516831;932.7528205025674;976;984.104464936904;1057.9100776433936;7804.921950780533;11023.61417350389;11042.944785276182;11089.390083545482;11095.778084438267;474464;520173;537978;541706.2914685401;650231;120482.7951720489;134227.98658020137;156121.43878561296;157251;159967;942.359195746885;984.1634610580949;986.1765425869479;990.108901988202;1029.1355261580159;18236.544086397946;18397.632047359246;18442.98563579016;18549.90725046366;18713.53216169607;42180.78909605432;54621.90756184774;55089.72455137699;55318.34044978657;55680.72159639338;72146.63926680334;75311;75617.62191189009;82181.17818821974;82492.17507824845;8109.472884262504;8156.102828688958;8161.469504482189;8429.957850210709;8463;9918.851217231602;10429.374237545771;11462.713432164262;12603.87396126027;12731.681707957376;10886.29239099456;11118.722031949266;11165.49755260999;11405.828912566152;11490.253133546626;9939.65211217604;10285.125764309818;10505.68482945513;10597.311174773615;11238;9780.217582593517;11347;11381.487833047699;11445.942770286096;11583.884161158618;7423.072115985502;7821.804454888445;8146.9185308148535;8258.587070646563;8305.50500909842;8299.419040667337;10208.795824083329;11446.255993360404;11544.480498377761;11679.708007299885 12:52:06 INFO - PROCESS | 2505 | |3;mootools.html;1598.2488230531649;1611.9194040297987;1884.3781552087812;1953.3163392812526;1958.7747409047963;8079.798005049862;8587.957060214698;8642.222200002;8975.506347150904;9005.45967241965;7184.281571842813;8665;8746.650133994648;8771.692990745325;8794.95602521988;2146.731658542682;2431.3435372449426;2436.196055301746;2468.814838887082;2481.2928315430104;2419.1412048722714;2451.9264422067276;2459.004103338151;2468.6420469031978;2480.627905814133;2452.3869032741813;2466.198485492222;2469.2221950085664;2505.24842547236;2517.924462266126;2451.264620613809;2455.631655251718;2470.5800013997664;2478.0707234786955;2479.7396273391396;2436.439618887648;2453.4970331082086;2456.2017344363153;2477.2320580620067;2482.2925466242214;7460.515066520658;8861.689840855604;8921.687740929035;8926.776830579223;8941;5277.261183434322;5464.972675136639;5470.070088085035;5491.615586908918;5519.144532597454;3417.624061353249;3506.491558723996;3532.3994920863515;3659.79871107089;3662.908427289339;1019.108280254777;1047.3820445936858;1050.9106725928305;1051.4637534857202;1069.6256310291415;11442.027427668574;11486.597969071043;11489.310641361544;11600.361980091091;11615;4633.837815676435;4677.345171675968;4744.505480773545;4754.405699287589;4778.426588809365;9114.36199466031;9254.953725231331;9271.721848344561;9272.30457715674;9273.536323183815;5326.094563924142;5339.919901201485;5354.732263386815;5358.651687640291;5361.973190134102;4417.7791110445;4874.195757699939;4915.8033678652455;4928.679635823732;4994.950050499521;3252.284497410566;3852.6917846572205;3853.133045064838;3882.9805850970565;4011.3782363733667;1217.0750229825396;1228.2446295528187;1231.9630411087503;1236.313845815581;1240.6526172671665;827.5324441690426;840.8528507511161;870.5908223135116;889.4929889962659;925.4539821505344;5836.649801011953;5899.734511946973;5913.704314784243;6000;6123.357047510036;863.4344504349662;940.7601061729353;953.9236861051099;955.6798472511787;957.30595318395;3881.1849511602313;3982.920341593152;3990.84036638537;4024.4164596133396;4035.354343305116;10534.262601617966;10862.511186996446;11006.614768483065;11015.173861960386;11017;10431.478426078666;10855.837162442569;10923.563057477772;10934.890651093547;10967.287126336763;8076.3942704297415;8176.182381761895;8196.54918979462;8228.094909559824;8272.007359116944;5724.627899186539;5792.275965504312;5923.881522369615;5927.614705044159;5951.16683664296;9725.6596019139;9886.50567471638;9928.602855885683;10059.195264378686;10099.14157296638;9957.601695932082;9975.700728978143;10170.491475426346;10388.013138751807;10390.688279351632;6921.377076063179;7512.173660897406;7708.845823083395;7892.210778922292;8198.672053117809;8191.754247372588;8280;8293.502389856629;8358.414910956175;8380.958095209486;6524.804255872332;7293.015442915139;7317.853642927218;7462.216467270859;7578.810529736801;7317.67070481819;7327.487075904849;7377.852442951218;7378.557286562824;7518.624068796647;5954.285485741565;6594.208694956444;6734.393904548614;6811.455083593201;6835.316468353125;4793.832215872428;4835.492273311393;4883.047805678001;5024.4975502450925;5028.949710502849;4984.753811547113;4998.6750861193905;5035.420926593489;5127.794888204579;5266.499682530153 12:52:06 INFO - PROCESS | 2505 | |4;prototype.html;258.70120011386854;318.0934337139154;325.77033191599924;326.5721904305362;327.75909706365815;4655.976720116399;4789.928151077732;4939.629527785417;5445.863853403667;5595;5602.590885919053;5617.41017193195;5670.92252472031;5687.744051517681;5719.313682358113;3792.601776813436;4101.200265948141;4314.654827613784;4319.265724826787;4319.76241306728;4085.4864816257996;4304.2882983720365;4322.394864718942;4323.740575565461;4355.956440435602;3689.8339574719203;3699.6300369962923;4044.959550404488;4270.402143699885;4272.081502476967;3461.930761384783;3619.6561326673923;3767.2842159989514;4042.2117687051036;4174.1651669666035;3616.7649102808496;3739;3742.7941463219513;3751.624837516254;3759.7744135351695;4642.744649044301;5226.686398816083;5351.759170837322;5476.808311709111;5563.165525171216;4863.297845753993;4921.261810728383;4928.979118561373;4932.482089380599;4934.754686617581;4737;5636.74634641438;5863.853403664943;5961.970190149022;5987.796528857084;3380.3070370573973;4011.3581826907553;4102.651274641629;4116.938245926313;4121.1345617420375;5409.972950135263;6114.449699527064;6129.846753831145;6221.9422698141425;6301.306856245809;3503.474478828196;3885.3589157788647;4158.708890377645;4165.771097526237;4188.958110418917;3971.4241434991764;4254.638355739735;4259.424013115167;4265.445492086071;4271.945558925383;4571.497135315112;4611.662617840856;4669.41632295963;4838.104950584153;4931.802727890844;5668.521867724534;5982.641041537435;6035;6044.546659000593;6044.727987240584;1304.504288370432;1475.2255066090388;1533.9463118791011;1536.91546964918;1538.2923855026588;871.6034204437093;904.6381447420953;1009.4498498318434;1012.3773879064322;1024.2471783239462;5904.940950590525;5909.965755992684;5977.970110149421;5996.640201587918;6050.761218349151;5305.700103474674;6282.717277722513;6594.318448795527;6610.173728283965;6638.137042184485;4985.850424487271;4990.351254336913;5715.8571035724435;5981.431763982415;6001.4298641628975;2757.8621068946873;3194.952075718866;3432.0094345279676;3485.664604714847;3561.412366959423;5468.496163554974;6094.110825643976;6291.402316779899;6718.7312507499155;6823.351781580841;4730.858074257778;5368.785248590091;5731.627444216113;6226.221722284715;6345.460635846005;5597;6187.133801267826;6389.520785941073;6424.38968298011;6445.774397896051;4905.117078925685;5321.680699158062;5425.918611220756;5529.419410961791;6079.209702738615;5362.946370536245;5392.811251606175;5616.775328986795;5875.148103525051;5996.340402555803;5304.443033481583;5614.410486898817;6152.354002829638;6373.968130159319;6427.810854991842;3634.01881492001;4051.5948405159247;4077.3883917412627;4108.801791041898;4114.547399785962;5935.465808077293;6008.218931538872;6149.877002459836;6181.116917635018;6196.411340922605;4884.925316430522;5246;5302.2046692996355;5932.1694962706115;6007.279126504847;4417.050334178167;5447.836564903059;5539.695316757617;5764.798232062026;6001.659352852478;5200.479952004769;5851.356350801493;5853.1805547222575;5882.5293976480925;5915.40845915405;5642.689652069175;5960.403959604005;5996.490298324691;6025.969870150621;6035.577509574287;4851.975740121276;5517.393086760533;5622.409646987007;5858.912116318344;5929.2884853816095 12:52:06 INFO - PROCESS | 2505 | |5;yui.html;678.5352033856807;722.1983598205991;733.4132693844927;744.2881372842461;774.6591499740116;13106.14810037347;14368.778653814425;15126.848731512682;15311.310991005405;15311.464098756545;13864.376103075361;13928.582142535715;13946.442142314296;14628.4880029199;15495.690086198298;937.3672770879663;937.8452555328363;943.5470973932516;944.5607792376544;952.1573407534313;739.8261408568982;897.8563429851225;923.866039424283;928.6981730937439;935.6351023100996;952.4808979106404;993.7764003099317;996.8305388084007;997.486294558303;998.2912132386015;986.659602437158;987.7975015121883;989.1740396768671;991.3655260633201;994.6568433890296;723.7792473295643;726.4515290955295;738.8817789153763;739.1204466684628;741.0477536365798;12275;15050.698986020217;15102.39590416382;15110.471133510384;15126.621834454227;12182.939085304606;12412;12504.562340318136;12559.309237991907;12624.305663188521;9942.552585133613;10008.749781255454;10177;10187.796244075076;10235.846462302998;3453.4474484082425;3533.487644291589;3616.18635806944;3648.215633638754;3808.1812410331636;74494.62752686256;75090;75796;75923.86114208291;76155.6192219041;9238.30712696537;9376.8593471098;9399.765005874771;9472.621095156117;9619.422834629804;19851.702224466644;20140.697889531668;20239.08924098419;20241.392758217276;20302.390928272176;7176.928230717626;7472.962635186789;7565.73519926811;7763.534187948628;7768.145503994556;4169.061961058737;4213.873583792491;4234.915301693949;4272.6795490338345;4273.722208056529;3535.611082780892;3655.287218992271;3656.634336566322;3663.3589121904092;3687.3731465650903;655.0403658640115;655.6819942327967;674.878521866059;677.5866721300004;682.382443888282;567.6934455394123;583.2476105823525;588.5350573047244;597.375742349246;605.2827399531569;7658.425618078666;7882.054153501616;8039.316658084128;8166.305864001626;8206.384521160937;494.6809307996322;564.153769345981;582.2808831093596;583.7285662167112;594.6135012241991;3803.82882770276;3825.980870095632;3834.5973672763957;3886.6696330811633;3946.7631942082994;85839.85400365041;87890.12109878947;97573.0242697564;98013.50993244989;98982.5050874741;62418.0637290441;78828;81167.18832811597;81511;83840;73860.2613973864;74018.25981740221;74117.88823167657;74414;74728.87906681404;7371.152317483344;7373.262673732584;7390.187079421152;7608.277213664693;7830.41271904598;38298.80850595729;43190.56809431991;46221.30668040049;46609;46814.531854681016;42978.14043719171;69134;69381.2654683637;69911.60176796537;70134;10150.54322555472;10224.53989570486;10346.120579750805;10423.009814067651;10480.161587072864;10340.327878687862;10746.73133171677;11215.383153926612;11251.774964500828;11781.05751539892;10008.09927106564;10070.546825393021;10163.034511721375;10414.375137491776;10775.29960552593;10454.42500662471;10628.468576571295;10657.893421066;10784.299020563638;11473.885261147281;10020.549075291776;10103.696889093339;10192.643257485952;10223.897761022592;10700;7889.3294070004695;8252.381071419788;8538.10349913275;8610.82778344417;8919.955400222958;8778.56107194625;8835.69075082369;8860.822783544165;9026.684066057656;9084.500352480676 12:52:06 INFO - PROCESS | 2505 | __end_tp_report 12:52:06 INFO - PROCESS | 2505 | __start_cc_report 12:52:06 INFO - PROCESS | 2505 | _x_x_mozilla_cycle_collect,421 12:52:06 INFO - PROCESS | 2505 | __end_cc_report 12:52:06 INFO - PROCESS | 2505 | __startTimestamp1462477926310__endTimestamp 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | ------- Summary: start ------- 12:52:06 INFO - PROCESS | 2505 | Number of tests: 6 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | [#0] dojo.html Cycles:165 Average:5741.32 Median:5937.64 stddev:663.09 (11.2%) stddev-sans-first:638.49 12:52:06 INFO - PROCESS | 2505 | Values: 3369.5 3451.7 3461.6 3508.6 3525.6 5761.6 5772.3 5814.1 5819.6 5863.6 5801.4 5812.9 5854.2 5871.1 5879.7 5257.2 5271.9 5357.1 5365.8 5398.9 5208.5 5287.7 5330.8 5339.6 5481.6 5277.5 5298.1 5302.8 5321.8 5327.8 5256.9 5293.9 5312.9 5327.8 5330.2 5263.4 5285.1 5321.1 5347.7 5416.1 5740.1 5752.6 5761.8 5811.3 5813.6 6125.1 6132.8 6174.3 6275.1 6306.4 5230.9 6212.2 6301.9 6308.8 6319.5 5900.1 5937.6 5948.3 5967.6 5995.6 6175.2 6211.8 6331.0 6351.7 6397.4 5688.9 6160.1 6246.6 6256.2 6272.7 5307.9 6144.0 6315.7 6333.9 6342.6 6100.4 6195.9 6254.1 6264.9 6274.7 6156.5 6171.6 6181.9 6189.8 6191.6 4071.6 4214.6 5008.8 5010.6 5058.5 4410.8 4459.8 4485.9 4516.5 4524.0 5848.7 5857.7 5893.8 5898.3 5917.5 6268.8 6271.6 6285.2 6290.4 6310.5 4356.3 5645.1 5728.4 5839.1 5924.9 4034.6 4584.4 5318.0 5328.4 5415.6 5416.9 6227.5 6227.9 6329.2 6351.4 5252.6 5777.2 5816.9 6213.3 6302.8 6360.3 6367.0 6396.5 6398.3 6420.7 5534.2 6113.0 6202.1 6234.9 6278.0 6128.2 6217.5 6292.7 6300.4 6305.1 6142.9 6168.5 6180.2 6185.4 6194.6 6039.8 6093.1 6106.0 6158.9 6183.4 5507.5 5886.6 5994.0 6034.1 6078.8 6123.8 6186.1 6188.5 6203.8 6210.5 5889.7 5981.5 6062.1 6068.5 6122.8 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | [#1] ext.html Cycles:180 Average:68998.21 Median:38758.63 stddev:153257.19 (395.4%) stddev-sans-first:153600.86 12:52:06 INFO - PROCESS | 2505 | Values: 514.6 551.3 558.4 560.8 604.1 38166.6 39491.6 39565.8 39619.6 39631.0 33969.7 40892.6 41652.2 41653.8 41847.2 9334.1 9577.9 9592.0 9611.7 9614.2 9435.2 9672.4 9702.5 9724.8 9767.0 9661.9 9733.7 9743.4 9751.2 9768.1 9628.9 9642.9 9652.0 9707.6 9735.0 9505.5 9624.2 9631.2 9649.4 9658.4 39331.8 39985.8 39994.4 40064.8 40073.0 3122.9 3371.6 3474.4 3682.6 3726.3 26979.0 27778.3 28851.3 28906.3 29005.0 2734.8 2752.3 2766.3 2781.1 2814.4 518466.0 519117.0 521897.0 522674.4 523859.0 34313.0 62019.8 62525.0 62537.7 62570.0 31616.7 35739.1 35906.0 36022.8 36205.7 15272.8 15676.4 15685.2 15693.9 15701.3 12913.2 13138.2 13158.5 13175.7 13217.8 8071.1 8195.0 8198.3 8237.6 8302.3 3386.4 3400.1 3429.0 3435.2 3458.4 964.6 1041.6 1047.6 1072.5 1174.7 29661.9 30338.8 30457.4 30922.4 30986.7 1110.3 1122.6 1122.9 1136.5 1249.9 15305.2 15610.1 15687.4 15688.3 15865.9 597412.0 622374.0 629583.0 636756.0 637002.0 52391.2 59370.4 60629.8 61231.4 61372.1 1457.8 1476.9 1480.4 1482.3 1494.3 25389.7 29758.7 30158.8 30269.1 30495.0 53546.7 54121.0 54128.7 54144.7 54220.0 552877.0 568779.0 568870.0 571340.0 573968.0 23907.6 23939.8 24311.1 24714.0 24734.9 46500.0 46946.3 47157.5 47517.8 48601.8 38340.0 38846.2 38935.8 38996.0 39202.0 38342.4 38515.2 38532.4 38870.8 39289.6 35829.6 35950.6 36445.8 36511.8 38671.0 32850.3 33484.5 33867.0 33914.0 34090.1 21318.7 25259.7 25323.4 25394.6 25601.1 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | [#2] jquery.html Cycles:180 Average:33539.43 Median:2570.04 stddev:92552.33 (3601.2%) stddev-sans-first:92778.34 12:52:06 INFO - PROCESS | 2505 | Values: 317.8 334.7 391.7 398.1 399.2 18969.0 19089.4 20017.4 20376.4 20402.0 20150.9 21064.6 21237.6 21488.8 21489.9 5338.5 5405.3 5517.0 5585.5 5632.2 4818.0 5348.3 5362.6 5465.8 5479.0 5224.8 5419.6 5490.1 5497.7 5505.8 5472.4 5473.7 5492.4 5504.3 5523.6 5043.4 5241.9 5250.8 5296.0 5370.1 10545.6 14078.8 14165.4 14277.8 14302.1 1970.9 2017.5 2225.6 2323.4 2816.7 9931.3 10330.2 10581.3 11423.8 15797.7 1597.8 1711.9 1730.3 1736.8 1776.4 96967.0 100402.0 115365.0 124959.4 130604.3 11636.3 11863.2 11923.5 12011.0 12023.9 15258.9 15364.8 15462.0 15471.5 15474.9 8909.0 8967.6 9007.5 9008.5 9118.0 7338.3 9006.0 9033.0 9051.6 9090.4 4064.4 4762.6 4776.0 4803.3 4816.9 1698.8 1856.8 1887.5 1974.4 2023.9 891.9 972.3 984.6 1024.0 1054.0 21083.6 21245.0 21262.0 21558.4 21640.9 876.2 932.8 976.0 984.1 1057.9 7804.9 11023.6 11042.9 11089.4 11095.8 474464.0 520173.0 537978.0 541706.3 650231.0 120482.8 134228.0 156121.4 157251.0 159967.0 942.4 984.2 986.2 990.1 1029.1 18236.5 18397.6 18443.0 18549.9 18713.5 42180.8 54621.9 55089.7 55318.3 55680.7 72146.6 75311.0 75617.6 82181.2 82492.2 8109.5 8156.1 8161.5 8430.0 8463.0 9918.9 10429.4 11462.7 12603.9 12731.7 10886.3 11118.7 11165.5 11405.8 11490.3 9939.7 10285.1 10505.7 10597.3 11238.0 9780.2 11347.0 11381.5 11445.9 11583.9 7423.1 7821.8 8146.9 8258.6 8305.5 8299.4 10208.8 11446.3 11544.5 11679.7 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | [#3] mootools.html Cycles:180 Average:5701.40 Median:5026.72 stddev:3156.26 (62.8%) stddev-sans-first:3150.06 12:52:06 INFO - PROCESS | 2505 | Values: 1598.2 1611.9 1884.4 1953.3 1958.8 8079.8 8588.0 8642.2 8975.5 9005.5 7184.3 8665.0 8746.7 8771.7 8795.0 2146.7 2431.3 2436.2 2468.8 2481.3 2419.1 2451.9 2459.0 2468.6 2480.6 2452.4 2466.2 2469.2 2505.2 2517.9 2451.3 2455.6 2470.6 2478.1 2479.7 2436.4 2453.5 2456.2 2477.2 2482.3 7460.5 8861.7 8921.7 8926.8 8941.0 5277.3 5465.0 5470.1 5491.6 5519.1 3417.6 3506.5 3532.4 3659.8 3662.9 1019.1 1047.4 1050.9 1051.5 1069.6 11442.0 11486.6 11489.3 11600.4 11615.0 4633.8 4677.3 4744.5 4754.4 4778.4 9114.4 9255.0 9271.7 9272.3 9273.5 5326.1 5339.9 5354.7 5358.7 5362.0 4417.8 4874.2 4915.8 4928.7 4995.0 3252.3 3852.7 3853.1 3883.0 4011.4 1217.1 1228.2 1232.0 1236.3 1240.7 827.5 840.9 870.6 889.5 925.5 5836.6 5899.7 5913.7 6000.0 6123.4 863.4 940.8 953.9 955.7 957.3 3881.2 3982.9 3990.8 4024.4 4035.4 10534.3 10862.5 11006.6 11015.2 11017.0 10431.5 10855.8 10923.6 10934.9 10967.3 8076.4 8176.2 8196.5 8228.1 8272.0 5724.6 5792.3 5923.9 5927.6 5951.2 9725.7 9886.5 9928.6 10059.2 10099.1 9957.6 9975.7 10170.5 10388.0 10390.7 6921.4 7512.2 7708.8 7892.2 8198.7 8191.8 8280.0 8293.5 8358.4 8381.0 6524.8 7293.0 7317.9 7462.2 7578.8 7317.7 7327.5 7377.9 7378.6 7518.6 5954.3 6594.2 6734.4 6811.5 6835.3 4793.8 4835.5 4883.0 5024.5 5028.9 4984.8 4998.7 5035.4 5127.8 5266.5 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | [#4] prototype.html Cycles:180 Average:4796.62 Median:5313.69 stddev:1484.42 (27.9%) stddev-sans-first:1448.99 12:52:06 INFO - PROCESS | 2505 | Values: 258.7 318.1 325.8 326.6 327.8 4656.0 4789.9 4939.6 5445.9 5595.0 5602.6 5617.4 5670.9 5687.7 5719.3 3792.6 4101.2 4314.7 4319.3 4319.8 4085.5 4304.3 4322.4 4323.7 4356.0 3689.8 3699.6 4045.0 4270.4 4272.1 3461.9 3619.7 3767.3 4042.2 4174.2 3616.8 3739.0 3742.8 3751.6 3759.8 4642.7 5226.7 5351.8 5476.8 5563.2 4863.3 4921.3 4929.0 4932.5 4934.8 4737.0 5636.7 5863.9 5962.0 5987.8 3380.3 4011.4 4102.7 4116.9 4121.1 5410.0 6114.4 6129.8 6221.9 6301.3 3503.5 3885.4 4158.7 4165.8 4189.0 3971.4 4254.6 4259.4 4265.4 4271.9 4571.5 4611.7 4669.4 4838.1 4931.8 5668.5 5982.6 6035.0 6044.5 6044.7 1304.5 1475.2 1533.9 1536.9 1538.3 871.6 904.6 1009.4 1012.4 1024.2 5904.9 5910.0 5978.0 5996.6 6050.8 5305.7 6282.7 6594.3 6610.2 6638.1 4985.9 4990.4 5715.9 5981.4 6001.4 2757.9 3195.0 3432.0 3485.7 3561.4 5468.5 6094.1 6291.4 6718.7 6823.4 4730.9 5368.8 5731.6 6226.2 6345.5 5597.0 6187.1 6389.5 6424.4 6445.8 4905.1 5321.7 5425.9 5529.4 6079.2 5362.9 5392.8 5616.8 5875.1 5996.3 5304.4 5614.4 6152.4 6374.0 6427.8 3634.0 4051.6 4077.4 4108.8 4114.5 5935.5 6008.2 6149.9 6181.1 6196.4 4884.9 5246.0 5302.2 5932.2 6007.3 4417.1 5447.8 5539.7 5764.8 6001.7 5200.5 5851.4 5853.2 5882.5 5915.4 5642.7 5960.4 5996.5 6026.0 6035.6 4852.0 5517.4 5622.4 5858.9 5929.3 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - PROCESS | 2505 | [#5] yui.html Cycles:180 Average:17798.26 Median:31536.55 stddev:25476.70 (80.8%) stddev-sans-first:25515.74 12:52:06 INFO - PROCESS | 2505 | Values: 678.5 722.2 733.4 744.3 774.7 13106.1 14368.8 15126.8 15311.3 15311.5 13864.4 13928.6 13946.4 14628.5 15495.7 937.4 937.8 943.5 944.6 952.2 739.8 897.9 923.9 928.7 935.6 952.5 993.8 996.8 997.5 998.3 986.7 987.8 989.2 991.4 994.7 723.8 726.5 738.9 739.1 741.0 12275.0 15050.7 15102.4 15110.5 15126.6 12182.9 12412.0 12504.6 12559.3 12624.3 9942.6 10008.7 10177.0 10187.8 10235.8 3453.4 3533.5 3616.2 3648.2 3808.2 74494.6 75090.0 75796.0 75923.9 76155.6 9238.3 9376.9 9399.8 9472.6 9619.4 19851.7 20140.7 20239.1 20241.4 20302.4 7176.9 7473.0 7565.7 7763.5 7768.1 4169.1 4213.9 4234.9 4272.7 4273.7 3535.6 3655.3 3656.6 3663.4 3687.4 655.0 655.7 674.9 677.6 682.4 567.7 583.2 588.5 597.4 605.3 7658.4 7882.1 8039.3 8166.3 8206.4 494.7 564.2 582.3 583.7 594.6 3803.8 3826.0 3834.6 3886.7 3946.8 85839.9 87890.1 97573.0 98013.5 98982.5 62418.1 78828.0 81167.2 81511.0 83840.0 73860.3 74018.3 74117.9 74414.0 74728.9 7371.2 7373.3 7390.2 7608.3 7830.4 38298.8 43190.6 46221.3 46609.0 46814.5 42978.1 69134.0 69381.3 69911.6 70134.0 10150.5 10224.5 10346.1 10423.0 10480.2 10340.3 10746.7 11215.4 11251.8 11781.1 10008.1 10070.5 10163.0 10414.4 10775.3 10454.4 10628.5 10657.9 10784.3 11473.9 10020.5 10103.7 10192.6 10223.9 10700.0 7889.3 8252.4 8538.1 8610.8 8920.0 8778.6 8835.7 8860.8 9026.7 9084.5 12:52:06 INFO - PROCESS | 2505 | -------- Summary: end -------- 12:52:06 INFO - PROCESS | 2505 | 12:52:06 INFO - TEST-INFO | 2505: exit 0 12:52:06 INFO - TEST-OK | dromaeo_css | took 1111890ms 12:52:06 INFO - TEST-START | kraken 12:52:06 INFO - operating with platform_type : mac_ 12:52:06 INFO - Initialising browser for kraken test... 12:52:06 INFO - TEST-INFO | started process 2510 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/tmp6lZUuh/profile http://localhost:55828/getInfo.html) 12:52:09 INFO - PROCESS | 2510 | __metrics Screen width/height:1600/1200 12:52:09 INFO - PROCESS | 2510 | colorDepth:24 12:52:09 INFO - PROCESS | 2510 | Browser inner width/height: 1024/689 12:52:09 INFO - PROCESS | 2510 | __metrics 12:52:09 ERROR - PROCESS | 2510 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 12:52:10 INFO - TEST-INFO | 2510: exit 0 12:52:10 INFO - Browser initialized. 12:52:10 INFO - Running cycle 1/1 for kraken test... 12:52:10 INFO - TEST-INFO | started process 2513 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/T/tmp6lZUuh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:52:22 INFO - PROCESS | 2513 | RSS: Main: 221569024 12:52:22 INFO - PROCESS | 2513 | 12:52:25 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55828/tests/kraken/driver.html?testName=audio-beat-detection) 12:52:26 INFO - PROCESS | 2513 | RSS: Main: 233705472 12:52:26 INFO - PROCESS | 2513 | 12:52:29 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55828/tests/kraken/driver.html?testName=audio-dft) 12:52:29 INFO - PROCESS | 2513 | RSS: Main: 243015680 12:52:29 INFO - PROCESS | 2513 | 12:52:33 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55828/tests/kraken/driver.html?testName=audio-fft) 12:52:33 INFO - PROCESS | 2513 | RSS: Main: 246112256 12:52:33 INFO - PROCESS | 2513 | 12:52:35 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55828/tests/kraken/driver.html?testName=audio-oscillator) 12:52:36 INFO - PROCESS | 2513 | RSS: Main: 246681600 12:52:36 INFO - PROCESS | 2513 | 12:52:38 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55828/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:52:39 INFO - PROCESS | 2513 | RSS: Main: 248164352 12:52:39 INFO - PROCESS | 2513 | 12:52:41 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55828/tests/kraken/driver.html?testName=imaging-darkroom) 12:52:42 INFO - PROCESS | 2513 | RSS: Main: 248569856 12:52:42 INFO - PROCESS | 2513 | 12:52:45 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55828/tests/kraken/driver.html?testName=imaging-desaturate) 12:52:46 INFO - PROCESS | 2513 | RSS: Main: 248696832 12:52:46 INFO - PROCESS | 2513 | 12:52:48 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55828/tests/kraken/driver.html?testName=json-parse-financial) 12:52:49 INFO - PROCESS | 2513 | RSS: Main: 248819712 12:52:49 INFO - PROCESS | 2513 | 12:52:51 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55828/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:52:51 INFO - PROCESS | 2513 | RSS: Main: 248258560 12:52:51 INFO - PROCESS | 2513 | 12:52:53 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:52:54 INFO - PROCESS | 2513 | RSS: Main: 248389632 12:52:54 INFO - PROCESS | 2513 | 12:52:56 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:52:57 INFO - PROCESS | 2513 | RSS: Main: 248287232 12:52:57 INFO - PROCESS | 2513 | 12:53:00 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:53:00 INFO - PROCESS | 2513 | RSS: Main: 248397824 12:53:00 INFO - PROCESS | 2513 | 12:53:03 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:53:04 INFO - PROCESS | 2513 | RSS: Main: 248356864 12:53:04 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | Cycle 1(1): loaded http://localhost:55828/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55828/tests/kraken/driver.html?testName=ai-astar) 12:53:06 INFO - PROCESS | 2513 | RSS: Main: 250011648 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | __start_tp_report 12:53:06 INFO - PROCESS | 2513 | _x_x_mozilla_page_load 12:53:06 INFO - PROCESS | 2513 | _x_x_mozilla_page_load_details 12:53:06 INFO - PROCESS | 2513 | |i|pagename|runs| 12:53:06 INFO - PROCESS | 2513 | |0;ai-astar;119;115;120;120;119;137;118;167;172;124 12:53:06 INFO - PROCESS | 2513 | |1;audio-beat-detection;187;135;145;171;151;174;136;159;208;157 12:53:06 INFO - PROCESS | 2513 | |2;audio-dft;195;204;202;205;201;201;200;199;229;193 12:53:06 INFO - PROCESS | 2513 | |3;audio-fft;70;82;74;148;57;57;61;62;135;75 12:53:06 INFO - PROCESS | 2513 | |4;audio-oscillator;81;84;129;79;80;88;123;83;80;122 12:53:06 INFO - PROCESS | 2513 | |5;imaging-gaussian-blur;91;90;89;88;108;109;91;93;90;88 12:53:06 INFO - PROCESS | 2513 | |6;imaging-darkroom;95;96;97;96;118;119;96;96;96;96 12:53:06 INFO - PROCESS | 2513 | |7;imaging-desaturate;110;154;90;93;88;89;91;89;90;91 12:53:06 INFO - PROCESS | 2513 | |8;json-parse-financial;53;76;100;98;102;89;66;77;61;59 12:53:06 INFO - PROCESS | 2513 | |9;json-stringify-tinderbox;65;68;76;71;75;80;73;66;70;69 12:53:06 INFO - PROCESS | 2513 | |10;stanford-crypto-aes;85;80;85;82;83;85;83;86;91;96 12:53:06 INFO - PROCESS | 2513 | |11;stanford-crypto-ccm;162;157;156;136;140;141;138;135;165;134 12:53:06 INFO - PROCESS | 2513 | |12;stanford-crypto-pbkdf2;172;172;175;176;185;171;169;173;173;175 12:53:06 INFO - PROCESS | 2513 | |13;stanford-crypto-sha256-iterative;68;69;69;68;70;68;70;68;76;70 12:53:06 INFO - PROCESS | 2513 | __end_tp_report 12:53:06 INFO - PROCESS | 2513 | __start_cc_report 12:53:06 INFO - PROCESS | 2513 | _x_x_mozilla_cycle_collect,1119 12:53:06 INFO - PROCESS | 2513 | __end_cc_report 12:53:06 INFO - PROCESS | 2513 | __startTimestamp1462477986401__endTimestamp 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | ------- Summary: start ------- 12:53:06 INFO - PROCESS | 2513 | Number of tests: 14 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#0] ai-astar Cycles:10 Average:131.10 Median:122.00 stddev:21.13 (17.3%) stddev-sans-first:21.95 12:53:06 INFO - PROCESS | 2513 | Values: 119.0 115.0 120.0 120.0 119.0 137.0 118.0 167.0 172.0 124.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#1] audio-beat-detection Cycles:10 Average:162.30 Median:165.00 stddev:23.13 (14.0%) stddev-sans-first:22.74 12:53:06 INFO - PROCESS | 2513 | Values: 187.0 135.0 145.0 171.0 151.0 174.0 136.0 159.0 208.0 157.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#2] audio-dft Cycles:10 Average:202.90 Median:201.50 stddev:9.88 (4.9%) stddev-sans-first:10.06 12:53:06 INFO - PROCESS | 2513 | Values: 195.0 204.0 202.0 205.0 201.0 201.0 200.0 199.0 229.0 193.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#3] audio-fft Cycles:10 Average:82.10 Median:66.00 stddev:32.51 (49.3%) stddev-sans-first:34.19 12:53:06 INFO - PROCESS | 2513 | Values: 70.0 82.0 74.0 148.0 57.0 57.0 61.0 62.0 135.0 75.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:80.50 stddev:20.78 (25.8%) stddev-sans-first:21.42 12:53:06 INFO - PROCESS | 2513 | Values: 81.0 84.0 129.0 79.0 80.0 88.0 123.0 83.0 80.0 122.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:90.00 stddev:7.94 (8.8%) stddev-sans-first:8.37 12:53:06 INFO - PROCESS | 2513 | Values: 91.0 90.0 89.0 88.0 108.0 109.0 91.0 93.0 90.0 88.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:96.00 stddev:9.50 (9.9%) stddev-sans-first:9.87 12:53:06 INFO - PROCESS | 2513 | Values: 95.0 96.0 97.0 96.0 118.0 119.0 96.0 96.0 96.0 96.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#7] imaging-desaturate Cycles:10 Average:98.50 Median:90.00 stddev:20.52 (22.8%) stddev-sans-first:21.34 12:53:06 INFO - PROCESS | 2513 | Values: 110.0 154.0 90.0 93.0 88.0 89.0 91.0 89.0 90.0 91.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#8] json-parse-financial Cycles:10 Average:78.10 Median:71.00 stddev:18.27 (25.7%) stddev-sans-first:16.97 12:53:06 INFO - PROCESS | 2513 | Values: 53.0 76.0 100.0 98.0 102.0 89.0 66.0 77.0 61.0 59.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:4.72 (6.5%) stddev-sans-first:4.42 12:53:06 INFO - PROCESS | 2513 | Values: 65.0 68.0 76.0 71.0 75.0 80.0 73.0 66.0 70.0 69.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:4.67 (5.5%) stddev-sans-first:4.95 12:53:06 INFO - PROCESS | 2513 | Values: 85.0 80.0 85.0 82.0 83.0 85.0 83.0 86.0 91.0 96.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:148.50 stddev:12.14 (8.2%) stddev-sans-first:11.49 12:53:06 INFO - PROCESS | 2513 | Values: 162.0 157.0 156.0 136.0 140.0 141.0 138.0 135.0 165.0 134.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:174.00 stddev:4.36 (2.5%) stddev-sans-first:4.56 12:53:06 INFO - PROCESS | 2513 | Values: 172.0 172.0 175.0 176.0 185.0 171.0 169.0 173.0 173.0 175.0 12:53:06 INFO - PROCESS | 2513 | 12:53:06 INFO - PROCESS | 2513 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.50 stddev:2.41 (3.5%) stddev-sans-first:2.49 12:53:06 INFO - PROCESS | 2513 | Values: 68.0 69.0 69.0 68.0 70.0 68.0 70.0 68.0 76.0 70.0 12:53:06 INFO - PROCESS | 2513 | -------- Summary: end -------- 12:53:06 INFO - PROCESS | 2513 | 12:53:07 INFO - TEST-INFO | 2513: exit 0 12:53:07 INFO - TEST-OK | kraken | took 60428ms 12:53:07 INFO - SUITE-END | took 1172s 12:53:07 INFO - Completed test suite (00:19:32) 12:53:07 INFO - javascript benchmark 12:53:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3369.4777309517026, 3451.7066049385803, 3461.619221885592, 3508.6140524542297, 3525.612182659907, 5761.596688231825, 5772.307323121221, 5814.098814683725, 5819.621724587904, 5863.648181109136, 5801.361850196475, 5812.912806307909, 5854.20968169297, 5871.148683440899, 5879.67661778602, 5257.158854583267, 5271.868203294929, 5357.089294819871, 5365.7853685852715, 5398.8920221595545, 5208.47915208478, 5287.68273903567, 5330.760115794798, 5339.6262261641705, 5481.588880833933, 5277.525022747952, 5298.14564902283, 5302.840914772563, 5321.840344789663, 5327.786888524454, 5256.947430525704, 5293.867653308679, 5312.946870531323, 5327.786888524454, 5330.173823057432, 5263.36839579253, 5285.127953887604, 5321.068812957717, 5347.732613369316, 5416.133418653036, 5740.138979153118, 5752.626079304831, 5761.827145185651, 5811.27359080115, 5813.622114562582, 6125.050617154348, 6132.754689812402, 6174.289956654952, 6275.09011193379, 6306.400891915261, 5230.947690523084, 6212.161358216628, 6301.905471417932, 6308.842278943017, 6319.526035547352, 5900.144479050514, 5937.643741375531, 5948.31594366649, 5967.5524335675, 5995.640261584319, 6175.166352542439, 6211.782587609502, 6330.968345158337, 6351.745930162834, 6397.424231819065, 5688.943110568925, 6160.1375807387, 6246.562740608205, 6256.186695729526, 6272.655003974825, 5307.946920530822, 6144.016957286902, 6315.684215789192, 6333.904991425133, 6342.560207854521, 6100.389961003864, 6195.876082478415, 6254.061890716429, 6264.9373506265265, 6274.6548939808745, 6156.476699480594, 6171.5988460750805, 6181.907271390933, 6189.8452538686, 6191.566590338633, 4071.592840715905, 4214.620684138442, 5008.774605142778, 5010.574101201439, 5058.54473097423, 4410.779461026936, 4459.821607135743, 4485.887852803706, 4516.525764794715, 4524.02733412318, 5848.707564621752, 5857.677827719431, 5893.823185304448, 5898.321693005273, 5917.46742793142, 6268.811935641938, 6271.592346497463, 6285.214348206474, 6290.370962903765, 6310.463610593151, 4356.324769660708, 5645.125005624135, 5728.369879313273, 5839.124131380242, 5924.881502369928, 4034.596540345942, 4584.404027476407, 5318.016167009116, 5328.440513746078, 5415.620906536505, 5416.864578385572, 6227.50179985591, 6227.937720622826, 6329.2404911409085, 6351.4283714465, 5252.579793616578, 5777.162311464898, 5816.854578635568, 6213.285472170758, 6302.779402720883, 6360.300366959723, 6367.0, 6396.456301214449, 6398.264199617103, 6420.711068002044, 5534.225208470737, 6113.0, 6202.069689546423, 6234.875302493834, 6277.968610157102, 6128.203333566607, 6217.533684973555, 6292.716827742671, 6300.432961033529, 6305.148804911461, 6142.877142457037, 6168.537359698131, 6180.196574445294, 6185.443310102112, 6194.628322300677, 6039.849003774941, 6093.055576385667, 6105.9694701526205, 6158.938410615837, 6183.443490085914, 5507.512971497753, 5886.558508111823, 5994.0, 6034.064719968412, 6078.787242446493, 6123.84690382744, 6186.10301506288, 6188.535859810442, 6203.844903877439, 6210.534209934182, 5889.705514724333, 5981.491573216152, 6062.090686397076, 6068.544859135671, 6122.785702500391], "value": 5701.475965873903, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.6397521734784, 551.3301338873268, 558.4280341787062, 560.7672815781451, 604.1240201707525, 38166.61833381669, 39491.60508394919, 39565.80217098918, 39619.60380396195, 39631.0, 33969.66030339696, 40892.59107408925, 41652.16695666085, 41653.7917310413, 41847.16305673885, 9334.113259240396, 9577.904220957771, 9592.040795920422, 9611.711648650551, 9614.230861531096, 9435.198008169315, 9672.419654820733, 9702.466364349957, 9724.756881077994, 9766.9511652442, 9661.90338096617, 9733.707988760312, 9743.415395076283, 9751.219902407825, 9768.120869121778, 9628.855567166498, 9642.855357169648, 9652.034796520291, 9707.61169553217, 9734.951325243364, 9505.477198754066, 9624.230061595055, 9631.229501639851, 9649.372790768577, 9658.420494770337, 39331.803340983395, 39985.80042598727, 39994.400083998764, 40064.799676001436, 40073.0, 3122.8750849965973, 3371.645977172386, 3474.3919814032442, 3682.558093028827, 3726.31063253299, 26979.0, 27778.305542361402, 28851.27871803201, 28906.27734306638, 29005.0, 2734.767544758698, 2752.3119220194953, 2766.294594878313, 2781.123945957017, 2814.3949050954143, 518466.0, 519117.0, 521897.0, 522674.3866280682, 523859.0, 34312.97061088171, 62019.75960480765, 62525.0, 62537.74924501484, 62570.0, 31616.683833161373, 35739.10652233715, 35906.0, 36022.819885900404, 36205.7327993519, 15272.770908436383, 15676.372945082298, 15685.215739212994, 15693.921530392276, 15701.293441795147, 12913.225206487641, 13138.211707297593, 13158.473661053451, 13175.736485270432, 13217.801732974012, 8071.071826739882, 8195.0, 8198.303144232688, 8237.629306681214, 8302.335813134934, 3386.407378708716, 3400.1329660936303, 3428.9657103429145, 3435.1927297085167, 3458.412069948115, 964.62379671928, 1041.620121439609, 1047.643801107627, 1072.4744875011345, 1174.6945794093597, 29661.85169074141, 30338.765757271845, 30457.39085218283, 30922.381552369276, 30986.690133098833, 1110.3337997201616, 1122.5509796081633, 1122.8989390954853, 1136.4885801389407, 1249.8500179978462, 15305.15821629821, 15610.14144222056, 15687.372505099896, 15688.294026768823, 15865.920670396574, 597412.0, 622374.0, 629583.0, 636756.0, 637002.0, 52391.21413178805, 59370.406295937355, 60629.84646305025, 61231.38768612346, 61372.07941880875, 1457.8177727784027, 1476.9187694676682, 1480.3560451203546, 1482.2810936695694, 1494.2827442827504, 25389.74610253874, 29758.702412975592, 30158.84920575471, 30269.091927242218, 30495.0, 53546.73226633842, 54121.0, 54128.72935635297, 54144.72927635494, 54220.0, 552877.0, 568779.0, 568870.0, 571340.0, 573968.0, 23907.641385378884, 23939.76060239375, 24311.149109781072, 24714.0, 24734.876325618978, 46500.0, 46946.29580556226, 47157.528424716686, 47517.76241118772, 48601.784955376395, 38340.0, 38846.223075538896, 38935.805320974345, 38996.0, 39202.0, 38342.42898903542, 38515.229695405375, 38532.42201366926, 38870.80564597159, 39289.607103928596, 35829.64170358367, 35950.64049359473, 36445.817770912035, 36511.817440913685, 38671.03322416962, 32850.34299314048, 33484.49773253354, 33867.0, 33914.0, 34090.14774630654, 21318.680219796406, 25259.747402526475, 25323.366915826515, 25394.619080713434, 25601.103961361263], "value": 18777.18197816955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.8394910570162, 334.7422484686791, 391.72579194563815, 398.13803116253314, 399.16375193968634, 18969.0, 19089.42731718049, 20017.399478015646, 20376.388708338738, 20401.99999999998, 20150.899245503788, 21064.578708425823, 21237.57524849502, 21488.785112148875, 21489.892550537224, 5338.492843179892, 5405.27028851105, 5517.0, 5585.469380408855, 5632.155176723483, 4818.0, 5348.304720386344, 5362.570994320445, 5465.754041068161, 5479.04116779562, 5224.843254702346, 5419.620626556142, 5490.149026900836, 5497.670139791625, 5505.752241149138, 5472.425395333493, 5473.698946557938, 5492.423295553949, 5504.311961004874, 5523.585731070146, 5043.42000669924, 5241.86895327616, 5250.81622143227, 5295.973520132413, 5370.087085195526, 10545.578176872916, 14078.788818167735, 14165.433382664682, 14277.78583321251, 14302.141871487744, 1970.8620396572246, 2017.4754563813367, 2225.6216443204694, 2323.384303159664, 2816.704246054155, 9931.304808663397, 10330.173586113093, 10581.259311848175, 11423.771524569545, 15797.68404631901, 1597.8162511311116, 1711.9144042797811, 1730.264637529045, 1736.8263173682658, 1776.3657435159685, 96967.0, 100402.0, 115364.99999999916, 124959.3752031243, 130604.34697826546, 11636.301821890713, 11863.228890122115, 11923.463444145034, 12011.0, 12023.939880300717, 15258.931874768881, 15364.769528457082, 15462.0, 15471.458498952708, 15474.922625386802, 8909.020007799138, 8967.551622418852, 9007.459552426875, 9008.459492430475, 9117.997020327759, 7338.266173382619, 9005.954970225106, 9033.006369299372, 9051.59267832949, 9090.36367454285, 4064.3700226464944, 4762.571368576775, 4775.976120119377, 4803.279508073747, 4816.903661926742, 1698.7621732957396, 1856.7559734977597, 1887.452289122906, 1974.4175468236904, 2023.9494012649507, 891.9420237684529, 972.2853702528636, 984.6356847966155, 1023.9897601024044, 1053.9736506587396, 21083.578328433345, 21245.043973021253, 21262.0, 21558.353249402542, 21640.91795410223, 876.2289185516831, 932.7528205025674, 976.0, 984.104464936904, 1057.9100776433936, 7804.921950780533, 11023.61417350389, 11042.944785276182, 11089.390083545482, 11095.778084438267, 474464.0, 520173.0, 537978.0, 541706.2914685401, 650231.0, 120482.7951720489, 134227.98658020137, 156121.43878561296, 157251.0, 159967.0, 942.359195746885, 984.1634610580949, 986.1765425869479, 990.108901988202, 1029.1355261580159, 18236.544086397946, 18397.632047359246, 18442.98563579016, 18549.90725046366, 18713.53216169607, 42180.78909605432, 54621.90756184774, 55089.72455137699, 55318.34044978657, 55680.72159639338, 72146.63926680334, 75311.0, 75617.62191189009, 82181.17818821974, 82492.17507824845, 8109.472884262504, 8156.102828688958, 8161.469504482189, 8429.957850210709, 8463.0, 9918.851217231602, 10429.374237545771, 11462.713432164262, 12603.87396126027, 12731.681707957376, 10886.29239099456, 11118.722031949266, 11165.49755260999, 11405.828912566152, 11490.253133546626, 9939.65211217604, 10285.125764309818, 10505.68482945513, 10597.311174773615, 11238.0, 9780.217582593517, 11347.0, 11381.487833047699, 11445.942770286096, 11583.884161158618, 7423.072115985502, 7821.804454888445, 8146.9185308148535, 8258.587070646563, 8305.50500909842, 8299.419040667337, 10208.795824083329, 11446.255993360404, 11544.480498377761, 11679.708007299885], "value": 9322.656307285837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1598.2488230531649, 1611.9194040297987, 1884.3781552087812, 1953.3163392812526, 1958.7747409047963, 8079.798005049862, 8587.957060214698, 8642.222200002, 8975.506347150904, 9005.45967241965, 7184.281571842813, 8665.0, 8746.650133994648, 8771.692990745325, 8794.95602521988, 2146.731658542682, 2431.3435372449426, 2436.196055301746, 2468.814838887082, 2481.2928315430104, 2419.1412048722714, 2451.9264422067276, 2459.004103338151, 2468.6420469031978, 2480.627905814133, 2452.3869032741813, 2466.198485492222, 2469.2221950085664, 2505.24842547236, 2517.924462266126, 2451.264620613809, 2455.631655251718, 2470.5800013997664, 2478.0707234786955, 2479.7396273391396, 2436.439618887648, 2453.4970331082086, 2456.2017344363153, 2477.2320580620067, 2482.2925466242214, 7460.515066520658, 8861.689840855604, 8921.687740929035, 8926.776830579223, 8941.0, 5277.261183434322, 5464.972675136639, 5470.070088085035, 5491.615586908918, 5519.144532597454, 3417.624061353249, 3506.491558723996, 3532.3994920863515, 3659.79871107089, 3662.908427289339, 1019.108280254777, 1047.3820445936858, 1050.9106725928305, 1051.4637534857202, 1069.6256310291415, 11442.027427668574, 11486.597969071043, 11489.310641361544, 11600.361980091091, 11615.0, 4633.837815676435, 4677.345171675968, 4744.505480773545, 4754.405699287589, 4778.426588809365, 9114.36199466031, 9254.953725231331, 9271.721848344561, 9272.30457715674, 9273.536323183815, 5326.094563924142, 5339.919901201485, 5354.732263386815, 5358.651687640291, 5361.973190134102, 4417.7791110445, 4874.195757699939, 4915.8033678652455, 4928.679635823732, 4994.950050499521, 3252.284497410566, 3852.6917846572205, 3853.133045064838, 3882.9805850970565, 4011.3782363733667, 1217.0750229825396, 1228.2446295528187, 1231.9630411087503, 1236.313845815581, 1240.6526172671665, 827.5324441690426, 840.8528507511161, 870.5908223135116, 889.4929889962659, 925.4539821505344, 5836.649801011953, 5899.734511946973, 5913.704314784243, 6000.0, 6123.357047510036, 863.4344504349662, 940.7601061729353, 953.9236861051099, 955.6798472511787, 957.30595318395, 3881.1849511602313, 3982.920341593152, 3990.84036638537, 4024.4164596133396, 4035.354343305116, 10534.262601617966, 10862.511186996446, 11006.614768483065, 11015.173861960386, 11017.0, 10431.478426078666, 10855.837162442569, 10923.563057477772, 10934.890651093547, 10967.287126336763, 8076.3942704297415, 8176.182381761895, 8196.54918979462, 8228.094909559824, 8272.007359116944, 5724.627899186539, 5792.275965504312, 5923.881522369615, 5927.614705044159, 5951.16683664296, 9725.6596019139, 9886.50567471638, 9928.602855885683, 10059.195264378686, 10099.14157296638, 9957.601695932082, 9975.700728978143, 10170.491475426346, 10388.013138751807, 10390.688279351632, 6921.377076063179, 7512.173660897406, 7708.845823083395, 7892.210778922292, 8198.672053117809, 8191.754247372588, 8280.0, 8293.502389856629, 8358.414910956175, 8380.958095209486, 6524.804255872332, 7293.015442915139, 7317.853642927218, 7462.216467270859, 7578.810529736801, 7317.67070481819, 7327.487075904849, 7377.852442951218, 7378.557286562824, 7518.624068796647, 5954.285485741565, 6594.208694956444, 6734.393904548614, 6811.455083593201, 6835.316468353125, 4793.832215872428, 4835.492273311393, 4883.047805678001, 5024.4975502450925, 5028.949710502849, 4984.753811547113, 4998.6750861193905, 5035.420926593489, 5127.794888204579, 5266.499682530153], "value": 4605.859493256191, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.70120011386854, 318.0934337139154, 325.77033191599924, 326.5721904305362, 327.75909706365815, 4655.976720116399, 4789.928151077732, 4939.629527785417, 5445.863853403667, 5595.0, 5602.590885919053, 5617.41017193195, 5670.92252472031, 5687.744051517681, 5719.313682358113, 3792.601776813436, 4101.200265948141, 4314.654827613784, 4319.265724826787, 4319.76241306728, 4085.4864816257996, 4304.2882983720365, 4322.394864718942, 4323.740575565461, 4355.956440435602, 3689.8339574719203, 3699.6300369962923, 4044.959550404488, 4270.402143699885, 4272.081502476967, 3461.930761384783, 3619.6561326673923, 3767.2842159989514, 4042.2117687051036, 4174.1651669666035, 3616.7649102808496, 3739.0, 3742.7941463219513, 3751.624837516254, 3759.7744135351695, 4642.744649044301, 5226.686398816083, 5351.759170837322, 5476.808311709111, 5563.165525171216, 4863.297845753993, 4921.261810728383, 4928.979118561373, 4932.482089380599, 4934.754686617581, 4737.0, 5636.74634641438, 5863.853403664943, 5961.970190149022, 5987.796528857084, 3380.3070370573973, 4011.3581826907553, 4102.651274641629, 4116.938245926313, 4121.1345617420375, 5409.972950135263, 6114.449699527064, 6129.846753831145, 6221.9422698141425, 6301.306856245809, 3503.474478828196, 3885.3589157788647, 4158.708890377645, 4165.771097526237, 4188.958110418917, 3971.4241434991764, 4254.638355739735, 4259.424013115167, 4265.445492086071, 4271.945558925383, 4571.497135315112, 4611.662617840856, 4669.41632295963, 4838.104950584153, 4931.802727890844, 5668.521867724534, 5982.641041537435, 6035.0, 6044.546659000593, 6044.727987240584, 1304.504288370432, 1475.2255066090388, 1533.9463118791011, 1536.91546964918, 1538.2923855026588, 871.6034204437093, 904.6381447420953, 1009.4498498318434, 1012.3773879064322, 1024.2471783239462, 5904.940950590525, 5909.965755992684, 5977.970110149421, 5996.640201587918, 6050.761218349151, 5305.700103474674, 6282.717277722513, 6594.318448795527, 6610.173728283965, 6638.137042184485, 4985.850424487271, 4990.351254336913, 5715.8571035724435, 5981.431763982415, 6001.4298641628975, 2757.8621068946873, 3194.952075718866, 3432.0094345279676, 3485.664604714847, 3561.412366959423, 5468.496163554974, 6094.110825643976, 6291.402316779899, 6718.7312507499155, 6823.351781580841, 4730.858074257778, 5368.785248590091, 5731.627444216113, 6226.221722284715, 6345.460635846005, 5597.0, 6187.133801267826, 6389.520785941073, 6424.38968298011, 6445.774397896051, 4905.117078925685, 5321.680699158062, 5425.918611220756, 5529.419410961791, 6079.209702738615, 5362.946370536245, 5392.811251606175, 5616.775328986795, 5875.148103525051, 5996.340402555803, 5304.443033481583, 5614.410486898817, 6152.354002829638, 6373.968130159319, 6427.810854991842, 3634.01881492001, 4051.5948405159247, 4077.3883917412627, 4108.801791041898, 4114.547399785962, 5935.465808077293, 6008.218931538872, 6149.877002459836, 6181.116917635018, 6196.411340922605, 4884.925316430522, 5246.0, 5302.2046692996355, 5932.1694962706115, 6007.279126504847, 4417.050334178167, 5447.836564903059, 5539.695316757617, 5764.798232062026, 6001.659352852478, 5200.479952004769, 5851.356350801493, 5853.1805547222575, 5882.5293976480925, 5915.40845915405, 5642.689652069175, 5960.403959604005, 5996.490298324691, 6025.969870150621, 6035.577509574287, 4851.975740121276, 5517.393086760533, 5622.409646987007, 5858.912116318344, 5929.2884853816095], "value": 4328.136142442224, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.5352033856807, 722.1983598205991, 733.4132693844927, 744.2881372842461, 774.6591499740116, 13106.14810037347, 14368.778653814425, 15126.848731512682, 15311.310991005405, 15311.464098756545, 13864.376103075361, 13928.582142535715, 13946.442142314296, 14628.4880029199, 15495.690086198298, 937.3672770879663, 937.8452555328363, 943.5470973932516, 944.5607792376544, 952.1573407534313, 739.8261408568982, 897.8563429851225, 923.866039424283, 928.6981730937439, 935.6351023100996, 952.4808979106404, 993.7764003099317, 996.8305388084007, 997.486294558303, 998.2912132386015, 986.659602437158, 987.7975015121883, 989.1740396768671, 991.3655260633201, 994.6568433890296, 723.7792473295643, 726.4515290955295, 738.8817789153763, 739.1204466684628, 741.0477536365798, 12275.0, 15050.698986020217, 15102.39590416382, 15110.471133510384, 15126.621834454227, 12182.939085304606, 12412.0, 12504.562340318136, 12559.309237991907, 12624.305663188521, 9942.552585133613, 10008.749781255454, 10177.0, 10187.796244075076, 10235.846462302998, 3453.4474484082425, 3533.487644291589, 3616.18635806944, 3648.215633638754, 3808.1812410331636, 74494.62752686256, 75090.0, 75796.0, 75923.86114208291, 76155.6192219041, 9238.30712696537, 9376.8593471098, 9399.765005874771, 9472.621095156117, 9619.422834629804, 19851.702224466644, 20140.697889531668, 20239.08924098419, 20241.392758217276, 20302.390928272176, 7176.928230717626, 7472.962635186789, 7565.73519926811, 7763.534187948628, 7768.145503994556, 4169.061961058737, 4213.873583792491, 4234.915301693949, 4272.6795490338345, 4273.722208056529, 3535.611082780892, 3655.287218992271, 3656.634336566322, 3663.3589121904092, 3687.3731465650903, 655.0403658640115, 655.6819942327967, 674.878521866059, 677.5866721300004, 682.382443888282, 567.6934455394123, 583.2476105823525, 588.5350573047244, 597.375742349246, 605.2827399531569, 7658.425618078666, 7882.054153501616, 8039.316658084128, 8166.305864001626, 8206.384521160937, 494.6809307996322, 564.153769345981, 582.2808831093596, 583.7285662167112, 594.6135012241991, 3803.82882770276, 3825.980870095632, 3834.5973672763957, 3886.6696330811633, 3946.7631942082994, 85839.85400365041, 87890.12109878947, 97573.0242697564, 98013.50993244989, 98982.5050874741, 62418.0637290441, 78828.0, 81167.18832811597, 81511.0, 83840.0, 73860.2613973864, 74018.25981740221, 74117.88823167657, 74414.0, 74728.87906681404, 7371.152317483344, 7373.262673732584, 7390.187079421152, 7608.277213664693, 7830.41271904598, 38298.80850595729, 43190.56809431991, 46221.30668040049, 46609.0, 46814.531854681016, 42978.14043719171, 69134.0, 69381.2654683637, 69911.60176796537, 70134.0, 10150.54322555472, 10224.53989570486, 10346.120579750805, 10423.009814067651, 10480.161587072864, 10340.327878687862, 10746.73133171677, 11215.383153926612, 11251.774964500828, 11781.05751539892, 10008.09927106564, 10070.546825393021, 10163.034511721375, 10414.375137491776, 10775.29960552593, 10454.42500662471, 10628.468576571295, 10657.893421066, 10784.299020563638, 11473.885261147281, 10020.549075291776, 10103.696889093339, 10192.643257485952, 10223.897761022592, 10700.0, 7889.3294070004695, 8252.381071419788, 8538.10349913275, 8610.82778344417, 8919.955400222958, 8778.56107194625, 8835.69075082369, 8860.822783544165, 9026.684066057656, 9084.500352480676], "value": 6768.923076741246, "unit": "score"}], "name": "dromaeo_css", "value": 7159.590993181123, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 115.0, 120.0, 120.0, 119.0, 137.0, 118.0, 167.0, 172.0, 124.0], "value": 131.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 135.0, 145.0, 171.0, 151.0, 174.0, 136.0, 159.0, 208.0, 157.0], "value": 162.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 202.0, 205.0, 201.0, 201.0, 200.0, 199.0, 229.0, 193.0], "value": 202.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 74.0, 148.0, 57.0, 57.0, 61.0, 62.0, 135.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 129.0, 79.0, 80.0, 88.0, 123.0, 83.0, 80.0, 122.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 89.0, 88.0, 108.0, 109.0, 91.0, 93.0, 90.0, 88.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 96.0, 118.0, 119.0, 96.0, 96.0, 96.0, 96.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 154.0, 90.0, 93.0, 88.0, 89.0, 91.0, 89.0, 90.0, 91.0], "value": 98.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 76.0, 100.0, 98.0, 102.0, 89.0, 66.0, 77.0, 61.0, 59.0], "value": 78.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 76.0, 71.0, 75.0, 80.0, 73.0, 66.0, 70.0, 69.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 85.0, 82.0, 83.0, 85.0, 83.0, 86.0, 91.0, 96.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 157.0, 156.0, 136.0, 140.0, 141.0, 138.0, 135.0, 165.0, 134.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 175.0, 176.0, 185.0, 171.0, 169.0, 173.0, 173.0, 175.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 68.0, 70.0, 68.0, 70.0, 68.0, 76.0, 70.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1591.0999999999997, "alertThreshold": 2.0}]} 12:53:07 INFO - Return code: 0 12:53:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:53:07 ERROR - # TBPL SUCCESS # 12:53:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:53:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:53:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:53:07 INFO - 2016-05-05 12:53:07.726 screenresolution[2518:27658] starting screenresolution argv=screenresolution get 12:53:07 INFO - 2016-05-05 12:53:07.729 screenresolution[2518:27658] Display 0: 1600x1200x32@60 12:53:07 INFO - 2016-05-05 12:53:07.735 screenresolution[2519:27661] starting screenresolution argv=screenresolution list 12:53:07 INFO - Available Modes on Display 0 12:53:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:53:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:53:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:53:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:53:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:53:07 INFO - Intel Iris: 12:53:07 INFO - Chipset Model: Intel Iris 12:53:07 INFO - Type: GPU 12:53:07 INFO - Bus: Built-In 12:53:07 INFO - VRAM (Dynamic, Max): 1536 MB 12:53:07 INFO - Vendor: Intel (0x8086) 12:53:07 INFO - Device ID: 0x0a2e 12:53:07 INFO - Revision ID: 0x0009 12:53:07 INFO - Displays: 12:53:07 INFO - Display: 12:53:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:53:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:53:07 INFO - Display Serial Number: 1600x1200 60 12:53:07 INFO - Main Display: Yes 12:53:07 INFO - Mirror: Off 12:53:07 INFO - Online: Yes 12:53:07 INFO - Rotation: Supported 12:53:07 INFO - Return code: 0 12:53:07 INFO - Running post-action listener: _resource_record_post_action 12:53:07 INFO - Running post-run listener: _resource_record_post_run 12:53:09 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 679936; Write bytes: 654118400; Read time: 7; Write time: 1784 12:53:09 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350829056; Write bytes: 346732032; Read time: 27005; Write time: 644 12:53:09 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 679936; Write bytes: 296597504; Read time: 7; Write time: 1087 12:53:09 INFO - Running post-run listener: _upload_blobber_files 12:53:09 INFO - Blob upload gear active. 12:53:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:53:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:53:09 INFO - (blobuploader) - INFO - Open directory for files ... 12:53:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:53:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:53:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:53:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:53:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:53:10 INFO - (blobuploader) - INFO - Done attempting. 12:53:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:53:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:53:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:53:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:53:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:53:11 INFO - (blobuploader) - INFO - Done attempting. 12:53:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:53:11 INFO - Return code: 0 12:53:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:53:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:53:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3113156bc4a96bc59debc63b2d3bb45b04d5777dd565b2c2633127ce6d49533746055ab1243540d46522594ef79468937a9e6ea273e4d201e15f27bde1411e65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd852d39b409104bc63fcf8bfeaaf095e00ea2b910eeef6f61b36df427e16727ff05a67dcf841f5ac7cc4faa800683915070bb7c9734b66e0bcdb33197b7425a"} 12:53:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:53:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:53:11 INFO - Contents: 12:53:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3113156bc4a96bc59debc63b2d3bb45b04d5777dd565b2c2633127ce6d49533746055ab1243540d46522594ef79468937a9e6ea273e4d201e15f27bde1411e65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd852d39b409104bc63fcf8bfeaaf095e00ea2b910eeef6f61b36df427e16727ff05a67dcf841f5ac7cc4faa800683915070bb7c9734b66e0bcdb33197b7425a"} 12:53:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:53:11 INFO - Copying logs to upload dir... 12:53:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:53:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.096921 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-05 12:53:11.354310) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 12:53:11.357730) ========= 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.dQkBqOCPSb/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.2tWXImbQRW/Listeners TMPDIR=/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/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/3113156bc4a96bc59debc63b2d3bb45b04d5777dd565b2c2633127ce6d49533746055ab1243540d46522594ef79468937a9e6ea273e4d201e15f27bde1411e65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd852d39b409104bc63fcf8bfeaaf095e00ea2b910eeef6f61b36df427e16727ff05a67dcf841f5ac7cc4faa800683915070bb7c9734b66e0bcdb33197b7425a"} build_url:https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011070 build_url: 'https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/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/3113156bc4a96bc59debc63b2d3bb45b04d5777dd565b2c2633127ce6d49533746055ab1243540d46522594ef79468937a9e6ea273e4d201e15f27bde1411e65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd852d39b409104bc63fcf8bfeaaf095e00ea2b910eeef6f61b36df427e16727ff05a67dcf841f5ac7cc4faa800683915070bb7c9734b66e0bcdb33197b7425a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 12:53:11.385358) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:53:11.385732) ========= 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.dQkBqOCPSb/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.2tWXImbQRW/Listeners TMPDIR=/var/folders/3p/glv0cyh572g29ns0_2zrypmr00000w/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.004673 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:53:11.434379) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 12:53:11.434720) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 12:53:11.435064) ========= ========= Total master_lag: 0.15 =========