builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0092 starttime: 1463039085.42 results: success (0) buildid: 20160511222711 builduid: 45fab69c536f4eca97bc2590092cddd9 revision: 901b6248af7d34012dc519cef40ec38c034f1c39 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.423225) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.423688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.423965) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.441543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.441967) ========= 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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-12 00:44:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.25M=0.001s 2016-05-12 00:44:45 (8.25 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127655 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.586379) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.586721) ========= 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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.020940 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.637752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:45.638132) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 901b6248af7d34012dc519cef40ec38c034f1c39 --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 901b6248af7d34012dc519cef40ec38c034f1c39 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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-12 00:44:45,703 truncating revision to first 12 chars 2016-05-12 00:44:45,703 Setting DEBUG logging. 2016-05-12 00:44:45,703 attempt 1/10 2016-05-12 00:44:45,703 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/901b6248af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 00:44:46,438 unpacking tar archive at: mozilla-aurora-901b6248af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980241 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:46.636077) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:46.636427) ========= script_repo_revision: 901b6248af7d34012dc519cef40ec38c034f1c39 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:46.636864) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:46.637236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 00:44:46.651108) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-12 00:44:46.651530) ========= /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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:44:46 INFO - MultiFileLogger online at 20160512 00:44:46 in /builds/slave/test 00:44:46 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 00:44:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:44:46 INFO - {'append_to_log': False, 00:44:46 INFO - 'base_work_dir': '/builds/slave/test', 00:44:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 00:44:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:44:46 INFO - 'branch': 'Mozilla-Aurora', 00:44:46 INFO - 'buildbot_json_path': 'buildprops.json', 00:44:46 INFO - 'config_files': ('talos/mac_config.py',), 00:44:46 INFO - 'default_actions': ('clobber', 00:44:46 INFO - 'read-buildbot-config', 00:44:46 INFO - 'download-and-extract', 00:44:46 INFO - 'populate-webroot', 00:44:46 INFO - 'create-virtualenv', 00:44:46 INFO - 'install', 00:44:46 INFO - 'run-tests'), 00:44:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:44:46 INFO - 'download_minidump_stackwalk': True, 00:44:46 INFO - 'download_symbols': 'ondemand', 00:44:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:44:46 INFO - 'tooltool.py': '/tools/tooltool.py', 00:44:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:44:46 INFO - '/tools/misc-python/virtualenv.py')}, 00:44:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:44:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:44:46 INFO - 'installer_path': 'installer.exe', 00:44:46 INFO - 'log_level': 'info', 00:44:46 INFO - 'log_name': 'talos', 00:44:46 INFO - 'log_to_console': True, 00:44:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:44:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:44:46 INFO - 'opt_config_files': (), 00:44:46 INFO - 'pip_index': False, 00:44:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:44:46 INFO - 'cmd': ('bash', 00:44:46 INFO - '-c', 00:44:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:44:46 INFO - 'enabled': True, 00:44:46 INFO - 'halt_on_failure': False, 00:44:46 INFO - 'name': 'check_screen_resolution'},), 00:44:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:44:46 INFO - 'cmd': ('bash', 00:44:46 INFO - '-c', 00:44:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:44:46 INFO - 'enabled': True, 00:44:46 INFO - 'halt_on_failure': False, 00:44:46 INFO - 'name': 'check_screen_resolution'},), 00:44:46 INFO - 'run_cmd_checks_enabled': True, 00:44:46 INFO - 'sps_profile': False, 00:44:46 INFO - 'sps_profile_interval': 0, 00:44:46 INFO - 'suite': 'dromaeojs-e10s', 00:44:46 INFO - 'system_bits': '32', 00:44:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:44:46 INFO - 'title': 't-yosemite-r7-0092', 00:44:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:44:46 INFO - 'use_talos_json': True, 00:44:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:44:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:44:46 INFO - 'work_dir': 'build'} 00:44:46 INFO - ##### 00:44:46 INFO - ##### Running clobber step. 00:44:46 INFO - ##### 00:44:46 INFO - Running pre-action listener: _resource_record_pre_action 00:44:46 INFO - Running main action method: clobber 00:44:46 INFO - rmtree: /builds/slave/test/build 00:44:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:44:48 INFO - Running post-action listener: _resource_record_post_action 00:44:48 INFO - ##### 00:44:48 INFO - ##### Running read-buildbot-config step. 00:44:48 INFO - ##### 00:44:48 INFO - Running pre-action listener: _resource_record_pre_action 00:44:48 INFO - Running main action method: read_buildbot_config 00:44:48 INFO - Using buildbot properties: 00:44:48 INFO - { 00:44:48 INFO - "project": "", 00:44:48 INFO - "product": "firefox", 00:44:48 INFO - "script_repo_revision": "production", 00:44:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 00:44:48 INFO - "repository": "", 00:44:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 00:44:48 INFO - "buildid": "20160511222711", 00:44:48 INFO - "pgo_build": "False", 00:44:48 INFO - "basedir": "/builds/slave/test", 00:44:48 INFO - "buildnumber": 327, 00:44:48 INFO - "slavename": "t-yosemite-r7-0092", 00:44:48 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 00:44:48 INFO - "platform": "yosemite_r7", 00:44:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 00:44:48 INFO - "branch": "mozilla-aurora", 00:44:48 INFO - "repo_path": "releases/mozilla-aurora", 00:44:48 INFO - "revision": "901b6248af7d34012dc519cef40ec38c034f1c39", 00:44:48 INFO - "stage_platform": "macosx64", 00:44:48 INFO - "builduid": "45fab69c536f4eca97bc2590092cddd9", 00:44:48 INFO - "slavebuilddir": "test" 00:44:48 INFO - } 00:44:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 00:44:48 INFO - Running post-action listener: _resource_record_post_action 00:44:48 INFO - ##### 00:44:48 INFO - ##### Running download-and-extract step. 00:44:48 INFO - ##### 00:44:48 INFO - Running pre-action listener: _resource_record_pre_action 00:44:48 INFO - Running main action method: download_and_extract 00:44:48 INFO - mkdir: /builds/slave/test/build/tests 00:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:44:48 INFO - https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:44:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 00:44:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 00:44:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:44:50 INFO - Downloaded 1199 bytes. 00:44:50 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 00:44:50 INFO - Using the following test package requirements: 00:44:50 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 00:44:50 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 00:44:50 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 00:44:50 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'jsshell-mac.zip'], 00:44:50 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 00:44:50 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 00:44:50 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 00:44:50 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 00:44:50 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 00:44:50 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:44:50 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 00:44:50 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 00:44:50 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')]} 00:44:50 INFO - https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:44:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:44:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:44:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:44:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:44:53 INFO - Downloaded 18089431 bytes. 00:44:53 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'] 00:44:53 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 00:44:56 INFO - Return code: 0 00:44:56 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 00:44: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')]} 00:44:56 INFO - https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:44:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:44:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:44:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:45:03 INFO - Downloaded 18089431 bytes. 00:45:03 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'] 00:45:03 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 00:45:07 INFO - Return code: 0 00:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:07 INFO - https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:45:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 00:45:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 00:45:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:45:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:45:08 INFO - Downloaded 10840145 bytes. 00:45:08 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'] 00:45:08 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 00:45:08 INFO - Return code: 0 00:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:08 INFO - https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 00:45:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:45:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:45:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 00:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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 00:45:14 INFO - Downloaded 114122129 bytes. 00:45:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:45:14 INFO - mkdir: /builds/slave/test/properties 00:45:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:45:14 INFO - Writing to file /builds/slave/test/properties/build_url 00:45:14 INFO - Contents: 00:45:14 INFO - build_url:https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:45:15 INFO - Running post-action listener: _resource_record_post_action 00:45:15 INFO - Running post-action listener: set_extra_try_arguments 00:45:15 INFO - ##### 00:45:15 INFO - ##### Running populate-webroot step. 00:45:15 INFO - ##### 00:45:15 INFO - Running pre-action listener: _resource_record_pre_action 00:45:15 INFO - Running main action method: populate_webroot 00:45:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:45:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:45:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:45:15 INFO - '--tppagecycles', 00:45:15 INFO - '7'], 00:45:15 INFO - 'tests': ['tsvgm']}}, 00:45:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:45:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:45:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:45:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:45:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:45:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:45:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['damp', 'tps']}, 00:45:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'tests': ['damp', 'tps']}, 00:45:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['dromaeo_dom']}, 00:45:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:45:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['a11yr', 00:45:15 INFO - 'ts_paint', 00:45:15 INFO - 'tpaint', 00:45:15 INFO - 'sessionrestore', 00:45:15 INFO - 'sessionrestore_no_auto_restore', 00:45:15 INFO - 'tabpaint']}, 00:45:15 INFO - 'other-e10s': {'tests': ['a11yr', 00:45:15 INFO - 'ts_paint', 00:45:15 INFO - 'tpaint', 00:45:15 INFO - 'sessionrestore', 00:45:15 INFO - 'sessionrestore_no_auto_restore', 00:45:15 INFO - 'tabpaint']}, 00:45:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:45:15 INFO - 'ts_paint', 00:45:15 INFO - 'tpaint', 00:45:15 INFO - 'sessionrestore', 00:45:15 INFO - 'sessionrestore_no_auto_restore', 00:45:15 INFO - 'tabpaint']}, 00:45:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:45:15 INFO - 'ts_paint', 00:45:15 INFO - 'tpaint', 00:45:15 INFO - 'sessionrestore', 00:45:15 INFO - 'sessionrestore_no_auto_restore', 00:45:15 INFO - 'tabpaint']}, 00:45:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['a11yr', 00:45:15 INFO - 'ts_paint', 00:45:15 INFO - 'tpaint', 00:45:15 INFO - 'sessionrestore', 00:45:15 INFO - 'sessionrestore_no_auto_restore', 00:45:15 INFO - 'tabpaint']}, 00:45:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['a11yr', 00:45:15 INFO - 'ts_paint', 00:45:15 INFO - 'tpaint', 00:45:15 INFO - 'sessionrestore', 00:45:15 INFO - 'sessionrestore_no_auto_restore', 00:45:15 INFO - 'tabpaint']}, 00:45:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['tsvgx', 00:45:15 INFO - 'tsvgr_opacity', 00:45:15 INFO - 'tart', 00:45:15 INFO - 'tscrollx', 00:45:15 INFO - 'cart']}, 00:45:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:45:15 INFO - 'tsvgr_opacity', 00:45:15 INFO - 'tart', 00:45:15 INFO - 'tscrollx', 00:45:15 INFO - 'cart']}, 00:45:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'talos_options': ['--disable-e10s'], 00:45:15 INFO - 'tests': ['tp5o']}, 00:45:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'tests': ['tp5o']}, 00:45:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'talos_options': ['--disable-e10s', 00:45:15 INFO - '--xperf_path', 00:45:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:45:15 INFO - 'tests': ['tp5n']}, 00:45:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:15 INFO - 'talos_options': ['--xperf_path', 00:45:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:45:15 INFO - 'tests': ['tp5n']}}, 00:45:15 INFO - 'talos.zip': {'path': '', 00:45:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:45:15 INFO - Running post-action listener: _resource_record_post_action 00:45:15 INFO - ##### 00:45:15 INFO - ##### Running create-virtualenv step. 00:45:15 INFO - ##### 00:45:15 INFO - Running pre-action listener: _resource_record_pre_action 00:45:15 INFO - Running main action method: create_virtualenv 00:45:15 INFO - Creating virtualenv /builds/slave/test/build/venv 00:45:15 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 00:45:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:45:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:45:15 INFO - Using real prefix '/tools/python27' 00:45:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:45:16 INFO - Installing distribute.............................................................................................................................................................................................done. 00:45:19 INFO - Installing pip.................done. 00:45:19 INFO - Return code: 0 00:45:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:19 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:19 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 00:45:19 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 00:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:19 INFO - 'HOME': '/Users/cltbld', 00:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:19 INFO - 'LOGNAME': 'cltbld', 00:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:19 INFO - 'MOZ_NO_REMOTE': '1', 00:45:19 INFO - 'NO_EM_RESTART': '1', 00:45:19 INFO - 'PAGER': '/bin/cat', 00:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:19 INFO - 'PWD': '/builds/slave/test', 00:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:19 INFO - 'SHELL': '/bin/bash', 00:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:19 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:19 INFO - 'USER': 'cltbld', 00:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:19 INFO - 'XPC_FLAGS': '0x0', 00:45:19 INFO - 'XPC_SERVICE_NAME': '0', 00:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:19 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 00:45:19 INFO - Cleaning up... 00:45:19 INFO - Return code: 0 00:45:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:19 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:19 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 00:45:19 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 00:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:19 INFO - 'HOME': '/Users/cltbld', 00:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:19 INFO - 'LOGNAME': 'cltbld', 00:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:19 INFO - 'MOZ_NO_REMOTE': '1', 00:45:19 INFO - 'NO_EM_RESTART': '1', 00:45:19 INFO - 'PAGER': '/bin/cat', 00:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:19 INFO - 'PWD': '/builds/slave/test', 00:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:19 INFO - 'SHELL': '/bin/bash', 00:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:19 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:19 INFO - 'USER': 'cltbld', 00:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:19 INFO - 'XPC_FLAGS': '0x0', 00:45:19 INFO - 'XPC_SERVICE_NAME': '0', 00:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:19 INFO - Downloading/unpacking psutil>=0.7.1 00:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:45:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:45:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:45:22 INFO - Installing collected packages: psutil 00:45:22 INFO - Running setup.py install for psutil 00:45:22 INFO - building 'psutil._psutil_osx' extension 00:45:22 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 00:45:23 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 00:45:23 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 00:45:23 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 00:45:23 INFO - building 'psutil._psutil_posix' extension 00:45:23 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 00:45:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:45:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:45:23 INFO - ^ 00:45:23 INFO - 1 warning generated. 00:45:23 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 00:45:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:45:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:45:23 INFO - Successfully installed psutil 00:45:23 INFO - Cleaning up... 00:45:23 INFO - Return code: 0 00:45:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:45:23 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')]} 00:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:23 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')]} 00:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:23 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:23 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 00:45:23 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 00:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:23 INFO - 'HOME': '/Users/cltbld', 00:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:23 INFO - 'LOGNAME': 'cltbld', 00:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:23 INFO - 'MOZ_NO_REMOTE': '1', 00:45:23 INFO - 'NO_EM_RESTART': '1', 00:45:23 INFO - 'PAGER': '/bin/cat', 00:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:23 INFO - 'PWD': '/builds/slave/test', 00:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:23 INFO - 'SHELL': '/bin/bash', 00:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:23 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:23 INFO - 'USER': 'cltbld', 00:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:23 INFO - 'XPC_FLAGS': '0x0', 00:45:23 INFO - 'XPC_SERVICE_NAME': '0', 00:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:45:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:45:26 INFO - Installing collected packages: mozsystemmonitor 00:45:26 INFO - Running setup.py install for mozsystemmonitor 00:45:26 INFO - Successfully installed mozsystemmonitor 00:45:26 INFO - Cleaning up... 00:45:26 INFO - Return code: 0 00:45:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:45:26 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')]} 00:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:26 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')]} 00:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:26 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:26 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 00:45:26 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 00:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:26 INFO - 'HOME': '/Users/cltbld', 00:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:26 INFO - 'LOGNAME': 'cltbld', 00:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:26 INFO - 'MOZ_NO_REMOTE': '1', 00:45:26 INFO - 'NO_EM_RESTART': '1', 00:45:26 INFO - 'PAGER': '/bin/cat', 00:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:26 INFO - 'PWD': '/builds/slave/test', 00:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:26 INFO - 'SHELL': '/bin/bash', 00:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:26 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:26 INFO - 'USER': 'cltbld', 00:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:26 INFO - 'XPC_FLAGS': '0x0', 00:45:26 INFO - 'XPC_SERVICE_NAME': '0', 00:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:26 INFO - Downloading/unpacking blobuploader==1.2.4 00:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:29 INFO - Downloading blobuploader-1.2.4.tar.gz 00:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:45:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:45:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:30 INFO - Downloading docopt-0.6.1.tar.gz 00:45:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:45:30 INFO - Installing collected packages: blobuploader, requests, docopt 00:45:30 INFO - Running setup.py install for blobuploader 00:45:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:45:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:45:30 INFO - Running setup.py install for requests 00:45:31 INFO - Running setup.py install for docopt 00:45:31 INFO - Successfully installed blobuploader requests docopt 00:45:31 INFO - Cleaning up... 00:45:31 INFO - Return code: 0 00:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:45:31 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')]} 00:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:31 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')]} 00:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:31 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:31 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 00:45:31 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 00:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:31 INFO - 'HOME': '/Users/cltbld', 00:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:31 INFO - 'LOGNAME': 'cltbld', 00:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:31 INFO - 'MOZ_NO_REMOTE': '1', 00:45:31 INFO - 'NO_EM_RESTART': '1', 00:45:31 INFO - 'PAGER': '/bin/cat', 00:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:31 INFO - 'PWD': '/builds/slave/test', 00:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:31 INFO - 'SHELL': '/bin/bash', 00:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:31 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:31 INFO - 'USER': 'cltbld', 00:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:31 INFO - 'XPC_FLAGS': '0x0', 00:45:31 INFO - 'XPC_SERVICE_NAME': '0', 00:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:45:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-G2i3OZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:45:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-k_oDf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:45:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-4rto8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:45:31 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-hB1ve0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-wzW8QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-eNnYkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-CWGKGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-FyShGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-cSXpCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-KMwObm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-ShjOxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-zlUERz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:45:32 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-F1qh4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:45:33 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-TtCTxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:33 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-ghnV9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:45:33 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-6ft8w3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:45:33 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-pFyKfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:45:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:45:33 INFO - Running setup.py install for manifestparser 00:45:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:45:33 INFO - Running setup.py install for mozcrash 00:45:33 INFO - Running setup.py install for mozdebug 00:45:33 INFO - Running setup.py install for mozdevice 00:45:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Running setup.py install for mozfile 00:45:34 INFO - Running setup.py install for mozhttpd 00:45:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Running setup.py install for mozinfo 00:45:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Running setup.py install for mozInstall 00:45:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Running setup.py install for mozleak 00:45:34 INFO - Running setup.py install for mozlog 00:45:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:45:34 INFO - Running setup.py install for moznetwork 00:45:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:45:35 INFO - Running setup.py install for mozprocess 00:45:35 INFO - Running setup.py install for mozprofile 00:45:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:45:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:45:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:45:35 INFO - Running setup.py install for mozrunner 00:45:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:45:35 INFO - Running setup.py install for mozscreenshot 00:45:35 INFO - Running setup.py install for moztest 00:45:35 INFO - Running setup.py install for mozversion 00:45:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:45:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:45:36 INFO - Cleaning up... 00:45:36 INFO - Return code: 0 00:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:36 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:36 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 00:45:36 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 00:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:36 INFO - 'HOME': '/Users/cltbld', 00:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:36 INFO - 'LOGNAME': 'cltbld', 00:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:36 INFO - 'MOZ_NO_REMOTE': '1', 00:45:36 INFO - 'NO_EM_RESTART': '1', 00:45:36 INFO - 'PAGER': '/bin/cat', 00:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:36 INFO - 'PWD': '/builds/slave/test', 00:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:36 INFO - 'SHELL': '/bin/bash', 00:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:36 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:36 INFO - 'USER': 'cltbld', 00:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:36 INFO - 'XPC_FLAGS': '0x0', 00:45:36 INFO - 'XPC_SERVICE_NAME': '0', 00:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-InuHz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:45:36 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)) 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-f1dltI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:45:36 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)) 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-_joxny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:45:36 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)) 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-rwk0Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:45:36 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)) 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-2HTt_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:45:36 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)) 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-71VpJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:45:36 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)) 00:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:45:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-pr4iCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-_Zwu7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-sIlpY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-rZY0_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-VbSy1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-R0g5vV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-2CEGAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-OmBYaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-SIr1CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-48ixhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:45:37 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)) 00:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:45:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-6QvhCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45:40 INFO - Downloading blessings-1.6.tar.gz 00:45:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:45:40 INFO - Installing collected packages: blessings 00:45:40 INFO - Running setup.py install for blessings 00:45:40 INFO - Successfully installed blessings 00:45:40 INFO - Cleaning up... 00:45:40 INFO - Return code: 0 00:45:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:45:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:45:41 INFO - Reading from file tmpfile_stdout 00:45:41 INFO - Current package versions: 00:45:41 INFO - blessings == 1.6 00:45:41 INFO - blobuploader == 1.2.4 00:45:41 INFO - docopt == 0.6.1 00:45:41 INFO - manifestparser == 1.1 00:45:41 INFO - mozInstall == 1.12 00:45:41 INFO - mozcrash == 0.17 00:45:41 INFO - mozdebug == 0.1 00:45:41 INFO - mozdevice == 0.48 00:45:41 INFO - mozfile == 1.2 00:45:41 INFO - mozhttpd == 0.7 00:45:41 INFO - mozinfo == 0.9 00:45:41 INFO - mozleak == 0.1 00:45:41 INFO - mozlog == 3.1 00:45:41 INFO - moznetwork == 0.27 00:45:41 INFO - mozprocess == 0.22 00:45:41 INFO - mozprofile == 0.28 00:45:41 INFO - mozrunner == 6.11 00:45:41 INFO - mozscreenshot == 0.1 00:45:41 INFO - mozsystemmonitor == 0.0 00:45:41 INFO - moztest == 0.7 00:45:41 INFO - mozversion == 1.4 00:45:41 INFO - psutil == 3.1.1 00:45:41 INFO - requests == 1.2.3 00:45:41 INFO - wsgiref == 0.1.2 00:45:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:45:41 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')]} 00:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:41 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')]} 00:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:41 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:41 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 00:45:41 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 00:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:41 INFO - 'HOME': '/Users/cltbld', 00:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:41 INFO - 'LOGNAME': 'cltbld', 00:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:41 INFO - 'MOZ_NO_REMOTE': '1', 00:45:41 INFO - 'NO_EM_RESTART': '1', 00:45:41 INFO - 'PAGER': '/bin/cat', 00:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:41 INFO - 'PWD': '/builds/slave/test', 00:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:41 INFO - 'SHELL': '/bin/bash', 00:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:41 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:41 INFO - 'USER': 'cltbld', 00:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:41 INFO - 'XPC_FLAGS': '0x0', 00:45:41 INFO - 'XPC_SERVICE_NAME': '0', 00:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 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)) 00:45:41 INFO - Cleaning up... 00:45:41 INFO - Return code: 0 00:45:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:45:41 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')]} 00:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:41 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')]} 00:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:41 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:41 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 00:45:41 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 00:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:41 INFO - 'HOME': '/Users/cltbld', 00:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:41 INFO - 'LOGNAME': 'cltbld', 00:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:41 INFO - 'MOZ_NO_REMOTE': '1', 00:45:41 INFO - 'NO_EM_RESTART': '1', 00:45:41 INFO - 'PAGER': '/bin/cat', 00:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:41 INFO - 'PWD': '/builds/slave/test', 00:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:41 INFO - 'SHELL': '/bin/bash', 00:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:41 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:41 INFO - 'USER': 'cltbld', 00:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:41 INFO - 'XPC_FLAGS': '0x0', 00:45:41 INFO - 'XPC_SERVICE_NAME': '0', 00:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:41 INFO - Downloading/unpacking jsonschema 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:45:44 INFO - Downloading/unpacking functools32 (from jsonschema) 00:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:44 INFO - Downloading functools32-3.2.3-2.tar.gz 00:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:45:44 INFO - warning: no files found matching '*.txt' 00:45:44 INFO - no previously-included directories found matching 'build' 00:45:44 INFO - no previously-included directories found matching 'dist' 00:45:44 INFO - no previously-included directories found matching '.git*' 00:45:44 INFO - Installing collected packages: jsonschema, functools32 00:45:44 INFO - Running setup.py install for functools32 00:45:44 INFO - warning: no files found matching '*.txt' 00:45:44 INFO - no previously-included directories found matching 'build' 00:45:44 INFO - no previously-included directories found matching 'dist' 00:45:44 INFO - no previously-included directories found matching '.git*' 00:45:44 INFO - Successfully installed jsonschema functools32 00:45:44 INFO - Cleaning up... 00:45:44 INFO - Return code: 0 00:45:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:45 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 0x10f0e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe183426aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, '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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 00:45:45 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 00:45:45 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 00:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:45 INFO - 'HOME': '/Users/cltbld', 00:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:45 INFO - 'LOGNAME': 'cltbld', 00:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:45 INFO - 'MOZ_NO_REMOTE': '1', 00:45:45 INFO - 'NO_EM_RESTART': '1', 00:45:45 INFO - 'PAGER': '/bin/cat', 00:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:45 INFO - 'PWD': '/builds/slave/test', 00:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:45 INFO - 'SHELL': '/bin/bash', 00:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:45:45 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:45 INFO - 'USER': 'cltbld', 00:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:45 INFO - 'XPC_FLAGS': '0x0', 00:45:45 INFO - 'XPC_SERVICE_NAME': '0', 00:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:45 INFO - Downloading/unpacking flake8 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:45:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:45:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 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 00:45:48 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 00:45:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:45:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:45:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:45:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:45:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:45:49 INFO - no previously-included directories found matching 'docs/_build' 00:45:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:45:49 INFO - Running setup.py install for pep8 00:45:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:45:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:45:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:45:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:45:49 INFO - no previously-included directories found matching 'docs/_build' 00:45:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:45:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:45:49 INFO - Cleaning up... 00:45:50 INFO - Return code: 0 00:45:50 INFO - Running post-action listener: _resource_record_post_action 00:45:50 INFO - Running post-action listener: _start_resource_monitoring 00:45:50 INFO - Starting resource monitoring. 00:45:50 INFO - ##### 00:45:50 INFO - ##### Running install step. 00:45:50 INFO - ##### 00:45:50 INFO - Running pre-action listener: _resource_record_pre_action 00:45:50 INFO - Running main action method: install 00:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:45:50 INFO - Reading from file tmpfile_stdout 00:45:50 INFO - Detecting whether we're running mozinstall >=1.0... 00:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:45:50 INFO - Reading from file tmpfile_stdout 00:45:50 INFO - Output received: 00:45:50 INFO - Usage: mozinstall [options] installer 00:45:50 INFO - Options: 00:45:50 INFO - -h, --help show this help message and exit 00:45:50 INFO - -d DEST, --destination=DEST 00:45:50 INFO - Directory to install application into. [default: 00:45:50 INFO - "/builds/slave/test"] 00:45:50 INFO - --app=APP Application being installed. [default: firefox] 00:45:50 INFO - mkdir: /builds/slave/test/build/application 00:45:50 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'] 00:45:50 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 00:46:21 INFO - Reading from file tmpfile_stdout 00:46:21 INFO - Output received: 00:46:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 00:46:21 INFO - Running post-action listener: _resource_record_post_action 00:46:21 INFO - ##### 00:46:21 INFO - ##### Running run-tests step. 00:46:21 INFO - ##### 00:46:21 INFO - Running pre-action listener: _resource_record_pre_action 00:46:21 INFO - Running main action method: run_tests 00:46:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:46:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:46:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:46:21 INFO - 2016-05-12 00:46:21.942 screenresolution[2174:25046] starting screenresolution argv=screenresolution get 00:46:21 INFO - 2016-05-12 00:46:21.945 screenresolution[2174:25046] Display 0: 1600x1200x32@60 00:46:21 INFO - 2016-05-12 00:46:21.951 screenresolution[2175:25049] starting screenresolution argv=screenresolution list 00:46:21 INFO - Available Modes on Display 0 00:46:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:46:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:46:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:46:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:46:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:46:22 INFO - Intel Iris: 00:46:22 INFO - Chipset Model: Intel Iris 00:46:22 INFO - Type: GPU 00:46:22 INFO - Bus: Built-In 00:46:22 INFO - VRAM (Dynamic, Max): 1536 MB 00:46:22 INFO - Vendor: Intel (0x8086) 00:46:22 INFO - Device ID: 0x0a2e 00:46:22 INFO - Revision ID: 0x0009 00:46:22 INFO - Displays: 00:46:22 INFO - Display: 00:46:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:46:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:46:22 INFO - Display Serial Number: 1600x1200 60 00:46:22 INFO - Main Display: Yes 00:46:22 INFO - Mirror: Off 00:46:22 INFO - Online: Yes 00:46:22 INFO - Rotation: Supported 00:46:22 INFO - Return code: 0 00:46:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:46:22 INFO - Python 2.7.3 00:46:22 INFO - Return code: 0 00:46:22 INFO - grabbing minidump binary from tooltool 00:46: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')]} 00:46:22 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 0x10ef87e00>, '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 0x10edf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef82b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:46:22 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 00:46:22 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 00:46:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:46:22 INFO - Return code: 0 00:46:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:46:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:46:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:46:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:46:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:46:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:46:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:46:23 INFO - Return code: 0 00:46:23 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/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 00:46:23 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/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --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 00:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nQbfJZcBUm/Render', 00:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:46:23 INFO - 'HOME': '/Users/cltbld', 00:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:46:23 INFO - 'LOGNAME': 'cltbld', 00:46:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:46:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:23 INFO - 'MOZ_NO_REMOTE': '1', 00:46:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:46:23 INFO - 'NO_EM_RESTART': '1', 00:46:23 INFO - 'PAGER': '/bin/cat', 00:46:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:23 INFO - 'PWD': '/builds/slave/test', 00:46:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:46:23 INFO - 'SHELL': '/bin/bash', 00:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l6hKGDp1Sr/Listeners', 00:46:23 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 00:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:46:23 INFO - 'USER': 'cltbld', 00:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:23 INFO - 'XPC_FLAGS': '0x0', 00:46:23 INFO - 'XPC_SERVICE_NAME': '0', 00:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:46:23 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/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 00:46:23 INFO - mozversion application_buildid: 20160511222711 00:46:23 INFO - mozversion application_changeset: 901b6248af7d34012dc519cef40ec38c034f1c39 00:46:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:46:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:46:23 INFO - mozversion application_name: Firefox 00:46:23 INFO - mozversion application_remotingname: firefox-dev 00:46:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:46:23 INFO - mozversion application_vendor: Mozilla 00:46:23 INFO - mozversion application_version: 48.0a2 00:46:23 INFO - mozversion platform_buildid: 20160511222711 00:46:23 INFO - mozversion platform_changeset: 901b6248af7d34012dc519cef40ec38c034f1c39 00:46:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:46:23 INFO - mozversion platform_version: 48.0a2 00:46:23 INFO - using testdate: 1463039183 00:46:23 INFO - actual date: 1463039183 00:46:23 INFO - starting webserver on 'localhost:59738' 00:46:23 INFO - SUITE-START | Running 2 tests 00:46:23 INFO - TEST-START | dromaeo_css 00:46:23 INFO - operating with platform_type : mac_ 00:46:23 INFO - Initialising browser for dromaeo_css test... 00:46:23 INFO - TEST-INFO | started process 2193 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmp7FCxLu/profile http://localhost:59738/getInfo.html) 00:46:26 INFO - PROCESS | 2193 | __metrics Screen width/height:1600/1200 00:46:26 INFO - PROCESS | 2193 | colorDepth:24 00:46:26 INFO - PROCESS | 2193 | Browser inner width/height: 1024/689 00:46:26 INFO - PROCESS | 2193 | __metrics 00:46:26 ERROR - PROCESS | 2193 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 00:46:27 INFO - TEST-INFO | 2193: exit 0 00:46:27 INFO - Browser initialized. 00:46:27 INFO - Running cycle 1/1 for dromaeo_css test... 00:46:27 INFO - TEST-INFO | started process 2196 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmp7FCxLu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:46:39 INFO - PROCESS | 2196 | RSS: Main: 220737536 00:46:39 INFO - PROCESS | 2196 | 00:47:28 INFO - PROCESS | 2196 | 1463039248743 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] 00:49:40 INFO - PROCESS | 2196 | Cycle 1(1): loaded http://localhost:59738/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59738/tests/dromaeo/cssquery-ext.html) 00:49:41 INFO - PROCESS | 2196 | RSS: Main: 234180608 00:49:41 INFO - PROCESS | 2196 | 00:52:44 INFO - PROCESS | 2196 | Cycle 1(1): loaded http://localhost:59738/tests/dromaeo/cssquery-ext.html (next: http://localhost:59738/tests/dromaeo/cssquery-jquery.html) 00:52:44 INFO - PROCESS | 2196 | RSS: Main: 246939648 00:52:44 INFO - PROCESS | 2196 | 00:55:46 INFO - PROCESS | 2196 | Cycle 1(1): loaded http://localhost:59738/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59738/tests/dromaeo/cssquery-mootools.html) 00:55:46 INFO - PROCESS | 2196 | RSS: Main: 247951360 00:55:46 INFO - PROCESS | 2196 | 00:58:48 INFO - PROCESS | 2196 | Cycle 1(1): loaded http://localhost:59738/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59738/tests/dromaeo/cssquery-prototype.html) 00:58:49 INFO - PROCESS | 2196 | RSS: Main: 251756544 00:58:49 INFO - PROCESS | 2196 | 01:01:51 INFO - PROCESS | 2196 | Cycle 1(1): loaded http://localhost:59738/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59738/tests/dromaeo/cssquery-yui.html) 01:01:52 INFO - PROCESS | 2196 | RSS: Main: 251314176 01:01:52 INFO - PROCESS | 2196 | 01:04:54 INFO - PROCESS | 2196 | Cycle 1(1): loaded http://localhost:59738/tests/dromaeo/cssquery-yui.html (next: http://localhost:59738/tests/dromaeo/cssquery-dojo.html) 01:04:55 INFO - PROCESS | 2196 | RSS: Main: 253702144 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | __start_tp_report 01:04:55 INFO - PROCESS | 2196 | _x_x_mozilla_page_load 01:04:55 INFO - PROCESS | 2196 | _x_x_mozilla_page_load_details 01:04:55 INFO - PROCESS | 2196 | |i|pagename|runs| 01:04:55 INFO - PROCESS | 2196 | |0;dojo.html;3114.563961045454;3445.948310775337;3464.2551851351955;3480.686738193562;3500.1424650960503;5640.435956404358;5675.460831221038;5699.088145896658;5703.372629010805;5709.715314054336;5662.263905692254;5694.544436445085;5699.68651724155;5713.942860571393;5748.252727145476;5162.845114646557;5188.584913206934;5197.974010129963;5253.9474605253845;5325.547328477084;5152.278680984665;5253.238280449333;5287;5320.308359913225;5337.546308563777;4568.1548913451115;5233.21501774733;5285.02227087988;5286;5293.708846013467;5200.92198617021;5228.372595288571;5253.527182553569;5265.3681558212875;5288.92066619001;4081.428599996003;4184.644305234059;5166.793328266865;5285.101532739443;5302.840914772563;4881.8535443936735;5202.713850738208;5670.5463562914865;5694.402087780765;5744.626599271076;5980.581359304851;6111.348653136725;6188.628682279078;6217.316095229567;6257.687115644199;6266.216722909637;6279.183706118221;6280.340564240781;6284.088807122987;6289.496840252768;5789.623674461147;5798.710064496759;5819.447152520504;5821.88356232873;5830.387809280007;6192.59748116371;6213.471854892295;6220.533459990519;6261.436470717657;6279.120923070774;6138.631682099089;6164.660943648142;6175.876482470326;6183.474404675563;6184.690765461799;6237.656928868956;6253.749850005949;6261.8121456355475;6279.843003924847;6291.276503202099;6055.091736239599;6112.94623313566;6133.29467111279;6203.410675985774;6258.593191442542;6223.253209614875;6225.844353891099;6238.906416403757;6239.157713708682;6254.624722516573;4755.476897541268;4932.334134891816;4940.6788558743565;4957.107720610325;4978.303037574742;4339.869803905888;4377.693561450732;4386;4410.2282100632265;4446.3108218226225;5743.655380677173;5750.367459579443;5755.827325180251;5757.481826635623;5766.279215098113;6060.090986352083;6155.569110162246;6183.258009038854;6268.843278918063;6300.30696623371;5780.306363236438;5811.27359080115;5813.883722325614;5815.796447124415;5860.970695146497;5374.758135883894;5408.567314614821;5409.945900540944;5416.322959630046;5421.457854214547;6189.566730328834;6206.627602343874;6223.937760622517;6338.841528961812;6341.23905131378;6231.22109736283;6256.843578910563;6271.560990730605;6274.372562743689;6287.842803929939;6323.177986861679;6323.430891219628;6337.081123237012;6349.841253968503;6353.555251132377;6039.63762174271;6106.908396374147;6146.832101900624;6192.938070619236;6225.62646241227;6161.476274516716;6231.096491008869;6254.437100660963;6260.499160067275;6276.90154223;6075.362086980804;6123.08153776937;6125.908111378422;6131.785387511416;6131.816045518642;4946.604271658331;5831.679257640871;5838.182654428469;6120.296165940974;6128.448439640276;5916.881662366643;5956.940430595639;5990.3710110439515;6006.459418652343;6011;5631.239782629293;5667.319921609434;5965.582409231312;6115.510759139347;6122.938770612237;5099.725068732817;5810.360860305447;5848.298204215522;5890.793822216202;5975.43233392827 01:04:55 INFO - PROCESS | 2196 | |1;ext.html;497.2640492071734;519.130456485387;574.5116650846779;576.9182782283219;626.9247690277167;32562.511562326556;39462.80268598653;39487.40768888469;39622;39642;40793.7960310198;41370;41377.58622413783;41390.96522586937;41594.58405415945;9397.20123789477;9646.469444180568;9647;9651.903480965171;9672.70981870545;9592.328537002413;9690.46702431366;9764.170045546138;9778.90221097787;9785.07041831025;9760.4631745254;9777.12005919467;9784.608615655365;9799.265055120859;9829.361091529028;9760;9787.51062446882;9818.330883749779;9836.95081524595;9846.212303015742;9434.103760142776;9558.808823823554;9568.186704130158;9577.808443831085;9598.376105553116;39566;39604;40016.399754003716;40160.196796063916;40182;3380.1042723678247;3434.862605495777;3441.5525981622227;3838.7504812187117;3881.2237552489614;26036.609450858254;27879;28272.293192670142;28662.856685716648;29433.264168395748;2752.2981639681752;2767.1006922750184;2787.414642924988;2798.6921438641734;2813.6482939632547;396190;469524;475404;476779;477453;36147.63852361443;59881.401185988456;59966.400335996084;60036.39963600308;60082;30634.234144146576;35721.571137154744;35875.64124358775;35915;36040.63959360425;15503.457378991681;15707.371705131667;15731.606709832116;15780.68438631221;15792.447264345692;10462;12916.160449570747;13073.411696473491;13182.934085329513;13183.604491865259;7079.964600176966;8282.420230583923;8297.58512074394;8321.334293256525;8386.664533418718;3141.1989942564956;3409.6419875913166;3450.723942084627;3452.2232497688324;3467.982660086734;858.6002124063258;883.5802993578;886.3883920095113;955.0592666223764;1004.2267454060333;26401.603975940376;26694.5995810063;27170;27277.590836137475;27288.863555682095;853.8889944307325;1002.1181360402799;1030.5053574284386;1046.8796088449774;1067.9519421626046;13618;13796.379162937692;13867.514636987657;14092.718145637029;14126.576202713935;527177;620997;628467;640445;643570;61514.38485615176;62304.376956230764;62324.75350492965;62362;62461.375386246465;1327.9800802987963;1440.7609455867946;1478.7042591481747;1495.379417541708;1496.304218538363;25947.351316217246;26383.868080659475;26562.601560976986;26946.191614251602;27051.1884643461;54511.454885450636;54950.17574736462;55020.89958200893;55079.173812392044;55132;534814;544402;547882;551151;551565;25560.360990975376;25876.482470350864;27388.589171162846;28102.85948570244;28122.85938570294;46642;46647.76676116598;47099.52900470951;47891.52108478871;48717.75641121772;30745.692543075176;38812.223755524166;38857.417138743505;39005.414918776805;39180.608193917695;37678;38382.80808595939;38439.807800961935;38590.614093858705;39434.21131577295;33510.66489335173;37471.625283747904;37834.62165378311;38362.6163738359;38427.807860960515;29471.85264073666;31536;33024.66975330216;33468.66531334753;33634.49548256827;21185.152593896073;24495.51008979846;24959;24960;25187.622185666864 01:04:55 INFO - PROCESS | 2196 | |2;jquery.html;409.30418288908845;409.3920528015898;411.34801339876293;416.2050483576369;422.31374017222015;20036.89981550092;20392.388228353135;20801.687974680393;20820.89589552052;21373.14507419705;20604.793952060474;20744.792552074476;21409;21674.916254187305;21755.912204389795;5773.076307790744;5903.557233207505;6035.973884439634;6068.211132552762;6108.175396321506;5103.668261563005;5932.2584676915385;5960.582759206857;5974.820755377345;6037.969810150943;5669.461401166883;5851.531877449794;5867.941320586782;5968.5523585731025;5979.46184843364;5862.237909071836;5905.143754155666;5929.466348028655;5936.46571808535;5938.079597662348;4458;5494.890102197974;5677.233573467571;5818.127280907897;5821.563382746311;9314.627414903396;13092.47630094795;13376.464941402332;24906.5018699625;24984.875680594236;2051.415346626204;2120.7985241402043;2140.6039882621767;2177.379446857638;2252.7898788566013;9706.805863882682;9885.654002109892;10092.091711745905;10094.646687365906;10208.285420020602;1745.1536005037547;1799.1543974332044;1837.2742766607248;1840.2454993452754;1851.0374605205218;106369.46815265951;108580;113956;114398.42800785943;114939.42530287294;11553.24903881245;11850.822237666442;11870.88129118715;11926.46330915111;12021.579244726567;12066;14936.103833770008;15088.698226035418;15103.697926041637;15136.924315378352;8855.291576673852;8951.194392504705;8953.418027828171;8978.86531702025;9074.546272686339;9045.462271413875;9048.954755226181;9128.995810460843;9133.77165570866;9202;4187.20443115807;4632.2125238709505;4769.451513076041;4818.903621927542;4858.489858564871;1599.1924078340364;2001.8098280663314;2044.2947183221765;2046.9795302047087;2068.1520576563535;806.6531391501711;832.6835802395051;884.2925659472525;885.7874110213502;923.9882328849899;18130.909345453187;18400.17199226011;18676.813231867778;18711.37605563835;18735.625287494448;810.9148539403395;853.0829358439578;916.7112359606703;929.4005366538545;931.5109567477128;9678;9698.46658433793;9832.852507212398;9858.950705246429;9971.351862128939;608121;610170;627814;632275;632971;118866;121954;124005;124581;127233;1013.8327176016022;1014.1025192704362;1027.7482016905756;1028.1209565821116;1036.3108532825888;16656.41702540405;16912.069836159128;16972.915135424737;17042.65914681724;17546.298148073933;50510.98978020492;51208;51645;51895.66469508957;52665.47334526606;73935.89096163455;74491.88262175964;74894;75538.62230688811;75701.62149189219;7942.887995680725;8016.839663206587;8126.065502467292;8307.210814972568;8308.584570771558;9827.901720982985;10941.945290273497;12320.938395307967;12448.190867593577;12564;8735;10241.129503992244;10258.794824103326;10356.896431035593;10488.632897848538;10688.946555267174;10715.089217416607;10766;10957.890421095686;10962.506687198937;10220.182385409;10484.63303784364;10538.78922421532;10790.730231743955;10898.782024359627;7165.176004759311;8010.799730006796;8085.595720213848;8304.543250121302;8479.491230526188;9830.918598954253;10317.071463568014;10442.321249118782;10449.320794148356;10668.89331106679 01:04:55 INFO - PROCESS | 2196 | |3;mootools.html;1567.7491601343788;1582.493602047345;1816.981830181698;1934.4293433437135;1988.5823976964837;7838.137804841477;8537.018242902068;8597.742067737961;8680.782980425485;8698.826023479527;6669.899951500732;8114.5393829110735;8342.249197572217;8749.256313213384;8928.866067009;2435.038159926832;2453.6074228123503;2464.8767561621935;2473.2332976777166;2510.3849556858595;2412.517496500698;2470.308313672175;2474.158786012755;2474.7772700456953;2482.094035676974;2441.56051910656;2451.1298489036403;2464.987675061622;2467.592847180213;2472.3942634054592;2428.490017096412;2447;2461.2616215135567;2465.5438743832447;2465.950680986379;2408.5664580375524;2441.121196369306;2443.15711079679;2449.6203088521306;2459.3605662527693;8865.423747456394;8893.86659200106;8942.50816205115;8964.238039766627;8969.775755606162;4645.3032045193495;5310.946890531084;5482.17767334899;5482.671039737588;5485.561155107582;3491.0748651607346;3571.428571428559;3618.6743193112748;3662.7619204751604;3675.687566556846;869.2090197919925;1041.9374837509774;1050.5430137889966;1056.910162636177;1073.62423151897;11323.810999845062;11547.53809847605;11603.361815100166;11630;11631.76736465266;4377.4528183977;4556.84051058218;4627.699199552018;4645.605123564535;4653.674242802971;9290.62837486493;9335.346525743133;9342.90657093434;9349.39228950116;9358.39170453932;5019.799208031638;5285.55072818807;5293.57651387888;5300.072487314705;5358.98179345923;3920.176762879827;4081.2245673321972;4344.478662560513;4793.089313030513;4973.950260497421;3378.9356352748805;3776.527934008249;3842.077901303667;3853.8265778040054;3910.1397692507603;1207.782599132147;1212.5331747277362;1216.1851559455185;1221.9144659873723;1222.4010234984794;766.6212702736165;805.6455159729817;861.7673228228343;879.0637970561331;917.7751450894574;5159.380874295109;5235.921461178085;5573.359063707644;5591.07747221712;5664.178694089334;456.7054250008726;462.81024779840106;470.9011107667428;986.8174387738292;1009.4296722351847;3438.209211881231;3570.393033184365;3686.2627474505207;3699.981500092536;3760.2291530236785;9276.536173191313;10607.363558186533;10741.140708743282;10741.946290268656;10794.136469082454;10398.948005260077;10418.11446027081;10457.738556536147;10644.148468122532;10676.51955661997;6524.347565243438;6919.377256047081;8037.03555573335;8041.236082572146;8195.590220488952;5633.126865335879;5692.089265717465;5719.342275638189;5764.221830052889;5869.6771677556435;8450.619722112397;9434.24526037929;9507.477088760184;9681.612735490784;9720.75698107553;9328.253739700673;9737.464439455614;9836;9855.605775768889;9912.504374781089;6844.315568443116;7205.927940720735;7427.8143046424275;7509.948607194888;7652.38780897538;8368.83262334762;8380.07819139882;8388.286995605442;8431.831363372576;8435.240828325235;6606.8678626426245;7326.633668316456;7403.8149046274275;7477.990471286306;7582.393408527194;7180.856382872418;7281.089863767029;7354.374878135204;7356;7394.852102957803;6786.898196526958;6794.456443484411;6812.761553345609;6856.9314306856295;6889.414399776075;4644.558766917137;4656.3946686930485;4670.13602483528;4772.021735544275;4772.976135119302;4660.091282199938;4687.039156972881;4716.6698331116495;4739.952600473951;4846.200376937766 01:04:55 INFO - PROCESS | 2196 | |4;prototype.html;301.64556645941013;326.6961725595197;328.29089167398416;333.81640097946126;337.58308489016076;5477.424870388601;6166.814995550129;6859.691313890874;6874.518783685137;6878.621675807829;5521.254630624864;6763.458923286137;6829.590224586515;6832.350926661959;6852.55458395204;4120.25835349637;4410;4459.732416055031;5083.79664813407;5090.236464530331;5005.249212618118;5073.949260507385;5088.618353623475;5091.745412729368;5117.411497677777;5016.347874776274;5023.960503895146;5035.320231768701;5041;5050.823221187259;5050.873728156789;5054.368203974504;5060.898782024339;5067.9746601267125;5071.137906555894;4315.4605674290715;4320.567943205654;4324.13517296542;4333.71830830995;4335.1979883721615;6286.510986402544;6623.801285961381;6631.767888123893;6633.104530888317;6663.167104111986;5015.59875209986;5026.798928042874;5519.972400137974;5851.912221316684;5871.324797648283;5614.803481878114;7311.707531698726;7317.743878964264;7323.194448610649;7350.191478937312;4665.2535594304745;4673.322368256584;4674.345591617176;4675.859724208279;4683.086798074377;7527.924720752777;7751.883721744179;7821.843563128762;7864;7872.76381708544;3896.8246428910766;4709.576138147583;4730.550597693214;4747.430308363018;4768.499307572655;4759.952400476021;4796.304535842353;4826.8793280168275;4831.782569784298;4836.661433699677;5803.390643982406;5804.100364443518;5820.534357251409;5824.708764561755;5849.8245052647635;6307.495400368051;7331.706731730777;7343.742968996059;7381.04046473955;7423.48035637511;1656.0312217352744;1671.4400675773509;1673.9956026384073;1674.732042873159;1675.5141009107222;1044.7492601775518;1058.99470502647;1069.5240617924949;1070.593174593665;1071.1056268016139;6789.558678685869;6838.111045564045;6846.0488760702965;6869.793906182823;6897.068895699016;6199.597026193283;7387.409007279405;7590.354819840265;7668.654910528928;7672.5012874163;6811.421029212573;6817.5568588041615;6822.3859852613505;6856.554323968926;6893.689783959633;4082.0611259410502;4110.157417729359;4118.4440100586635;4123.958760412417;4137.172565486914;8117.350611951029;8126.55303958288;8135.959320203479;8145.470544414713;8163.55100469468;7735.033120859893;7952.483088599222;8013.559254240956;8017.599120044091;8020.839583208303;8072.636731347104;8097.068837083811;8107.878381824277;8142.674293028331;8149.7147599833725;6490.188726409199;7771.572563508835;7779.533228006338;7780.377569794516;7784.41616878725;6873.072135261676;7427.5914824683;7609.581473019037;7635.389168866588;7647.694092236248;7738.110117336579;8000.279974802295;8015.278624923551;8053;8064.677412903418;4675.509071547439;4777.092352453023;4786.186348320724;4786.976065119652;4789.377380940456;7734.381249499914;7735.613219338898;7744.806379840549;7766;7768.019155115409;7107.680154392961;7422.925770742223;7440.665170067252;7445.664945077593;7446.776596702108;7405.111386633423;7408.407327413686;7428.33145016951;7461.403087753075;7463.888041679271;5937.940620593911;7253.611565003308;7270.309320614533;7277.59973201479;7302.160251570925;6060.303065147565;7422.851542969002;7428.1030398143685;7463.141738700119;7493.625318734151;6585.703643335965;6997.440204783707;7233.855322893618;7277.235890231449;7295.8540829184185 01:04:55 INFO - PROCESS | 2196 | |5;yui.html;677.5697432130597;677.9355961183687;737.6975440069573;758.0940775772947;763.8434121005195;15065.322060507277;15070.47253346133;15182.772258416106;15269.847301526981;15320.69358612827;15702.057876527417;16024.359025638989;16027;16035.037897726144;16061;733.3290039613747;911.3757076402669;923.4920793563547;925.7824411263348;928.3826255540058;901.5312037740371;911.9088091190894;932.9300302477308;933.7105497295826;934.8644446555245;950.0879156010238;953.0279115302387;984.7538115471132;990.5047476261869;991.5884907763269;971.218169373654;979.8089372572351;982.474376208732;982.4841957972122;982.8427451607708;704.5314865614412;719.0723966083746;730.5835673666013;733.757859906235;734;13390.665233369145;15248.313825877862;15263.923680381527;15462.381504739797;15481.14853683047;12347.19743216697;12361.32012739299;12382;12449.813252801216;12533;8413.705520306821;10082;10445.634402795866;10493.790124197474;10534.947325263402;3437.1750779812915;3450.516927630134;3517.56030496188;3569.446735755962;3721.962780372216;60177.5928962841;80354.19645803585;80859.5957020211;81434.9999999994;81692;8600.139986001475;8842.690505832265;8942.105789421135;9115.225205857576;9122.31582631292;20892.37322880316;20916.37250882476;20991.685124723143;21028.78971210299;21532.708037517797;7268.164161121433;7353.117625884928;7370.815729606696;7407.8888816667795;7572.924270757222;3966.5835087315418;3981.1838573092446;3987.222491614107;4018.1963607278662;4080.5307389649975;3599.3881040223223;3662.102784817737;3673.0633688409284;3684.907877303035;3716.0152559571734;588.4292236530558;646.3245908026124;651.4007113455538;656.7569999100268;666.4135560706644;518.4633903909436;519.2107995846293;572.8825590753886;596.9940650004756;599.604261187614;7801.375889928792;7858.17489163641;7862.449628525948;7952;8171.223733745284;503.2501572656788;529.3013222546202;561.4525837308591;576.5157267894988;606.6360183889719;3246.513023046562;3531.682148606638;3745.4943582615997;3814.0548320356816;3823.885283441501;75686.24313756792;84865;89810;94404;95536;75241;77631;78157;78976;80690;70431;70454.29545704478;70789.29210707828;70797.58404831875;71472.64263678648;6946.541226342525;7091.326323999212;7523.849523009619;7615.200403957505;7657;40605.796971014955;49342.75328623477;50069.49930500648;50480.49519504758;50749.74625126851;52725.472745272054;68841.65579172072;68880;69977.65011174911;70737.29262707513;9282.860757088783;9547.61809527639;9666.468344240853;9773.9022609773;10066.798664026825;10576.312539684895;10746.73133171677;10756.38654201876;10842.566297348018;10996.835047474135;9938.552765125442;10020.899791001997;10045.949770251102;10075.748106297402;10286.794264114526;10092.596296148366;10108.241881858743;10456.006679365448;10467.581296748045;11388.54445822191;10421.791564168523;11040.668779936615;11226.494807733836;11290.15323850698;11726.94136529312;9835.262355323523;9855.655052073143;9869.259805514472;9948.452835093849;10103.646372376932;8786.033536311128;9153.359264851397;9486.573104210189;9863.852042219229;9897.55461004271 01:04:55 INFO - PROCESS | 2196 | __end_tp_report 01:04:55 INFO - PROCESS | 2196 | __start_cc_report 01:04:55 INFO - PROCESS | 2196 | _x_x_mozilla_cycle_collect,400 01:04:55 INFO - PROCESS | 2196 | __end_cc_report 01:04:55 INFO - PROCESS | 2196 | __startTimestamp1463040295126__endTimestamp 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | ------- Summary: start ------- 01:04:55 INFO - PROCESS | 2196 | Number of tests: 6 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | [#0] dojo.html Cycles:165 Average:5709.67 Median:5860.97 stddev:661.74 (11.3%) stddev-sans-first:631.67 01:04:55 INFO - PROCESS | 2196 | Values: 3114.6 3445.9 3464.3 3480.7 3500.1 5640.4 5675.5 5699.1 5703.4 5709.7 5662.3 5694.5 5699.7 5713.9 5748.3 5162.8 5188.6 5198.0 5253.9 5325.5 5152.3 5253.2 5287.0 5320.3 5337.5 4568.2 5233.2 5285.0 5286.0 5293.7 5200.9 5228.4 5253.5 5265.4 5288.9 4081.4 4184.6 5166.8 5285.1 5302.8 4881.9 5202.7 5670.5 5694.4 5744.6 5980.6 6111.3 6188.6 6217.3 6257.7 6266.2 6279.2 6280.3 6284.1 6289.5 5789.6 5798.7 5819.4 5821.9 5830.4 6192.6 6213.5 6220.5 6261.4 6279.1 6138.6 6164.7 6175.9 6183.5 6184.7 6237.7 6253.7 6261.8 6279.8 6291.3 6055.1 6112.9 6133.3 6203.4 6258.6 6223.3 6225.8 6238.9 6239.2 6254.6 4755.5 4932.3 4940.7 4957.1 4978.3 4339.9 4377.7 4386.0 4410.2 4446.3 5743.7 5750.4 5755.8 5757.5 5766.3 6060.1 6155.6 6183.3 6268.8 6300.3 5780.3 5811.3 5813.9 5815.8 5861.0 5374.8 5408.6 5409.9 5416.3 5421.5 6189.6 6206.6 6223.9 6338.8 6341.2 6231.2 6256.8 6271.6 6274.4 6287.8 6323.2 6323.4 6337.1 6349.8 6353.6 6039.6 6106.9 6146.8 6192.9 6225.6 6161.5 6231.1 6254.4 6260.5 6276.9 6075.4 6123.1 6125.9 6131.8 6131.8 4946.6 5831.7 5838.2 6120.3 6128.4 5916.9 5956.9 5990.4 6006.5 6011.0 5631.2 5667.3 5965.6 6115.5 6122.9 5099.7 5810.4 5848.3 5890.8 5975.4 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | [#1] ext.html Cycles:180 Average:66151.86 Median:39307.41 stddev:145429.90 (370.0%) stddev-sans-first:145754.32 01:04:55 INFO - PROCESS | 2196 | Values: 497.3 519.1 574.5 576.9 626.9 32562.5 39462.8 39487.4 39622.0 39642.0 40793.8 41370.0 41377.6 41391.0 41594.6 9397.2 9646.5 9647.0 9651.9 9672.7 9592.3 9690.5 9764.2 9778.9 9785.1 9760.5 9777.1 9784.6 9799.3 9829.4 9760.0 9787.5 9818.3 9837.0 9846.2 9434.1 9558.8 9568.2 9577.8 9598.4 39566.0 39604.0 40016.4 40160.2 40182.0 3380.1 3434.9 3441.6 3838.8 3881.2 26036.6 27879.0 28272.3 28662.9 29433.3 2752.3 2767.1 2787.4 2798.7 2813.6 396190.0 469524.0 475404.0 476779.0 477453.0 36147.6 59881.4 59966.4 60036.4 60082.0 30634.2 35721.6 35875.6 35915.0 36040.6 15503.5 15707.4 15731.6 15780.7 15792.4 10462.0 12916.2 13073.4 13182.9 13183.6 7080.0 8282.4 8297.6 8321.3 8386.7 3141.2 3409.6 3450.7 3452.2 3468.0 858.6 883.6 886.4 955.1 1004.2 26401.6 26694.6 27170.0 27277.6 27288.9 853.9 1002.1 1030.5 1046.9 1068.0 13618.0 13796.4 13867.5 14092.7 14126.6 527177.0 620997.0 628467.0 640445.0 643570.0 61514.4 62304.4 62324.8 62362.0 62461.4 1328.0 1440.8 1478.7 1495.4 1496.3 25947.4 26383.9 26562.6 26946.2 27051.2 54511.5 54950.2 55020.9 55079.2 55132.0 534814.0 544402.0 547882.0 551151.0 551565.0 25560.4 25876.5 27388.6 28102.9 28122.9 46642.0 46647.8 47099.5 47891.5 48717.8 30745.7 38812.2 38857.4 39005.4 39180.6 37678.0 38382.8 38439.8 38590.6 39434.2 33510.7 37471.6 37834.6 38362.6 38427.8 29471.9 31536.0 33024.7 33468.7 33634.5 21185.2 24495.5 24959.0 24960.0 25187.6 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | [#2] jquery.html Cycles:180 Average:34857.19 Median:410.37 stddev:103437.48 (25205.9%) stddev-sans-first:103695.31 01:04:55 INFO - PROCESS | 2196 | Values: 409.3 409.4 411.3 416.2 422.3 20036.9 20392.4 20801.7 20820.9 21373.1 20604.8 20744.8 21409.0 21674.9 21755.9 5773.1 5903.6 6036.0 6068.2 6108.2 5103.7 5932.3 5960.6 5974.8 6038.0 5669.5 5851.5 5867.9 5968.6 5979.5 5862.2 5905.1 5929.5 5936.5 5938.1 4458.0 5494.9 5677.2 5818.1 5821.6 9314.6 13092.5 13376.5 24906.5 24984.9 2051.4 2120.8 2140.6 2177.4 2252.8 9706.8 9885.7 10092.1 10094.6 10208.3 1745.2 1799.2 1837.3 1840.2 1851.0 106369.5 108580.0 113956.0 114398.4 114939.4 11553.2 11850.8 11870.9 11926.5 12021.6 12066.0 14936.1 15088.7 15103.7 15136.9 8855.3 8951.2 8953.4 8978.9 9074.5 9045.5 9049.0 9129.0 9133.8 9202.0 4187.2 4632.2 4769.5 4818.9 4858.5 1599.2 2001.8 2044.3 2047.0 2068.2 806.7 832.7 884.3 885.8 924.0 18130.9 18400.2 18676.8 18711.4 18735.6 810.9 853.1 916.7 929.4 931.5 9678.0 9698.5 9832.9 9859.0 9971.4 608121.0 610170.0 627814.0 632275.0 632971.0 118866.0 121954.0 124005.0 124581.0 127233.0 1013.8 1014.1 1027.7 1028.1 1036.3 16656.4 16912.1 16972.9 17042.7 17546.3 50511.0 51208.0 51645.0 51895.7 52665.5 73935.9 74491.9 74894.0 75538.6 75701.6 7942.9 8016.8 8126.1 8307.2 8308.6 9827.9 10941.9 12320.9 12448.2 12564.0 8735.0 10241.1 10258.8 10356.9 10488.6 10688.9 10715.1 10766.0 10957.9 10962.5 10220.2 10484.6 10538.8 10790.7 10898.8 7165.2 8010.8 8085.6 8304.5 8479.5 9830.9 10317.1 10442.3 10449.3 10668.9 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | [#3] mootools.html Cycles:180 Average:5559.10 Median:4819.64 stddev:3104.00 (64.4%) stddev-sans-first:3098.21 01:04:55 INFO - PROCESS | 2196 | Values: 1567.7 1582.5 1817.0 1934.4 1988.6 7838.1 8537.0 8597.7 8680.8 8698.8 6669.9 8114.5 8342.2 8749.3 8928.9 2435.0 2453.6 2464.9 2473.2 2510.4 2412.5 2470.3 2474.2 2474.8 2482.1 2441.6 2451.1 2465.0 2467.6 2472.4 2428.5 2447.0 2461.3 2465.5 2466.0 2408.6 2441.1 2443.2 2449.6 2459.4 8865.4 8893.9 8942.5 8964.2 8969.8 4645.3 5310.9 5482.2 5482.7 5485.6 3491.1 3571.4 3618.7 3662.8 3675.7 869.2 1041.9 1050.5 1056.9 1073.6 11323.8 11547.5 11603.4 11630.0 11631.8 4377.5 4556.8 4627.7 4645.6 4653.7 9290.6 9335.3 9342.9 9349.4 9358.4 5019.8 5285.6 5293.6 5300.1 5359.0 3920.2 4081.2 4344.5 4793.1 4974.0 3378.9 3776.5 3842.1 3853.8 3910.1 1207.8 1212.5 1216.2 1221.9 1222.4 766.6 805.6 861.8 879.1 917.8 5159.4 5235.9 5573.4 5591.1 5664.2 456.7 462.8 470.9 986.8 1009.4 3438.2 3570.4 3686.3 3700.0 3760.2 9276.5 10607.4 10741.1 10741.9 10794.1 10398.9 10418.1 10457.7 10644.1 10676.5 6524.3 6919.4 8037.0 8041.2 8195.6 5633.1 5692.1 5719.3 5764.2 5869.7 8450.6 9434.2 9507.5 9681.6 9720.8 9328.3 9737.5 9836.0 9855.6 9912.5 6844.3 7205.9 7427.8 7509.9 7652.4 8368.8 8380.1 8388.3 8431.8 8435.2 6606.9 7326.6 7403.8 7478.0 7582.4 7180.9 7281.1 7354.4 7356.0 7394.9 6786.9 6794.5 6812.8 6856.9 6889.4 4644.6 4656.4 4670.1 4772.0 4773.0 4660.1 4687.0 4716.7 4740.0 4846.2 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | [#4] prototype.html Cycles:180 Average:5967.42 Median:6776.51 stddev:1958.98 (28.9%) stddev-sans-first:1917.76 01:04:55 INFO - PROCESS | 2196 | Values: 301.6 326.7 328.3 333.8 337.6 5477.4 6166.8 6859.7 6874.5 6878.6 5521.3 6763.5 6829.6 6832.4 6852.6 4120.3 4410.0 4459.7 5083.8 5090.2 5005.2 5073.9 5088.6 5091.7 5117.4 5016.3 5024.0 5035.3 5041.0 5050.8 5050.9 5054.4 5060.9 5068.0 5071.1 4315.5 4320.6 4324.1 4333.7 4335.2 6286.5 6623.8 6631.8 6633.1 6663.2 5015.6 5026.8 5520.0 5851.9 5871.3 5614.8 7311.7 7317.7 7323.2 7350.2 4665.3 4673.3 4674.3 4675.9 4683.1 7527.9 7751.9 7821.8 7864.0 7872.8 3896.8 4709.6 4730.6 4747.4 4768.5 4760.0 4796.3 4826.9 4831.8 4836.7 5803.4 5804.1 5820.5 5824.7 5849.8 6307.5 7331.7 7343.7 7381.0 7423.5 1656.0 1671.4 1674.0 1674.7 1675.5 1044.7 1059.0 1069.5 1070.6 1071.1 6789.6 6838.1 6846.0 6869.8 6897.1 6199.6 7387.4 7590.4 7668.7 7672.5 6811.4 6817.6 6822.4 6856.6 6893.7 4082.1 4110.2 4118.4 4124.0 4137.2 8117.4 8126.6 8136.0 8145.5 8163.6 7735.0 7952.5 8013.6 8017.6 8020.8 8072.6 8097.1 8107.9 8142.7 8149.7 6490.2 7771.6 7779.5 7780.4 7784.4 6873.1 7427.6 7609.6 7635.4 7647.7 7738.1 8000.3 8015.3 8053.0 8064.7 4675.5 4777.1 4786.2 4787.0 4789.4 7734.4 7735.6 7744.8 7766.0 7768.0 7107.7 7422.9 7440.7 7445.7 7446.8 7405.1 7408.4 7428.3 7461.4 7463.9 5937.9 7253.6 7270.3 7277.6 7302.2 6060.3 7422.9 7428.1 7463.1 7493.6 6585.7 6997.4 7233.9 7277.2 7295.9 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - PROCESS | 2196 | [#5] yui.html Cycles:180 Average:17859.23 Median:661.59 stddev:25158.94 (3802.8%) stddev-sans-first:25196.44 01:04:55 INFO - PROCESS | 2196 | Values: 677.6 677.9 737.7 758.1 763.8 15065.3 15070.5 15182.8 15269.8 15320.7 15702.1 16024.4 16027.0 16035.0 16061.0 733.3 911.4 923.5 925.8 928.4 901.5 911.9 932.9 933.7 934.9 950.1 953.0 984.8 990.5 991.6 971.2 979.8 982.5 982.5 982.8 704.5 719.1 730.6 733.8 734.0 13390.7 15248.3 15263.9 15462.4 15481.1 12347.2 12361.3 12382.0 12449.8 12533.0 8413.7 10082.0 10445.6 10493.8 10534.9 3437.2 3450.5 3517.6 3569.4 3722.0 60177.6 80354.2 80859.6 81435.0 81692.0 8600.1 8842.7 8942.1 9115.2 9122.3 20892.4 20916.4 20991.7 21028.8 21532.7 7268.2 7353.1 7370.8 7407.9 7572.9 3966.6 3981.2 3987.2 4018.2 4080.5 3599.4 3662.1 3673.1 3684.9 3716.0 588.4 646.3 651.4 656.8 666.4 518.5 519.2 572.9 597.0 599.6 7801.4 7858.2 7862.4 7952.0 8171.2 503.3 529.3 561.5 576.5 606.6 3246.5 3531.7 3745.5 3814.1 3823.9 75686.2 84865.0 89810.0 94404.0 95536.0 75241.0 77631.0 78157.0 78976.0 80690.0 70431.0 70454.3 70789.3 70797.6 71472.6 6946.5 7091.3 7523.8 7615.2 7657.0 40605.8 49342.8 50069.5 50480.5 50749.7 52725.5 68841.7 68880.0 69977.7 70737.3 9282.9 9547.6 9666.5 9773.9 10066.8 10576.3 10746.7 10756.4 10842.6 10996.8 9938.6 10020.9 10045.9 10075.7 10286.8 10092.6 10108.2 10456.0 10467.6 11388.5 10421.8 11040.7 11226.5 11290.2 11726.9 9835.3 9855.7 9869.3 9948.5 10103.6 8786.0 9153.4 9486.6 9863.9 9897.6 01:04:55 INFO - PROCESS | 2196 | -------- Summary: end -------- 01:04:55 INFO - PROCESS | 2196 | 01:04:55 INFO - TEST-INFO | 2196: exit 0 01:04:55 INFO - TEST-OK | dromaeo_css | took 1112134ms 01:04:55 INFO - TEST-START | kraken 01:04:55 INFO - operating with platform_type : mac_ 01:04:55 INFO - Initialising browser for kraken test... 01:04:55 INFO - TEST-INFO | started process 2201 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpgsiLmf/profile http://localhost:59738/getInfo.html) 01:04:58 INFO - PROCESS | 2201 | __metrics Screen width/height:1600/1200 01:04:58 INFO - PROCESS | 2201 | colorDepth:24 01:04:58 INFO - PROCESS | 2201 | Browser inner width/height: 1024/689 01:04:58 INFO - PROCESS | 2201 | __metrics 01:04:58 ERROR - PROCESS | 2201 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 01:04:59 INFO - TEST-INFO | 2201: exit 0 01:04:59 INFO - Browser initialized. 01:04:59 INFO - Running cycle 1/1 for kraken test... 01:04:59 INFO - TEST-INFO | started process 2204 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpgsiLmf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:05:11 INFO - PROCESS | 2204 | RSS: Main: 221184000 01:05:11 INFO - PROCESS | 2204 | 01:05:14 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59738/tests/kraken/driver.html?testName=audio-beat-detection) 01:05:15 INFO - PROCESS | 2204 | RSS: Main: 233062400 01:05:15 INFO - PROCESS | 2204 | 01:05:18 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59738/tests/kraken/driver.html?testName=audio-dft) 01:05:18 INFO - PROCESS | 2204 | RSS: Main: 241950720 01:05:18 INFO - PROCESS | 2204 | 01:05:22 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59738/tests/kraken/driver.html?testName=audio-fft) 01:05:22 INFO - PROCESS | 2204 | RSS: Main: 245641216 01:05:22 INFO - PROCESS | 2204 | 01:05:24 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59738/tests/kraken/driver.html?testName=audio-oscillator) 01:05:25 INFO - PROCESS | 2204 | RSS: Main: 246239232 01:05:25 INFO - PROCESS | 2204 | 01:05:27 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59738/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:05:28 INFO - PROCESS | 2204 | RSS: Main: 246403072 01:05:28 INFO - PROCESS | 2204 | 01:05:31 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59738/tests/kraken/driver.html?testName=imaging-darkroom) 01:05:31 INFO - PROCESS | 2204 | RSS: Main: 248057856 01:05:31 INFO - PROCESS | 2204 | 01:05:34 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59738/tests/kraken/driver.html?testName=imaging-desaturate) 01:05:35 INFO - PROCESS | 2204 | RSS: Main: 248078336 01:05:35 INFO - PROCESS | 2204 | 01:05:38 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59738/tests/kraken/driver.html?testName=json-parse-financial) 01:05:38 INFO - PROCESS | 2204 | RSS: Main: 248188928 01:05:38 INFO - PROCESS | 2204 | 01:05:40 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59738/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:05:41 INFO - PROCESS | 2204 | RSS: Main: 247640064 01:05:41 INFO - PROCESS | 2204 | 01:05:42 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:05:43 INFO - PROCESS | 2204 | RSS: Main: 247336960 01:05:43 INFO - PROCESS | 2204 | 01:05:45 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:05:46 INFO - PROCESS | 2204 | RSS: Main: 247709696 01:05:46 INFO - PROCESS | 2204 | 01:05:49 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:05:49 INFO - PROCESS | 2204 | RSS: Main: 247881728 01:05:49 INFO - PROCESS | 2204 | 01:05:52 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:05:53 INFO - PROCESS | 2204 | RSS: Main: 247873536 01:05:53 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:59738/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59738/tests/kraken/driver.html?testName=ai-astar) 01:05:55 INFO - PROCESS | 2204 | RSS: Main: 249425920 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | __start_tp_report 01:05:55 INFO - PROCESS | 2204 | _x_x_mozilla_page_load 01:05:55 INFO - PROCESS | 2204 | _x_x_mozilla_page_load_details 01:05:55 INFO - PROCESS | 2204 | |i|pagename|runs| 01:05:55 INFO - PROCESS | 2204 | |0;ai-astar;119;112;118;118;125;188;164;115;115;153 01:05:55 INFO - PROCESS | 2204 | |1;audio-beat-detection;155;126;159;157;130;179;151;181;171;123 01:05:55 INFO - PROCESS | 2204 | |2;audio-dft;196;201;201;200;195;219;272;208;197;200 01:05:55 INFO - PROCESS | 2204 | |3;audio-fft;66;79;77;145;68;57;59;65;125;57 01:05:55 INFO - PROCESS | 2204 | |4;audio-oscillator;81;84;123;78;79;80;121;81;79;121 01:05:55 INFO - PROCESS | 2204 | |5;imaging-gaussian-blur;90;74;86;129;134;86;89;87;88;86 01:05:55 INFO - PROCESS | 2204 | |6;imaging-darkroom;94;95;95;104;99;130;150;105;150;108 01:05:55 INFO - PROCESS | 2204 | |7;imaging-desaturate;90;91;90;91;94;89;92;93;91;90 01:05:55 INFO - PROCESS | 2204 | |8;json-parse-financial;61;64;66;66;65;66;66;82;62;61 01:05:55 INFO - PROCESS | 2204 | |9;json-stringify-tinderbox;66;71;68;74;77;78;74;69;68;78 01:05:55 INFO - PROCESS | 2204 | |10;stanford-crypto-aes;81;82;82;84;80;82;81;84;86;88 01:05:55 INFO - PROCESS | 2204 | |11;stanford-crypto-ccm;146;153;137;134;147;134;132;132;173;176 01:05:55 INFO - PROCESS | 2204 | |12;stanford-crypto-pbkdf2;170;168;169;194;218;168;166;180;196;227 01:05:55 INFO - PROCESS | 2204 | |13;stanford-crypto-sha256-iterative;70;69;67;70;66;71;69;69;69;70 01:05:55 INFO - PROCESS | 2204 | __end_tp_report 01:05:55 INFO - PROCESS | 2204 | __start_cc_report 01:05:55 INFO - PROCESS | 2204 | _x_x_mozilla_cycle_collect,1091 01:05:55 INFO - PROCESS | 2204 | __end_cc_report 01:05:55 INFO - PROCESS | 2204 | __startTimestamp1463040355428__endTimestamp 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | ------- Summary: start ------- 01:05:55 INFO - PROCESS | 2204 | Number of tests: 14 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#0] ai-astar Cycles:10 Average:132.70 Median:122.00 stddev:26.21 (21.5%) stddev-sans-first:27.33 01:05:55 INFO - PROCESS | 2204 | Values: 119.0 112.0 118.0 118.0 125.0 188.0 164.0 115.0 115.0 153.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#1] audio-beat-detection Cycles:10 Average:153.20 Median:158.00 stddev:21.09 (13.3%) stddev-sans-first:22.36 01:05:55 INFO - PROCESS | 2204 | Values: 155.0 126.0 159.0 157.0 130.0 179.0 151.0 181.0 171.0 123.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#2] audio-dft Cycles:10 Average:208.90 Median:201.00 stddev:23.26 (11.6%) stddev-sans-first:24.20 01:05:55 INFO - PROCESS | 2204 | Values: 196.0 201.0 201.0 200.0 195.0 219.0 272.0 208.0 197.0 200.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#3] audio-fft Cycles:10 Average:79.80 Median:65.50 stddev:30.41 (46.4%) stddev-sans-first:31.84 01:05:55 INFO - PROCESS | 2204 | Values: 66.0 79.0 77.0 145.0 68.0 57.0 59.0 65.0 125.0 57.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:79.50 stddev:20.06 (25.2%) stddev-sans-first:20.83 01:05:55 INFO - PROCESS | 2204 | Values: 81.0 84.0 123.0 78.0 79.0 80.0 121.0 81.0 79.0 121.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:86.50 stddev:19.82 (22.9%) stddev-sans-first:20.94 01:05:55 INFO - PROCESS | 2204 | Values: 90.0 74.0 86.0 129.0 134.0 86.0 89.0 87.0 88.0 86.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#6] imaging-darkroom Cycles:10 Average:113.00 Median:122.00 stddev:22.12 (18.1%) stddev-sans-first:22.36 01:05:55 INFO - PROCESS | 2204 | Values: 94.0 95.0 95.0 104.0 99.0 130.0 150.0 105.0 150.0 108.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.00 stddev:1.52 (1.7%) stddev-sans-first:1.56 01:05:55 INFO - PROCESS | 2204 | Values: 90.0 91.0 90.0 91.0 94.0 89.0 92.0 93.0 91.0 90.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.00 stddev:6.03 (9.1%) stddev-sans-first:6.13 01:05:55 INFO - PROCESS | 2204 | Values: 61.0 64.0 66.0 66.0 65.0 66.0 66.0 82.0 62.0 61.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:74.00 stddev:4.50 (6.1%) stddev-sans-first:4.15 01:05:55 INFO - PROCESS | 2204 | Values: 66.0 71.0 68.0 74.0 77.0 78.0 74.0 69.0 68.0 78.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:2.49 (3.0%) stddev-sans-first:2.54 01:05:55 INFO - PROCESS | 2204 | Values: 81.0 82.0 82.0 84.0 80.0 82.0 81.0 84.0 86.0 88.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:146.50 stddev:16.46 (11.2%) stddev-sans-first:17.46 01:05:55 INFO - PROCESS | 2204 | Values: 146.0 153.0 137.0 134.0 147.0 134.0 132.0 132.0 173.0 176.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:187.00 stddev:22.35 (12.0%) stddev-sans-first:22.98 01:05:55 INFO - PROCESS | 2204 | Values: 170.0 168.0 169.0 194.0 218.0 168.0 166.0 180.0 196.0 227.0 01:05:55 INFO - PROCESS | 2204 | 01:05:55 INFO - PROCESS | 2204 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:1.49 (2.1%) stddev-sans-first:1.54 01:05:55 INFO - PROCESS | 2204 | Values: 70.0 69.0 67.0 70.0 66.0 71.0 69.0 69.0 69.0 70.0 01:05:55 INFO - PROCESS | 2204 | -------- Summary: end -------- 01:05:55 INFO - PROCESS | 2204 | 01:05:56 INFO - TEST-INFO | 2204: exit 0 01:05:56 INFO - TEST-OK | kraken | took 60433ms 01:05:56 INFO - SUITE-END | took 1172s 01:05:56 INFO - Completed test suite (00:19:33) 01:05:56 INFO - javascript benchmark 01:05:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.563961045454, 3445.948310775337, 3464.2551851351955, 3480.686738193562, 3500.1424650960503, 5640.435956404358, 5675.460831221038, 5699.088145896658, 5703.372629010805, 5709.715314054336, 5662.263905692254, 5694.544436445085, 5699.68651724155, 5713.942860571393, 5748.252727145476, 5162.845114646557, 5188.584913206934, 5197.974010129963, 5253.9474605253845, 5325.547328477084, 5152.278680984665, 5253.238280449333, 5287.0, 5320.308359913225, 5337.546308563777, 4568.1548913451115, 5233.21501774733, 5285.02227087988, 5286.0, 5293.708846013467, 5200.92198617021, 5228.372595288571, 5253.527182553569, 5265.3681558212875, 5288.92066619001, 4081.428599996003, 4184.644305234059, 5166.793328266865, 5285.101532739443, 5302.840914772563, 4881.8535443936735, 5202.713850738208, 5670.5463562914865, 5694.402087780765, 5744.626599271076, 5980.581359304851, 6111.348653136725, 6188.628682279078, 6217.316095229567, 6257.687115644199, 6266.216722909637, 6279.183706118221, 6280.340564240781, 6284.088807122987, 6289.496840252768, 5789.623674461147, 5798.710064496759, 5819.447152520504, 5821.88356232873, 5830.387809280007, 6192.59748116371, 6213.471854892295, 6220.533459990519, 6261.436470717657, 6279.120923070774, 6138.631682099089, 6164.660943648142, 6175.876482470326, 6183.474404675563, 6184.690765461799, 6237.656928868956, 6253.749850005949, 6261.8121456355475, 6279.843003924847, 6291.276503202099, 6055.091736239599, 6112.94623313566, 6133.29467111279, 6203.410675985774, 6258.593191442542, 6223.253209614875, 6225.844353891099, 6238.906416403757, 6239.157713708682, 6254.624722516573, 4755.476897541268, 4932.334134891816, 4940.6788558743565, 4957.107720610325, 4978.303037574742, 4339.869803905888, 4377.693561450732, 4386.0, 4410.2282100632265, 4446.3108218226225, 5743.655380677173, 5750.367459579443, 5755.827325180251, 5757.481826635623, 5766.279215098113, 6060.090986352083, 6155.569110162246, 6183.258009038854, 6268.843278918063, 6300.30696623371, 5780.306363236438, 5811.27359080115, 5813.883722325614, 5815.796447124415, 5860.970695146497, 5374.758135883894, 5408.567314614821, 5409.945900540944, 5416.322959630046, 5421.457854214547, 6189.566730328834, 6206.627602343874, 6223.937760622517, 6338.841528961812, 6341.23905131378, 6231.22109736283, 6256.843578910563, 6271.560990730605, 6274.372562743689, 6287.842803929939, 6323.177986861679, 6323.430891219628, 6337.081123237012, 6349.841253968503, 6353.555251132377, 6039.63762174271, 6106.908396374147, 6146.832101900624, 6192.938070619236, 6225.62646241227, 6161.476274516716, 6231.096491008869, 6254.437100660963, 6260.499160067275, 6276.90154223, 6075.362086980804, 6123.08153776937, 6125.908111378422, 6131.785387511416, 6131.816045518642, 4946.604271658331, 5831.679257640871, 5838.182654428469, 6120.296165940974, 6128.448439640276, 5916.881662366643, 5956.940430595639, 5990.3710110439515, 6006.459418652343, 6011.0, 5631.239782629293, 5667.319921609434, 5965.582409231312, 6115.510759139347, 6122.938770612237, 5099.725068732817, 5810.360860305447, 5848.298204215522, 5890.793822216202, 5975.43233392827], "value": 5667.682406909414, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.2640492071734, 519.130456485387, 574.5116650846779, 576.9182782283219, 626.9247690277167, 32562.511562326556, 39462.80268598653, 39487.40768888469, 39622.0, 39642.0, 40793.7960310198, 41370.0, 41377.58622413783, 41390.96522586937, 41594.58405415945, 9397.20123789477, 9646.469444180568, 9647.0, 9651.903480965171, 9672.70981870545, 9592.328537002413, 9690.46702431366, 9764.170045546138, 9778.90221097787, 9785.07041831025, 9760.4631745254, 9777.12005919467, 9784.608615655365, 9799.265055120859, 9829.361091529028, 9760.0, 9787.51062446882, 9818.330883749779, 9836.95081524595, 9846.212303015742, 9434.103760142776, 9558.808823823554, 9568.186704130158, 9577.808443831085, 9598.376105553116, 39566.0, 39604.0, 40016.399754003716, 40160.196796063916, 40182.0, 3380.1042723678247, 3434.862605495777, 3441.5525981622227, 3838.7504812187117, 3881.2237552489614, 26036.609450858254, 27879.0, 28272.293192670142, 28662.856685716648, 29433.264168395748, 2752.2981639681752, 2767.1006922750184, 2787.414642924988, 2798.6921438641734, 2813.6482939632547, 396190.0, 469524.0, 475404.0, 476779.0, 477453.0, 36147.63852361443, 59881.401185988456, 59966.400335996084, 60036.39963600308, 60082.0, 30634.234144146576, 35721.571137154744, 35875.64124358775, 35915.0, 36040.63959360425, 15503.457378991681, 15707.371705131667, 15731.606709832116, 15780.68438631221, 15792.447264345692, 10462.0, 12916.160449570747, 13073.411696473491, 13182.934085329513, 13183.604491865259, 7079.964600176966, 8282.420230583923, 8297.58512074394, 8321.334293256525, 8386.664533418718, 3141.1989942564956, 3409.6419875913166, 3450.723942084627, 3452.2232497688324, 3467.982660086734, 858.6002124063258, 883.5802993578, 886.3883920095113, 955.0592666223764, 1004.2267454060333, 26401.603975940376, 26694.5995810063, 27170.0, 27277.590836137475, 27288.863555682095, 853.8889944307325, 1002.1181360402799, 1030.5053574284386, 1046.8796088449774, 1067.9519421626046, 13618.0, 13796.379162937692, 13867.514636987657, 14092.718145637029, 14126.576202713935, 527177.0, 620997.0, 628467.0, 640445.0, 643570.0, 61514.38485615176, 62304.376956230764, 62324.75350492965, 62362.0, 62461.375386246465, 1327.9800802987963, 1440.7609455867946, 1478.7042591481747, 1495.379417541708, 1496.304218538363, 25947.351316217246, 26383.868080659475, 26562.601560976986, 26946.191614251602, 27051.1884643461, 54511.454885450636, 54950.17574736462, 55020.89958200893, 55079.173812392044, 55132.0, 534814.0, 544402.0, 547882.0, 551151.0, 551565.0, 25560.360990975376, 25876.482470350864, 27388.589171162846, 28102.85948570244, 28122.85938570294, 46642.0, 46647.76676116598, 47099.52900470951, 47891.52108478871, 48717.75641121772, 30745.692543075176, 38812.223755524166, 38857.417138743505, 39005.414918776805, 39180.608193917695, 37678.0, 38382.80808595939, 38439.807800961935, 38590.614093858705, 39434.21131577295, 33510.66489335173, 37471.625283747904, 37834.62165378311, 38362.6163738359, 38427.807860960515, 29471.85264073666, 31536.0, 33024.66975330216, 33468.66531334753, 33634.49548256827, 21185.152593896073, 24495.51008979846, 24959.0, 24960.0, 25187.622185666864], "value": 18374.599493303103, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.30418288908845, 409.3920528015898, 411.34801339876293, 416.2050483576369, 422.31374017222015, 20036.89981550092, 20392.388228353135, 20801.687974680393, 20820.89589552052, 21373.14507419705, 20604.793952060474, 20744.792552074476, 21409.0, 21674.916254187305, 21755.912204389795, 5773.076307790744, 5903.557233207505, 6035.973884439634, 6068.211132552762, 6108.175396321506, 5103.668261563005, 5932.2584676915385, 5960.582759206857, 5974.820755377345, 6037.969810150943, 5669.461401166883, 5851.531877449794, 5867.941320586782, 5968.5523585731025, 5979.46184843364, 5862.237909071836, 5905.143754155666, 5929.466348028655, 5936.46571808535, 5938.079597662348, 4458.0, 5494.890102197974, 5677.233573467571, 5818.127280907897, 5821.563382746311, 9314.627414903396, 13092.47630094795, 13376.464941402332, 24906.5018699625, 24984.875680594236, 2051.415346626204, 2120.7985241402043, 2140.6039882621767, 2177.379446857638, 2252.7898788566013, 9706.805863882682, 9885.654002109892, 10092.091711745905, 10094.646687365906, 10208.285420020602, 1745.1536005037547, 1799.1543974332044, 1837.2742766607248, 1840.2454993452754, 1851.0374605205218, 106369.46815265951, 108580.0, 113956.0, 114398.42800785943, 114939.42530287294, 11553.24903881245, 11850.822237666442, 11870.88129118715, 11926.46330915111, 12021.579244726567, 12066.0, 14936.103833770008, 15088.698226035418, 15103.697926041637, 15136.924315378352, 8855.291576673852, 8951.194392504705, 8953.418027828171, 8978.86531702025, 9074.546272686339, 9045.462271413875, 9048.954755226181, 9128.995810460843, 9133.77165570866, 9202.0, 4187.20443115807, 4632.2125238709505, 4769.451513076041, 4818.903621927542, 4858.489858564871, 1599.1924078340364, 2001.8098280663314, 2044.2947183221765, 2046.9795302047087, 2068.1520576563535, 806.6531391501711, 832.6835802395051, 884.2925659472525, 885.7874110213502, 923.9882328849899, 18130.909345453187, 18400.17199226011, 18676.813231867778, 18711.37605563835, 18735.625287494448, 810.9148539403395, 853.0829358439578, 916.7112359606703, 929.4005366538545, 931.5109567477128, 9678.0, 9698.46658433793, 9832.852507212398, 9858.950705246429, 9971.351862128939, 608121.0, 610170.0, 627814.0, 632275.0, 632971.0, 118866.0, 121954.0, 124005.0, 124581.0, 127233.0, 1013.8327176016022, 1014.1025192704362, 1027.7482016905756, 1028.1209565821116, 1036.3108532825888, 16656.41702540405, 16912.069836159128, 16972.915135424737, 17042.65914681724, 17546.298148073933, 50510.98978020492, 51208.0, 51645.0, 51895.66469508957, 52665.47334526606, 73935.89096163455, 74491.88262175964, 74894.0, 75538.62230688811, 75701.62149189219, 7942.887995680725, 8016.839663206587, 8126.065502467292, 8307.210814972568, 8308.584570771558, 9827.901720982985, 10941.945290273497, 12320.938395307967, 12448.190867593577, 12564.0, 8735.0, 10241.129503992244, 10258.794824103326, 10356.896431035593, 10488.632897848538, 10688.946555267174, 10715.089217416607, 10766.0, 10957.890421095686, 10962.506687198937, 10220.182385409, 10484.63303784364, 10538.78922421532, 10790.730231743955, 10898.782024359627, 7165.176004759311, 8010.799730006796, 8085.595720213848, 8304.543250121302, 8479.491230526188, 9830.918598954253, 10317.071463568014, 10442.321249118782, 10449.320794148356, 10668.89331106679], "value": 9302.197806817965, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1567.7491601343788, 1582.493602047345, 1816.981830181698, 1934.4293433437135, 1988.5823976964837, 7838.137804841477, 8537.018242902068, 8597.742067737961, 8680.782980425485, 8698.826023479527, 6669.899951500732, 8114.5393829110735, 8342.249197572217, 8749.256313213384, 8928.866067009, 2435.038159926832, 2453.6074228123503, 2464.8767561621935, 2473.2332976777166, 2510.3849556858595, 2412.517496500698, 2470.308313672175, 2474.158786012755, 2474.7772700456953, 2482.094035676974, 2441.56051910656, 2451.1298489036403, 2464.987675061622, 2467.592847180213, 2472.3942634054592, 2428.490017096412, 2447.0, 2461.2616215135567, 2465.5438743832447, 2465.950680986379, 2408.5664580375524, 2441.121196369306, 2443.15711079679, 2449.6203088521306, 2459.3605662527693, 8865.423747456394, 8893.86659200106, 8942.50816205115, 8964.238039766627, 8969.775755606162, 4645.3032045193495, 5310.946890531084, 5482.17767334899, 5482.671039737588, 5485.561155107582, 3491.0748651607346, 3571.428571428559, 3618.6743193112748, 3662.7619204751604, 3675.687566556846, 869.2090197919925, 1041.9374837509774, 1050.5430137889966, 1056.910162636177, 1073.62423151897, 11323.810999845062, 11547.53809847605, 11603.361815100166, 11630.0, 11631.76736465266, 4377.4528183977, 4556.84051058218, 4627.699199552018, 4645.605123564535, 4653.674242802971, 9290.62837486493, 9335.346525743133, 9342.90657093434, 9349.39228950116, 9358.39170453932, 5019.799208031638, 5285.55072818807, 5293.57651387888, 5300.072487314705, 5358.98179345923, 3920.176762879827, 4081.2245673321972, 4344.478662560513, 4793.089313030513, 4973.950260497421, 3378.9356352748805, 3776.527934008249, 3842.077901303667, 3853.8265778040054, 3910.1397692507603, 1207.782599132147, 1212.5331747277362, 1216.1851559455185, 1221.9144659873723, 1222.4010234984794, 766.6212702736165, 805.6455159729817, 861.7673228228343, 879.0637970561331, 917.7751450894574, 5159.380874295109, 5235.921461178085, 5573.359063707644, 5591.07747221712, 5664.178694089334, 456.7054250008726, 462.81024779840106, 470.9011107667428, 986.8174387738292, 1009.4296722351847, 3438.209211881231, 3570.393033184365, 3686.2627474505207, 3699.981500092536, 3760.2291530236785, 9276.536173191313, 10607.363558186533, 10741.140708743282, 10741.946290268656, 10794.136469082454, 10398.948005260077, 10418.11446027081, 10457.738556536147, 10644.148468122532, 10676.51955661997, 6524.347565243438, 6919.377256047081, 8037.03555573335, 8041.236082572146, 8195.590220488952, 5633.126865335879, 5692.089265717465, 5719.342275638189, 5764.221830052889, 5869.6771677556435, 8450.619722112397, 9434.24526037929, 9507.477088760184, 9681.612735490784, 9720.75698107553, 9328.253739700673, 9737.464439455614, 9836.0, 9855.605775768889, 9912.504374781089, 6844.315568443116, 7205.927940720735, 7427.8143046424275, 7509.948607194888, 7652.38780897538, 8368.83262334762, 8380.07819139882, 8388.286995605442, 8431.831363372576, 8435.240828325235, 6606.8678626426245, 7326.633668316456, 7403.8149046274275, 7477.990471286306, 7582.393408527194, 7180.856382872418, 7281.089863767029, 7354.374878135204, 7356.0, 7394.852102957803, 6786.898196526958, 6794.456443484411, 6812.761553345609, 6856.9314306856295, 6889.414399776075, 4644.558766917137, 4656.3946686930485, 4670.13602483528, 4772.021735544275, 4772.976135119302, 4660.091282199938, 4687.039156972881, 4716.6698331116495, 4739.952600473951, 4846.200376937766], "value": 4462.93026918719, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.64556645941013, 326.6961725595197, 328.29089167398416, 333.81640097946126, 337.58308489016076, 5477.424870388601, 6166.814995550129, 6859.691313890874, 6874.518783685137, 6878.621675807829, 5521.254630624864, 6763.458923286137, 6829.590224586515, 6832.350926661959, 6852.55458395204, 4120.25835349637, 4410.0, 4459.732416055031, 5083.79664813407, 5090.236464530331, 5005.249212618118, 5073.949260507385, 5088.618353623475, 5091.745412729368, 5117.411497677777, 5016.347874776274, 5023.960503895146, 5035.320231768701, 5041.0, 5050.823221187259, 5050.873728156789, 5054.368203974504, 5060.898782024339, 5067.9746601267125, 5071.137906555894, 4315.4605674290715, 4320.567943205654, 4324.13517296542, 4333.71830830995, 4335.1979883721615, 6286.510986402544, 6623.801285961381, 6631.767888123893, 6633.104530888317, 6663.167104111986, 5015.59875209986, 5026.798928042874, 5519.972400137974, 5851.912221316684, 5871.324797648283, 5614.803481878114, 7311.707531698726, 7317.743878964264, 7323.194448610649, 7350.191478937312, 4665.2535594304745, 4673.322368256584, 4674.345591617176, 4675.859724208279, 4683.086798074377, 7527.924720752777, 7751.883721744179, 7821.843563128762, 7864.0, 7872.76381708544, 3896.8246428910766, 4709.576138147583, 4730.550597693214, 4747.430308363018, 4768.499307572655, 4759.952400476021, 4796.304535842353, 4826.8793280168275, 4831.782569784298, 4836.661433699677, 5803.390643982406, 5804.100364443518, 5820.534357251409, 5824.708764561755, 5849.8245052647635, 6307.495400368051, 7331.706731730777, 7343.742968996059, 7381.04046473955, 7423.48035637511, 1656.0312217352744, 1671.4400675773509, 1673.9956026384073, 1674.732042873159, 1675.5141009107222, 1044.7492601775518, 1058.99470502647, 1069.5240617924949, 1070.593174593665, 1071.1056268016139, 6789.558678685869, 6838.111045564045, 6846.0488760702965, 6869.793906182823, 6897.068895699016, 6199.597026193283, 7387.409007279405, 7590.354819840265, 7668.654910528928, 7672.5012874163, 6811.421029212573, 6817.5568588041615, 6822.3859852613505, 6856.554323968926, 6893.689783959633, 4082.0611259410502, 4110.157417729359, 4118.4440100586635, 4123.958760412417, 4137.172565486914, 8117.350611951029, 8126.55303958288, 8135.959320203479, 8145.470544414713, 8163.55100469468, 7735.033120859893, 7952.483088599222, 8013.559254240956, 8017.599120044091, 8020.839583208303, 8072.636731347104, 8097.068837083811, 8107.878381824277, 8142.674293028331, 8149.7147599833725, 6490.188726409199, 7771.572563508835, 7779.533228006338, 7780.377569794516, 7784.41616878725, 6873.072135261676, 7427.5914824683, 7609.581473019037, 7635.389168866588, 7647.694092236248, 7738.110117336579, 8000.279974802295, 8015.278624923551, 8053.0, 8064.677412903418, 4675.509071547439, 4777.092352453023, 4786.186348320724, 4786.976065119652, 4789.377380940456, 7734.381249499914, 7735.613219338898, 7744.806379840549, 7766.0, 7768.019155115409, 7107.680154392961, 7422.925770742223, 7440.665170067252, 7445.664945077593, 7446.776596702108, 7405.111386633423, 7408.407327413686, 7428.33145016951, 7461.403087753075, 7463.888041679271, 5937.940620593911, 7253.611565003308, 7270.309320614533, 7277.59973201479, 7302.160251570925, 6060.303065147565, 7422.851542969002, 7428.1030398143685, 7463.141738700119, 7493.625318734151, 6585.703643335965, 6997.440204783707, 7233.855322893618, 7277.235890231449, 7295.8540829184185], "value": 5308.506214098566, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.5697432130597, 677.9355961183687, 737.6975440069573, 758.0940775772947, 763.8434121005195, 15065.322060507277, 15070.47253346133, 15182.772258416106, 15269.847301526981, 15320.69358612827, 15702.057876527417, 16024.359025638989, 16027.0, 16035.037897726144, 16061.0, 733.3290039613747, 911.3757076402669, 923.4920793563547, 925.7824411263348, 928.3826255540058, 901.5312037740371, 911.9088091190894, 932.9300302477308, 933.7105497295826, 934.8644446555245, 950.0879156010238, 953.0279115302387, 984.7538115471132, 990.5047476261869, 991.5884907763269, 971.218169373654, 979.8089372572351, 982.474376208732, 982.4841957972122, 982.8427451607708, 704.5314865614412, 719.0723966083746, 730.5835673666013, 733.757859906235, 734.0, 13390.665233369145, 15248.313825877862, 15263.923680381527, 15462.381504739797, 15481.14853683047, 12347.19743216697, 12361.32012739299, 12382.0, 12449.813252801216, 12533.0, 8413.705520306821, 10082.0, 10445.634402795866, 10493.790124197474, 10534.947325263402, 3437.1750779812915, 3450.516927630134, 3517.56030496188, 3569.446735755962, 3721.962780372216, 60177.5928962841, 80354.19645803585, 80859.5957020211, 81434.9999999994, 81692.0, 8600.139986001475, 8842.690505832265, 8942.105789421135, 9115.225205857576, 9122.31582631292, 20892.37322880316, 20916.37250882476, 20991.685124723143, 21028.78971210299, 21532.708037517797, 7268.164161121433, 7353.117625884928, 7370.815729606696, 7407.8888816667795, 7572.924270757222, 3966.5835087315418, 3981.1838573092446, 3987.222491614107, 4018.1963607278662, 4080.5307389649975, 3599.3881040223223, 3662.102784817737, 3673.0633688409284, 3684.907877303035, 3716.0152559571734, 588.4292236530558, 646.3245908026124, 651.4007113455538, 656.7569999100268, 666.4135560706644, 518.4633903909436, 519.2107995846293, 572.8825590753886, 596.9940650004756, 599.604261187614, 7801.375889928792, 7858.17489163641, 7862.449628525948, 7952.0, 8171.223733745284, 503.2501572656788, 529.3013222546202, 561.4525837308591, 576.5157267894988, 606.6360183889719, 3246.513023046562, 3531.682148606638, 3745.4943582615997, 3814.0548320356816, 3823.885283441501, 75686.24313756792, 84865.0, 89810.0, 94404.0, 95536.0, 75241.0, 77631.0, 78157.0, 78976.0, 80690.0, 70431.0, 70454.29545704478, 70789.29210707828, 70797.58404831875, 71472.64263678648, 6946.541226342525, 7091.326323999212, 7523.849523009619, 7615.200403957505, 7657.0, 40605.796971014955, 49342.75328623477, 50069.49930500648, 50480.49519504758, 50749.74625126851, 52725.472745272054, 68841.65579172072, 68880.0, 69977.65011174911, 70737.29262707513, 9282.860757088783, 9547.61809527639, 9666.468344240853, 9773.9022609773, 10066.798664026825, 10576.312539684895, 10746.73133171677, 10756.38654201876, 10842.566297348018, 10996.835047474135, 9938.552765125442, 10020.899791001997, 10045.949770251102, 10075.748106297402, 10286.794264114526, 10092.596296148366, 10108.241881858743, 10456.006679365448, 10467.581296748045, 11388.54445822191, 10421.791564168523, 11040.668779936615, 11226.494807733836, 11290.15323850698, 11726.94136529312, 9835.262355323523, 9855.655052073143, 9869.259805514472, 9948.452835093849, 10103.646372376932, 8786.033536311128, 9153.359264851397, 9486.573104210189, 9863.852042219229, 9897.55461004271], "value": 6767.218157947361, "unit": "score"}], "name": "dromaeo_css", "value": 7331.7514358612525, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 112.0, 118.0, 118.0, 125.0, 188.0, 164.0, 115.0, 115.0, 153.0], "value": 132.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 159.0, 157.0, 130.0, 179.0, 151.0, 181.0, 171.0, 123.0], "value": 153.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 201.0, 200.0, 195.0, 219.0, 272.0, 208.0, 197.0, 200.0], "value": 208.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 77.0, 145.0, 68.0, 57.0, 59.0, 65.0, 125.0, 57.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 123.0, 78.0, 79.0, 80.0, 121.0, 81.0, 79.0, 121.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 74.0, 86.0, 129.0, 134.0, 86.0, 89.0, 87.0, 88.0, 86.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 104.0, 99.0, 130.0, 150.0, 105.0, 150.0, 108.0], "value": 113.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 91.0, 94.0, 89.0, 92.0, 93.0, 91.0, 90.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 66.0, 66.0, 65.0, 66.0, 66.0, 82.0, 62.0, 61.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 74.0, 77.0, 78.0, 74.0, 69.0, 68.0, 78.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 84.0, 80.0, 82.0, 81.0, 84.0, 86.0, 88.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 153.0, 137.0, 134.0, 147.0, 134.0, 132.0, 132.0, 173.0, 176.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 169.0, 194.0, 218.0, 168.0, 166.0, 180.0, 196.0, 227.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 67.0, 70.0, 66.0, 71.0, 69.0, 69.0, 69.0, 70.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1588.5, "alertThreshold": 2.0}]} 01:05:56 INFO - Return code: 0 01:05:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:05:56 ERROR - # TBPL SUCCESS # 01:05:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:05:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:05:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:05:56 INFO - 2016-05-12 01:05:56.771 screenresolution[2209:27968] starting screenresolution argv=screenresolution get 01:05:56 INFO - 2016-05-12 01:05:56.773 screenresolution[2209:27968] Display 0: 1600x1200x32@60 01:05:56 INFO - 2016-05-12 01:05:56.779 screenresolution[2210:27971] starting screenresolution argv=screenresolution list 01:05:56 INFO - Available Modes on Display 0 01:05:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:05:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:05:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:05:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:05:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:05:57 INFO - Intel Iris: 01:05:57 INFO - Chipset Model: Intel Iris 01:05:57 INFO - Type: GPU 01:05:57 INFO - Bus: Built-In 01:05:57 INFO - VRAM (Dynamic, Max): 1536 MB 01:05:57 INFO - Vendor: Intel (0x8086) 01:05:57 INFO - Device ID: 0x0a2e 01:05:57 INFO - Revision ID: 0x0009 01:05:57 INFO - Displays: 01:05:57 INFO - Display: 01:05:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:05:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:05:57 INFO - Display Serial Number: 1600x1200 60 01:05:57 INFO - Main Display: Yes 01:05:57 INFO - Mirror: Off 01:05:57 INFO - Online: Yes 01:05:57 INFO - Rotation: Supported 01:05:57 INFO - Return code: 0 01:05:57 INFO - Running post-action listener: _resource_record_post_action 01:05:57 INFO - Running post-run listener: _resource_record_post_run 01:05:58 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 258048; Write bytes: 655599104; Read time: 3; Write time: 2235 01:05:58 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345455104; Write bytes: 346511360; Read time: 26640; Write time: 665 01:05:58 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 258048; Write bytes: 299130368; Read time: 3; Write time: 1537 01:05:58 INFO - Running post-run listener: _upload_blobber_files 01:05:58 INFO - Blob upload gear active. 01:05:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:05:58 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 01:05:58 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'] 01:05:58 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 01:05:58 INFO - (blobuploader) - INFO - Open directory for files ... 01:05:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:05:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:05:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:05:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:05:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:05:59 INFO - (blobuploader) - INFO - Done attempting. 01:05:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:05:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:05:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:06:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:00 INFO - (blobuploader) - INFO - Done attempting. 01:06:00 INFO - (blobuploader) - INFO - Iteration through files over. 01:06:00 INFO - Return code: 0 01:06:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:06:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:06:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7905cc814cfa8abcad430f7f0d9d3d87a6aa8fbd57abce097b46dc2b40a0f4d63d58cfe10aa9edd4b6ddde598e049eb8316dbc39710fe0b35a01a81aa36eed12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a9ee88ffe7eba7974aab4f7c4126eeee816c0266b3aff0fcf70d557410ddae9f32f186b8a090a16238e50f845c17392c7980be7309ae9aa3bd2356c4aaa37a9"} 01:06:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:06:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:06:00 INFO - Contents: 01:06:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7905cc814cfa8abcad430f7f0d9d3d87a6aa8fbd57abce097b46dc2b40a0f4d63d58cfe10aa9edd4b6ddde598e049eb8316dbc39710fe0b35a01a81aa36eed12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a9ee88ffe7eba7974aab4f7c4126eeee816c0266b3aff0fcf70d557410ddae9f32f186b8a090a16238e50f845c17392c7980be7309ae9aa3bd2356c4aaa37a9"} 01:06:00 INFO - Running post-run listener: copy_logs_to_upload_dir 01:06:00 INFO - Copying logs to upload dir... 01:06:00 INFO - mkdir: /builds/slave/test/build/upload/logs 01:06:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.406157 ========= master_lag: 2.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-12 01:06:02.323314) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 01:06:02.326927) ========= 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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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/7905cc814cfa8abcad430f7f0d9d3d87a6aa8fbd57abce097b46dc2b40a0f4d63d58cfe10aa9edd4b6ddde598e049eb8316dbc39710fe0b35a01a81aa36eed12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a9ee88ffe7eba7974aab4f7c4126eeee816c0266b3aff0fcf70d557410ddae9f32f186b8a090a16238e50f845c17392c7980be7309ae9aa3bd2356c4aaa37a9"} build_url:https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/HlTZExGnSE6Zw0q34ATVvQ/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/7905cc814cfa8abcad430f7f0d9d3d87a6aa8fbd57abce097b46dc2b40a0f4d63d58cfe10aa9edd4b6ddde598e049eb8316dbc39710fe0b35a01a81aa36eed12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a9ee88ffe7eba7974aab4f7c4126eeee816c0266b3aff0fcf70d557410ddae9f32f186b8a090a16238e50f845c17392c7980be7309ae9aa3bd2356c4aaa37a9"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 01:06:02.397911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-12 01:06:02.398290) ========= 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.nQbfJZcBUm/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.l6hKGDp1Sr/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.005167 ========= master_lag: 3.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-12 01:06:05.412902) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 01:06:05.413217) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 01:06:05.415703) ========= ========= Total master_lag: 5.40 =========