builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0189 starttime: 1461216994.67 results: success (0) buildid: 20160420211019 builduid: 065a2115ca4f40409fa9288085876b2e revision: 26b73eb3a49119c43b633b67564496e285ab70e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 22:36:34.667017) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 22:36:34.667855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 35 secs) (at 2016-04-20 22:36:34.668142) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 35 secs) (at 2016-04-20 22:37:10.547040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-20 22:37:10.547337) ========= 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.bHjSg6W1pj/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.MxP46X7dys/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-20 22:37:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.8M=0.001s 2016-04-20 22:37:11 (15.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.628882 ========= master_lag: 7.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-20 22:37:18.364204) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:37:18.364536) ========= 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.bHjSg6W1pj/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.MxP46X7dys/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.027139 ========= master_lag: 0.77 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:37:19.161625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 22:37:19.161950) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 26b73eb3a49119c43b633b67564496e285ab70e6 --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 26b73eb3a49119c43b633b67564496e285ab70e6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bHjSg6W1pj/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.MxP46X7dys/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-20 22:37:19,454 truncating revision to first 12 chars 2016-04-20 22:37:19,454 Setting DEBUG logging. 2016-04-20 22:37:19,454 attempt 1/10 2016-04-20 22:37:19,454 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/26b73eb3a491?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 22:37:21,410 unpacking tar archive at: mozilla-aurora-26b73eb3a491/testing/mozharness/ program finished with exit code 0 elapsedTime=2.208299 ========= master_lag: 1.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-20 22:37:22.659390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 22:37:22.662693) ========= script_repo_revision: 26b73eb3a49119c43b633b67564496e285ab70e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 22:37:22.663068) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 22:37:22.663330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 22:37:23.203284) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-04-20 22:37:23.203584) ========= /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.bHjSg6W1pj/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.MxP46X7dys/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:37:23 INFO - MultiFileLogger online at 20160420 22:37:23 in /builds/slave/test 22:37:23 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 22:37:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:37:23 INFO - {'append_to_log': False, 22:37:23 INFO - 'base_work_dir': '/builds/slave/test', 22:37:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:37:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:37:23 INFO - 'branch': 'Mozilla-Aurora', 22:37:23 INFO - 'buildbot_json_path': 'buildprops.json', 22:37:23 INFO - 'config_files': ('talos/mac_config.py',), 22:37:23 INFO - 'default_actions': ('clobber', 22:37:23 INFO - 'read-buildbot-config', 22:37:23 INFO - 'download-and-extract', 22:37:23 INFO - 'populate-webroot', 22:37:23 INFO - 'create-virtualenv', 22:37:23 INFO - 'install', 22:37:23 INFO - 'run-tests'), 22:37:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:37:23 INFO - 'download_minidump_stackwalk': True, 22:37:23 INFO - 'download_symbols': 'ondemand', 22:37:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:37:23 INFO - 'tooltool.py': '/tools/tooltool.py', 22:37:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:37:23 INFO - '/tools/misc-python/virtualenv.py')}, 22:37:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:37:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:37:23 INFO - 'installer_path': 'installer.exe', 22:37:23 INFO - 'log_level': 'info', 22:37:23 INFO - 'log_name': 'talos', 22:37:23 INFO - 'log_to_console': True, 22:37:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:37:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:37:23 INFO - 'opt_config_files': (), 22:37:23 INFO - 'pip_index': False, 22:37:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:37:23 INFO - 'cmd': ('bash', 22:37:23 INFO - '-c', 22:37:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:37:23 INFO - 'enabled': True, 22:37:23 INFO - 'halt_on_failure': False, 22:37:23 INFO - 'name': 'check_screen_resolution'},), 22:37:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:37:23 INFO - 'cmd': ('bash', 22:37:23 INFO - '-c', 22:37:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:37:23 INFO - 'enabled': True, 22:37:23 INFO - 'halt_on_failure': False, 22:37:23 INFO - 'name': 'check_screen_resolution'},), 22:37:23 INFO - 'run_cmd_checks_enabled': True, 22:37:23 INFO - 'sps_profile': False, 22:37:23 INFO - 'sps_profile_interval': 0, 22:37:23 INFO - 'suite': 'dromaeojs-e10s', 22:37:23 INFO - 'system_bits': '32', 22:37:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:37:23 INFO - 'title': 't-yosemite-r7-0189', 22:37:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:37:23 INFO - 'use_talos_json': True, 22:37:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:37:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:37:23 INFO - 'work_dir': 'build'} 22:37:23 INFO - ##### 22:37:23 INFO - ##### Running clobber step. 22:37:23 INFO - ##### 22:37:23 INFO - Running pre-action listener: _resource_record_pre_action 22:37:23 INFO - Running main action method: clobber 22:37:23 INFO - rmtree: /builds/slave/test/build 22:37:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:37:24 INFO - Running post-action listener: _resource_record_post_action 22:37:24 INFO - ##### 22:37:24 INFO - ##### Running read-buildbot-config step. 22:37:24 INFO - ##### 22:37:24 INFO - Running pre-action listener: _resource_record_pre_action 22:37:24 INFO - Running main action method: read_buildbot_config 22:37:24 INFO - Using buildbot properties: 22:37:24 INFO - { 22:37:24 INFO - "project": "", 22:37:24 INFO - "product": "firefox", 22:37:24 INFO - "script_repo_revision": "production", 22:37:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 22:37:24 INFO - "repository": "", 22:37:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 22:37:24 INFO - "buildid": "20160420211019", 22:37:24 INFO - "pgo_build": "False", 22:37:24 INFO - "basedir": "/builds/slave/test", 22:37:24 INFO - "buildnumber": 251, 22:37:24 INFO - "slavename": "t-yosemite-r7-0189", 22:37:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:37:24 INFO - "platform": "yosemite_r7", 22:37:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:37:24 INFO - "branch": "mozilla-aurora", 22:37:24 INFO - "repo_path": "releases/mozilla-aurora", 22:37:24 INFO - "revision": "26b73eb3a49119c43b633b67564496e285ab70e6", 22:37:24 INFO - "stage_platform": "macosx64", 22:37:24 INFO - "builduid": "065a2115ca4f40409fa9288085876b2e", 22:37:24 INFO - "slavebuilddir": "test" 22:37:24 INFO - } 22:37:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 22:37:24 INFO - Running post-action listener: _resource_record_post_action 22:37:24 INFO - ##### 22:37:24 INFO - ##### Running download-and-extract step. 22:37:24 INFO - ##### 22:37:24 INFO - Running pre-action listener: _resource_record_pre_action 22:37:24 INFO - Running main action method: download_and_extract 22:37:24 INFO - mkdir: /builds/slave/test/build/tests 22:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:24 INFO - https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:37:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 22:37:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 22:37:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 22:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 22:37:27 INFO - Downloaded 1277 bytes. 22:37:27 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 22:37:27 INFO - Using the following test package requirements: 22:37:27 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 22:37:27 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 22:37:27 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 22:37:27 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'jsshell-mac.zip'], 22:37:27 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 22:37:27 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 22:37:27 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 22:37:27 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 22:37:27 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 22:37:27 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 22:37:27 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 22:37:27 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 22:37:27 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:37:27 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')]} 22:37:27 INFO - https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:37:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 22:37:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 22:37:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 22:37:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:37:30 INFO - Downloaded 17913768 bytes. 22:37:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:37:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:37:33 INFO - Return code: 0 22:37:33 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:33 INFO - https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:37:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 22:37:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 22:37:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 22:37:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:37:35 INFO - Downloaded 17913768 bytes. 22:37:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:37:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:37:38 INFO - Return code: 0 22:37:38 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')]} 22:37:38 INFO - https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:37:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 22:37:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 22:37:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 22:37:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:37:39 INFO - Downloaded 10845198 bytes. 22:37:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:37:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:37:40 INFO - Return code: 0 22:37:40 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')]} 22:37:40 INFO - https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 22:37:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 22:37:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 22:37:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 22:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 22:37:43 INFO - Downloaded 115367047 bytes. 22:37:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 22:37:43 INFO - mkdir: /builds/slave/test/properties 22:37:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:37:43 INFO - Writing to file /builds/slave/test/properties/build_url 22:37:43 INFO - Contents: 22:37:43 INFO - build_url:https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 22:37:44 INFO - Running post-action listener: _resource_record_post_action 22:37:44 INFO - Running post-action listener: set_extra_try_arguments 22:37:44 INFO - ##### 22:37:44 INFO - ##### Running populate-webroot step. 22:37:44 INFO - ##### 22:37:44 INFO - Running pre-action listener: _resource_record_pre_action 22:37:44 INFO - Running main action method: populate_webroot 22:37:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:37:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:37:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:37:44 INFO - '--tppagecycles', 22:37:44 INFO - '7'], 22:37:44 INFO - 'tests': ['tsvgm']}}, 22:37:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:37:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:37:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:37:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:37:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:37:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:37:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'tests': ['damp', 'tps']}, 22:37:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['damp', 'tps']}, 22:37:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:37:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:37:44 INFO - 'other': {'tests': ['a11yr', 22:37:44 INFO - 'ts_paint', 22:37:44 INFO - 'tpaint', 22:37:44 INFO - 'sessionrestore', 22:37:44 INFO - 'sessionrestore_no_auto_restore', 22:37:44 INFO - 'tabpaint']}, 22:37:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['a11yr', 22:37:44 INFO - 'ts_paint', 22:37:44 INFO - 'tpaint', 22:37:44 INFO - 'sessionrestore', 22:37:44 INFO - 'sessionrestore_no_auto_restore', 22:37:44 INFO - 'tabpaint']}, 22:37:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['a11yr', 22:37:44 INFO - 'ts_paint', 22:37:44 INFO - 'tpaint', 22:37:44 INFO - 'sessionrestore', 22:37:44 INFO - 'sessionrestore_no_auto_restore', 22:37:44 INFO - 'tabpaint']}, 22:37:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['a11yr', 22:37:44 INFO - 'ts_paint', 22:37:44 INFO - 'tpaint', 22:37:44 INFO - 'sessionrestore', 22:37:44 INFO - 'sessionrestore_no_auto_restore', 22:37:44 INFO - 'tabpaint']}, 22:37:44 INFO - 'other_l64': {'tests': ['a11yr', 22:37:44 INFO - 'ts_paint', 22:37:44 INFO - 'tpaint', 22:37:44 INFO - 'sessionrestore', 22:37:44 INFO - 'sessionrestore_no_auto_restore', 22:37:44 INFO - 'tabpaint']}, 22:37:44 INFO - 'other_nol64': {'tests': ['a11yr', 22:37:44 INFO - 'ts_paint', 22:37:44 INFO - 'tpaint', 22:37:44 INFO - 'sessionrestore', 22:37:44 INFO - 'sessionrestore_no_auto_restore', 22:37:44 INFO - 'tabpaint']}, 22:37:44 INFO - 'svgr': {'tests': ['tsvgx', 22:37:44 INFO - 'tsvgr_opacity', 22:37:44 INFO - 'tart', 22:37:44 INFO - 'tscrollx', 22:37:44 INFO - 'cart']}, 22:37:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['tsvgx', 22:37:44 INFO - 'tsvgr_opacity', 22:37:44 INFO - 'tart', 22:37:44 INFO - 'tscrollx', 22:37:44 INFO - 'cart']}, 22:37:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'tests': ['tp5o']}, 22:37:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'talos_options': ['--e10s'], 22:37:44 INFO - 'tests': ['tp5o']}, 22:37:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'talos_options': ['--xperf_path', 22:37:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:37:44 INFO - 'tests': ['tp5n']}, 22:37:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:44 INFO - 'talos_options': ['--e10s', 22:37:44 INFO - '--xperf_path', 22:37:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:37:44 INFO - 'tests': ['tp5n']}}, 22:37:44 INFO - 'talos.zip': {'path': '', 22:37:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:37:44 INFO - Running post-action listener: _resource_record_post_action 22:37:44 INFO - ##### 22:37:44 INFO - ##### Running create-virtualenv step. 22:37:44 INFO - ##### 22:37:44 INFO - Running pre-action listener: _resource_record_pre_action 22:37:44 INFO - Running main action method: create_virtualenv 22:37:44 INFO - Creating virtualenv /builds/slave/test/build/venv 22:37:44 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 22:37:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:37:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:37:44 INFO - Using real prefix '/tools/python27' 22:37:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:37:45 INFO - Installing distribute.............................................................................................................................................................................................done. 22:37:47 INFO - Installing pip.................done. 22:37:47 INFO - Return code: 0 22:37:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:37:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:48 INFO - 'HOME': '/Users/cltbld', 22:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:48 INFO - 'LOGNAME': 'cltbld', 22:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:48 INFO - 'MOZ_NO_REMOTE': '1', 22:37:48 INFO - 'NO_EM_RESTART': '1', 22:37:48 INFO - 'PAGER': '/bin/cat', 22:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:48 INFO - 'PWD': '/builds/slave/test', 22:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:48 INFO - 'SHELL': '/bin/bash', 22:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:37:48 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:48 INFO - 'USER': 'cltbld', 22:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:48 INFO - 'XPC_FLAGS': '0x0', 22:37:48 INFO - 'XPC_SERVICE_NAME': '0', 22:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:48 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 22:37:48 INFO - Cleaning up... 22:37:48 INFO - Return code: 0 22:37:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:37:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:48 INFO - 'HOME': '/Users/cltbld', 22:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:48 INFO - 'LOGNAME': 'cltbld', 22:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:48 INFO - 'MOZ_NO_REMOTE': '1', 22:37:48 INFO - 'NO_EM_RESTART': '1', 22:37:48 INFO - 'PAGER': '/bin/cat', 22:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:48 INFO - 'PWD': '/builds/slave/test', 22:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:48 INFO - 'SHELL': '/bin/bash', 22:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:37:48 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:48 INFO - 'USER': 'cltbld', 22:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:48 INFO - 'XPC_FLAGS': '0x0', 22:37:48 INFO - 'XPC_SERVICE_NAME': '0', 22:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:48 INFO - Downloading/unpacking psutil>=0.7.1 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:37:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:37:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:37:51 INFO - Installing collected packages: psutil 22:37:51 INFO - Running setup.py install for psutil 22:37:51 INFO - building 'psutil._psutil_osx' extension 22:37:51 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 22:37:52 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 22:37:52 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 22:37:52 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 22:37:52 INFO - building 'psutil._psutil_posix' extension 22:37:52 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 22:37:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:37:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:37:52 INFO - ^ 22:37:52 INFO - 1 warning generated. 22:37:52 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 22:37:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:37:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:37:53 INFO - Successfully installed psutil 22:37:53 INFO - Cleaning up... 22:37:53 INFO - Return code: 0 22:37:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:37:53 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')]} 22:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:53 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')]} 22:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:53 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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:37:53 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 22:37:53 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 22:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:53 INFO - 'HOME': '/Users/cltbld', 22:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:53 INFO - 'LOGNAME': 'cltbld', 22:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:53 INFO - 'MOZ_NO_REMOTE': '1', 22:37:53 INFO - 'NO_EM_RESTART': '1', 22:37:53 INFO - 'PAGER': '/bin/cat', 22:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:53 INFO - 'PWD': '/builds/slave/test', 22:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:53 INFO - 'SHELL': '/bin/bash', 22:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:37:53 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:53 INFO - 'USER': 'cltbld', 22:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:53 INFO - 'XPC_FLAGS': '0x0', 22:37:53 INFO - 'XPC_SERVICE_NAME': '0', 22:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:37:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:37:55 INFO - Installing collected packages: mozsystemmonitor 22:37:55 INFO - Running setup.py install for mozsystemmonitor 22:37:56 INFO - Successfully installed mozsystemmonitor 22:37:56 INFO - Cleaning up... 22:37:56 INFO - Return code: 0 22:37:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:37:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:37:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:56 INFO - 'HOME': '/Users/cltbld', 22:37:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:56 INFO - 'LOGNAME': 'cltbld', 22:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:56 INFO - 'MOZ_NO_REMOTE': '1', 22:37:56 INFO - 'NO_EM_RESTART': '1', 22:37:56 INFO - 'PAGER': '/bin/cat', 22:37:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:56 INFO - 'PWD': '/builds/slave/test', 22:37:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:56 INFO - 'SHELL': '/bin/bash', 22:37:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:37:56 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:37:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:56 INFO - 'USER': 'cltbld', 22:37:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:56 INFO - 'XPC_FLAGS': '0x0', 22:37:56 INFO - 'XPC_SERVICE_NAME': '0', 22:37:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:56 INFO - Downloading/unpacking blobuploader==1.2.4 22:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:58 INFO - Downloading blobuploader-1.2.4.tar.gz 22:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:37:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:37:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:59 INFO - Downloading docopt-0.6.1.tar.gz 22:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:38:00 INFO - Installing collected packages: blobuploader, requests, docopt 22:38:00 INFO - Running setup.py install for blobuploader 22:38:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:38:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:38:00 INFO - Running setup.py install for requests 22:38:00 INFO - Running setup.py install for docopt 22:38:00 INFO - Successfully installed blobuploader requests docopt 22:38:00 INFO - Cleaning up... 22:38:00 INFO - Return code: 0 22:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:38:00 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')]} 22:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:00 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')]} 22:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:00 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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:38:00 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 22:38:00 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 22:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:00 INFO - 'HOME': '/Users/cltbld', 22:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:00 INFO - 'LOGNAME': 'cltbld', 22:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:00 INFO - 'MOZ_NO_REMOTE': '1', 22:38:00 INFO - 'NO_EM_RESTART': '1', 22:38:00 INFO - 'PAGER': '/bin/cat', 22:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:00 INFO - 'PWD': '/builds/slave/test', 22:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:00 INFO - 'SHELL': '/bin/bash', 22:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:38:00 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:00 INFO - 'USER': 'cltbld', 22:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:00 INFO - 'XPC_FLAGS': '0x0', 22:38:00 INFO - 'XPC_SERVICE_NAME': '0', 22:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-kITEaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Rykyc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-9fZzIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-VAsy0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-VQ6D24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-T4sbEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-8JwFrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:38:01 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-2wE3ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-wR2mdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-bfrKUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-27wqpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-VtmSbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-fB6Npw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-i2qcAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-LBZF8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-obgG7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:38:02 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-hQdjzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:38:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:38:02 INFO - Running setup.py install for manifestparser 22:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:38:03 INFO - Running setup.py install for mozcrash 22:38:03 INFO - Running setup.py install for mozdebug 22:38:03 INFO - Running setup.py install for mozdevice 22:38:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:38:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:38:03 INFO - Running setup.py install for mozfile 22:38:03 INFO - Running setup.py install for mozhttpd 22:38:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:38:03 INFO - Running setup.py install for mozinfo 22:38:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Running setup.py install for mozInstall 22:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Running setup.py install for mozleak 22:38:04 INFO - Running setup.py install for mozlog 22:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Running setup.py install for moznetwork 22:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Running setup.py install for mozprocess 22:38:04 INFO - Running setup.py install for mozprofile 22:38:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:38:04 INFO - Running setup.py install for mozrunner 22:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:38:05 INFO - Running setup.py install for mozscreenshot 22:38:05 INFO - Running setup.py install for moztest 22:38:05 INFO - Running setup.py install for mozversion 22:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:38:05 INFO - Cleaning up... 22:38:05 INFO - Return code: 0 22:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:05 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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:38:05 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 22:38:05 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 22:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:05 INFO - 'HOME': '/Users/cltbld', 22:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:05 INFO - 'LOGNAME': 'cltbld', 22:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:05 INFO - 'MOZ_NO_REMOTE': '1', 22:38:05 INFO - 'NO_EM_RESTART': '1', 22:38:05 INFO - 'PAGER': '/bin/cat', 22:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:05 INFO - 'PWD': '/builds/slave/test', 22:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:05 INFO - 'SHELL': '/bin/bash', 22:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:38:05 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:05 INFO - 'USER': 'cltbld', 22:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:05 INFO - 'XPC_FLAGS': '0x0', 22:38:05 INFO - 'XPC_SERVICE_NAME': '0', 22:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:38:05 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-OFdV2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:38:05 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)) 22:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:38:05 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-unK_Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-y81llZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-PEUHw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-KJcdZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-7IkaFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-a6kmVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-zFDTE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-K2kEf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-C_OOVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:38:06 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)) 22:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:38:06 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-mSPHtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:38:07 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)) 22:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:38:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-WUldlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:38:07 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)) 22:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:38:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-hs5Aeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:38:07 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)) 22:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:38:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-lODpIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:38:07 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)) 22:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:38:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-crZWtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:38:07 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)) 22:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:38:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-0za8ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:38:07 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)) 22:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:38:07 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-CrPGlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:38:07 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)) 22:38:07 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)) 22:38:07 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)) 22:38:07 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)) 22:38:07 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)) 22:38:07 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)) 22:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:10 INFO - Downloading blessings-1.6.tar.gz 22:38:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:38:10 INFO - Installing collected packages: blessings 22:38:10 INFO - Running setup.py install for blessings 22:38:10 INFO - Successfully installed blessings 22:38:10 INFO - Cleaning up... 22:38:10 INFO - Return code: 0 22:38:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:38:10 INFO - Reading from file tmpfile_stdout 22:38:10 INFO - Current package versions: 22:38:10 INFO - blessings == 1.6 22:38:10 INFO - blobuploader == 1.2.4 22:38:10 INFO - docopt == 0.6.1 22:38:10 INFO - manifestparser == 1.1 22:38:10 INFO - mozInstall == 1.12 22:38:10 INFO - mozcrash == 0.17 22:38:10 INFO - mozdebug == 0.1 22:38:10 INFO - mozdevice == 0.48 22:38:10 INFO - mozfile == 1.2 22:38:10 INFO - mozhttpd == 0.7 22:38:10 INFO - mozinfo == 0.9 22:38:10 INFO - mozleak == 0.1 22:38:10 INFO - mozlog == 3.1 22:38:10 INFO - moznetwork == 0.27 22:38:10 INFO - mozprocess == 0.22 22:38:10 INFO - mozprofile == 0.28 22:38:10 INFO - mozrunner == 6.11 22:38:10 INFO - mozscreenshot == 0.1 22:38:10 INFO - mozsystemmonitor == 0.0 22:38:10 INFO - moztest == 0.7 22:38:10 INFO - mozversion == 1.4 22:38:10 INFO - psutil == 3.1.1 22:38:10 INFO - requests == 1.2.3 22:38:10 INFO - wsgiref == 0.1.2 22:38:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:10 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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:38:10 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 22:38:10 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 22:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:10 INFO - 'HOME': '/Users/cltbld', 22:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:10 INFO - 'LOGNAME': 'cltbld', 22:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:10 INFO - 'MOZ_NO_REMOTE': '1', 22:38:10 INFO - 'NO_EM_RESTART': '1', 22:38:10 INFO - 'PAGER': '/bin/cat', 22:38:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:10 INFO - 'PWD': '/builds/slave/test', 22:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:10 INFO - 'SHELL': '/bin/bash', 22:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:38:10 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:10 INFO - 'USER': 'cltbld', 22:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:10 INFO - 'XPC_FLAGS': '0x0', 22:38:10 INFO - 'XPC_SERVICE_NAME': '0', 22:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 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)) 22:38:10 INFO - Cleaning up... 22:38:10 INFO - Return code: 0 22:38:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:10 INFO - 'HOME': '/Users/cltbld', 22:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:10 INFO - 'LOGNAME': 'cltbld', 22:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:10 INFO - 'MOZ_NO_REMOTE': '1', 22:38:10 INFO - 'NO_EM_RESTART': '1', 22:38:10 INFO - 'PAGER': '/bin/cat', 22:38:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:10 INFO - 'PWD': '/builds/slave/test', 22:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:10 INFO - 'SHELL': '/bin/bash', 22:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:38:10 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:10 INFO - 'USER': 'cltbld', 22:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:10 INFO - 'XPC_FLAGS': '0x0', 22:38:10 INFO - 'XPC_SERVICE_NAME': '0', 22:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:11 INFO - Downloading/unpacking jsonschema 22:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:38:13 INFO - Downloading/unpacking functools32 (from jsonschema) 22:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:13 INFO - Downloading functools32-3.2.3-2.tar.gz 22:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:38:14 INFO - warning: no files found matching '*.txt' 22:38:14 INFO - no previously-included directories found matching 'build' 22:38:14 INFO - no previously-included directories found matching 'dist' 22:38:14 INFO - no previously-included directories found matching '.git*' 22:38:14 INFO - Installing collected packages: jsonschema, functools32 22:38:14 INFO - Running setup.py install for functools32 22:38:14 INFO - warning: no files found matching '*.txt' 22:38:14 INFO - no previously-included directories found matching 'build' 22:38:14 INFO - no previously-included directories found matching 'dist' 22:38:14 INFO - no previously-included directories found matching '.git*' 22:38:14 INFO - Successfully installed jsonschema functools32 22:38:14 INFO - Cleaning up... 22:38:14 INFO - Return code: 0 22:38:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:38:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:38:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:38:14 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 0x107fc4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107fcb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb949d59f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, '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.bHjSg6W1pj/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.MxP46X7dys/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 22:38:14 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 22:38:14 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 22:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:14 INFO - 'HOME': '/Users/cltbld', 22:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:14 INFO - 'LOGNAME': 'cltbld', 22:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:14 INFO - 'MOZ_NO_REMOTE': '1', 22:38:14 INFO - 'NO_EM_RESTART': '1', 22:38:14 INFO - 'PAGER': '/bin/cat', 22:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:14 INFO - 'PWD': '/builds/slave/test', 22:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:14 INFO - 'SHELL': '/bin/bash', 22:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:38:14 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:14 INFO - 'USER': 'cltbld', 22:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:14 INFO - 'XPC_FLAGS': '0x0', 22:38:14 INFO - 'XPC_SERVICE_NAME': '0', 22:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:14 INFO - Downloading/unpacking flake8 22:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:38:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:38:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:38:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:17 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 22:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:38:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:38:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:38:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:38:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:38:18 INFO - no previously-included directories found matching 'docs/_build' 22:38:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:38:18 INFO - Running setup.py install for pep8 22:38:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:38:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:38:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:38:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:38:18 INFO - no previously-included directories found matching 'docs/_build' 22:38:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:38:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:38:18 INFO - Cleaning up... 22:38:18 INFO - Return code: 0 22:38:18 INFO - Running post-action listener: _resource_record_post_action 22:38:18 INFO - Running post-action listener: _start_resource_monitoring 22:38:18 INFO - Starting resource monitoring. 22:38:18 INFO - ##### 22:38:18 INFO - ##### Running install step. 22:38:18 INFO - ##### 22:38:18 INFO - Running pre-action listener: _resource_record_pre_action 22:38:18 INFO - Running main action method: install 22:38:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:38:18 INFO - Reading from file tmpfile_stdout 22:38:18 INFO - Detecting whether we're running mozinstall >=1.0... 22:38:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:38:19 INFO - Reading from file tmpfile_stdout 22:38:19 INFO - Output received: 22:38:19 INFO - Usage: mozinstall [options] installer 22:38:19 INFO - Options: 22:38:19 INFO - -h, --help show this help message and exit 22:38:19 INFO - -d DEST, --destination=DEST 22:38:19 INFO - Directory to install application into. [default: 22:38:19 INFO - "/builds/slave/test"] 22:38:19 INFO - --app=APP Application being installed. [default: firefox] 22:38:19 INFO - mkdir: /builds/slave/test/build/application 22:38:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:38:50 INFO - Reading from file tmpfile_stdout 22:38:50 INFO - Output received: 22:38:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:38:50 INFO - Running post-action listener: _resource_record_post_action 22:38:50 INFO - ##### 22:38:50 INFO - ##### Running run-tests step. 22:38:50 INFO - ##### 22:38:50 INFO - Running pre-action listener: _resource_record_pre_action 22:38:50 INFO - Running main action method: run_tests 22:38:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:38:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:38:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:38:50 INFO - 2016-04-20 22:38:50.468 screenresolution[1642:8335] starting screenresolution argv=screenresolution get 22:38:50 INFO - 2016-04-20 22:38:50.471 screenresolution[1642:8335] Display 0: 1600x1200x32@60 22:38:50 INFO - 2016-04-20 22:38:50.478 screenresolution[1643:8337] starting screenresolution argv=screenresolution list 22:38:50 INFO - Available Modes on Display 0 22:38:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:38:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:38:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:38:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:38:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:38:50 INFO - Intel Iris: 22:38:50 INFO - Chipset Model: Intel Iris 22:38:50 INFO - Type: GPU 22:38:50 INFO - Bus: Built-In 22:38:50 INFO - VRAM (Dynamic, Max): 1536 MB 22:38:50 INFO - Vendor: Intel (0x8086) 22:38:50 INFO - Device ID: 0x0a2e 22:38:50 INFO - Revision ID: 0x0009 22:38:50 INFO - Displays: 22:38:50 INFO - Display: 22:38:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:38:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:38:50 INFO - Display Serial Number: 1600x1200 60 22:38:50 INFO - Main Display: Yes 22:38:50 INFO - Mirror: Off 22:38:50 INFO - Online: Yes 22:38:50 INFO - Rotation: Supported 22:38:50 INFO - Return code: 0 22:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:38:50 INFO - Python 2.7.3 22:38:50 INFO - Return code: 0 22:38:50 INFO - grabbing minidump binary from tooltool 22:38: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')]} 22:38:50 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 0x107e68d30>, '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 0x107cccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e5fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:38:50 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 22:38:50 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 22:38:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:38:50 INFO - Return code: 0 22:38:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:38:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:38:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:38:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:38:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:38:52 INFO - Return code: 0 22:38:52 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/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 22:38:52 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/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0189 --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 22:38:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bHjSg6W1pj/Render', 22:38:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:52 INFO - 'HOME': '/Users/cltbld', 22:38:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:52 INFO - 'LOGNAME': 'cltbld', 22:38:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:38:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:52 INFO - 'MOZ_NO_REMOTE': '1', 22:38:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:38:52 INFO - 'NO_EM_RESTART': '1', 22:38:52 INFO - 'PAGER': '/bin/cat', 22:38:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:52 INFO - 'PWD': '/builds/slave/test', 22:38:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:38:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:52 INFO - 'SHELL': '/bin/bash', 22:38:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MxP46X7dys/Listeners', 22:38:52 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 22:38:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:52 INFO - 'USER': 'cltbld', 22:38:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:52 INFO - 'XPC_FLAGS': '0x0', 22:38:52 INFO - 'XPC_SERVICE_NAME': '0', 22:38:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:52 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/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 22:38:52 INFO - mozversion application_buildid: 20160420211019 22:38:52 INFO - mozversion application_changeset: 26b73eb3a49119c43b633b67564496e285ab70e6 22:38:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:38:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:38:52 INFO - mozversion application_name: Firefox 22:38:52 INFO - mozversion application_remotingname: firefox-dev 22:38:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:38:52 INFO - mozversion application_vendor: Mozilla 22:38:52 INFO - mozversion application_version: 47.0a2 22:38:52 INFO - mozversion platform_buildid: 20160420211019 22:38:52 INFO - mozversion platform_changeset: 26b73eb3a49119c43b633b67564496e285ab70e6 22:38:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:38:52 INFO - mozversion platform_version: 47.0a2 22:38:52 INFO - using testdate: 1461217132 22:38:52 INFO - actual date: 1461217132 22:38:52 INFO - starting webserver on 'localhost:49275' 22:38:52 INFO - SUITE-START | Running 2 tests 22:38:52 INFO - TEST-START | dromaeo_css 22:38:52 INFO - operating with platform_type : mac_ 22:38:52 INFO - Initialising browser for dromaeo_css test... 22:38:52 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpd2kp_x/profile http://localhost:49275/getInfo.html) 22:38:55 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 22:38:55 INFO - PROCESS | 1661 | colorDepth:24 22:38:55 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 22:38:55 INFO - PROCESS | 1661 | __metrics 22:38:55 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 22:38:55 INFO - TEST-INFO | 1661: exit 0 22:38:55 INFO - Browser initialized. 22:38:55 INFO - Running cycle 1/1 for dromaeo_css test... 22:38:55 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpd2kp_x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:39:07 INFO - PROCESS | 1665 | RSS: Main: 218230784 22:39:07 INFO - PROCESS | 1665 | 22:39:57 INFO - PROCESS | 1665 | 1461217197368 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] 22:42:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:42:09 INFO - PROCESS | 1665 | RSS: Main: 230105088 22:42:09 INFO - PROCESS | 1665 | 22:45:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 22:45:12 INFO - PROCESS | 1665 | RSS: Main: 240603136 22:45:12 INFO - PROCESS | 1665 | 22:48:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 22:48:14 INFO - PROCESS | 1665 | RSS: Main: 242552832 22:48:14 INFO - PROCESS | 1665 | 22:51:16 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 22:51:17 INFO - PROCESS | 1665 | RSS: Main: 244289536 22:51:17 INFO - PROCESS | 1665 | 22:54:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 22:54:20 INFO - PROCESS | 1665 | RSS: Main: 247291904 22:54:20 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 22:57:22 INFO - PROCESS | 1665 | RSS: Main: 248557568 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | __start_tp_report 22:57:22 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 22:57:22 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 22:57:22 INFO - PROCESS | 1665 | |i|pagename|runs| 22:57:22 INFO - PROCESS | 1665 | |0;dojo.html;3515.542979412676;3560.5015297858295;3576.7317451191166;3624.601293857675;3641.654042865927;5956.791512297071;5960;5961.493273071783;5972.581919265654;6045.697715114237;5980.641161530311;6003.399660033994;6011.489023433013;6036.607620504664;6037.2151620289305;5352.036633405985;5362.43694412086;5378.220158077076;5401.865608222278;5474.534664553517;5286.365636123652;5385.246065550826;5405.378381486121;5408.91886621701;5448.991936491742;5344.946550534483;5374.086405311087;5393.460653934614;5421.457854214586;5427.592930530206;5409.972950135263;5430.239766432723;5450.700211488366;5450.91823622646;5451.754671039813;5312.362516498007;5330.546903513206;5350.411454739976;5382.6232163748555;5412.83761487156;5764.048931926269;5811.157382179603;5934.821955341346;5982.551308651869;6037.243447035884;6260.123582698426;6278.340774218733;6284.654344011077;6305.8108256752375;6322.71547780351;6357.459615932651;6369.235691717024;6381.93618063818;6393.776217832354;6399.104125422445;5962.314333851619;6038.9396106038375;6114.205153330038;6124.142620033199;6127.18952814863;6439.678016099177;6468.482521398277;6484.675766211671;6491.51313651478;6507.544471886922;6278.591891527036;6279.277883043417;6289.622622642656;6290.55966082379;6292.339304372975;6195.628262304186;6258.874822503524;6287.717052732638;6353.5870168438905;6360.109584658151;6231.688415579203;6250.562460627803;6286.9056964145575;6296.527760417987;6302.991521356561;6156.938430615726;6239;6290.968545157337;6313.305536390993;6346.873062538723;5021.271915572222;5044.621653375938;5061.240813877874;5069.467705890902;5100.413452452941;4006.098627808778;4423.867283981422;4499.55004499554;4514.503404625489;4550.271956486946;5846.269216347957;5956.6723830188885;5972.790952316648;5998.400159984054;6059.7273122709585;5361.571074314045;6197.628142311476;6286.654234017082;6368.203974503187;6394.424501794861;4777.9044419112115;4829;4963.354763880672;5243.318368612132;6075.2405949256345;5466.207399926989;5547.528460080938;5587.7485513152005;5597.356304025089;5627.32472103342;5772.567057470623;6433.1636887205295;6545.836354091186;6631.535792494479;6665.833354166185;6395.616263024047;6395.712192951235;6396.296407395276;6397.904031439439;6408.679566021773;6357;6558.475321974326;6611.173603299588;6637.269900311058;6669.199696036507;6286.622802631857;6311.936880631319;6313.431791138636;6316.905246421215;6337.04944258365;6231.594946328474;6370.93629063722;6381.266154392304;6392.264889537762;6398.840028999126;6133.693315334304;6134.926457743815;6192.907106393498;6233.438990490878;6271.843203919939;6217.378262173927;6245.094461303176;6246.937530624818;6249.999999999978;6271.184745982993;6040.063790112539;6152.477039451696;6198.349173336915;6216.409441103088;6221.657808820558;6123.571350005457;6147.600405973598;6233.438990490878;6260.092286618506;6272.968635156795;5516.172574113915;6174.25908890936;6188.938110619017;6206.565540412128;6244.46922011616 22:57:22 INFO - PROCESS | 1665 | |1;ext.html;486.51834683663185;579.3511267380537;590.5806877117246;681.9931800681995;689.2383915773071;39928.00179995502;40769.59230407695;40842.591574084254;41048.384274235876;41063.58936410635;42750.93122671927;43302.56697433025;43317.7834110829;43337.56662433375;43416.91457713559;9513.5243237838;9638.518074096268;9678.709638710814;9726.17327527161;9771.364861283993;9565.473898935556;9835;9835.606575736962;9876.753081172992;9917.603295868194;9747.074027967366;9809;9847.16299114576;9848.40909545426;9881.604735810559;9729.513524323791;9800.460974646427;9810.558524866326;9834.754131146707;9873.654422095227;9708.330209192458;9806.411615303105;9821.165200957927;9824.06671366219;9842.65550705729;35178.29643407118;42066.789666051474;42273.57726422727;42285.78857105726;42579;3043.163130139207;3124.937501249985;3250.203700093469;3311.8344082795766;3359.2777552826014;26084.73915260842;28728.42543149146;28823.991160309288;29746.107616771533;30200.697993020007;2709.403931135147;2775.8334499929865;2818.055951256332;2828.1374180874823;2829.0381270367966;289293;308213;342105;343125;343214;50186.24720629193;74827;75209.62395188099;75511.4897702043;75740.24259757443;44243.33634995478;49452.752736236085;49638.75180624074;49740.75129624401;49842.50157498451;14064.507742229178;16659;16998.320067197175;17008.234629441704;17009.064501452292;13414.262215578237;13440.126391784503;13524.391402386917;13561.660958475919;13566.389512471766;13214.73570528584;15636.905416620955;15785.605359866095;16044.919775401047;16111.033337999757;5715.371309155989;6612.768553100618;6724.394804467622;6769.627670478072;6792.660366981631;946.654471118037;955.1833182628891;1020.9234307426973;1064.6646306413609;1085.148158695436;30773.538396924065;31525.369492610018;31660.683393165775;32144.874929377358;32577.18557036093;1087.4671411008549;1108.1744100645071;1122.2985633978763;1170.800963836133;1247.9376031198403;15220;16098.517044488684;16187;16234.83765162357;16335.836641633432;341775;344933;346151;347921;347928;67680.32319676838;68625;68637.65681171563;68923.65538172277;69121.30878691148;1521.5435369389138;1534.6316883947995;1549.5893588199135;1558.462330495977;1681.7141086015163;28904.554772261723;30603.846980764953;30691.192090452023;31275.84362078266;31470;57489.712551436976;57515.71242143762;58283;58434;58665.70667146637;441968;445569;445793;447697;448294;35512.64487355093;35769;36250.8187459061;36420.635793641726;36992.445113322785;48229.794255143905;49219.50780492293;49919.75040124921;50165.74917125391;50239;40058.59941400549;40310;40372;40436.59563404328;41384.58615413807;39667.801660991514;39928.40107398333;40117.79941100276;40176.565406075635;40945.79527102345;32618.673813261565;40351;41013;42282.36576451417;42587.57412425836;33345.66654333426;35570.93287201388;35647.64352356443;35688.286234275685;35990.4601430984;26697.33256668521;26715.06497272586;26787.866060670352;26866.194014179608;27250.454990899674 22:57:22 INFO - PROCESS | 1665 | |2;jquery.html;383.5857274143923;385.0431677281954;388;392.7093950476648;395.48784324300027;17433.82566174338;18477.53806154847;19997.90001049993;20922.476938076536;20926;20548.589028219427;20764;20826.687599685978;20948.952552372397;21133.894330528325;5882.911756323659;6149.661768602725;6172.135900973867;6176.84557886052;6272.1219029335925;5658.377578466366;5871.911921321183;5930.406959304079;5936.5547583931375;5953.642781433106;5563.638363506359;5803.941960580382;5807.73865176066;5814.273215848019;5923.08192230205;5682.602217844753;5722.427757224286;5746.48281654651;5798.971005144989;5812.912806307888;5761.3950535194035;5780.248567686216;5784.07454807233;5787.450192231773;5812.796552120655;13222.66943326415;13573.321333933263;13757.79363309551;13793.655158621115;13826.377812998438;2045.263705066175;2246.4496198431325;2346.0263990443946;2441.5727247731575;2800.9719902800916;9729.805403891953;9906.752331191707;9971.451570163637;10016.899831001743;10234;1794.0850166415091;1801.1534578747967;1821.253286152671;1858.479625704805;1863.1802007116826;99429;107133.46433267862;108152.4592377041;110700.9999999992;110963;11758.529658813552;11809.822852657217;11870.28778273289;11933.105017123751;11933.403329833474;14530;14539.27303634814;14541.709165816836;14555.781663274847;14586.562403127924;9091.863622045674;9117.179453849185;9133.817323653622;9154.725358239264;9188.264938805014;9223.44659320443;9230.984591694909;9275;9306.696122876418;9316.580753866092;3689.594144644087;4615.838445654386;4668.649851261169;4681.438227412732;4721.1501929652995;1947.7370554974898;2007.9397618071482;2046.3042565527494;2056.1569756399804;2056.8354531637433;930.8696782450462;999.3003904912631;1052.7420781908481;1116.5980247111033;1116.8603924509437;20736.792632073786;21046.894765526074;21085.68371474429;21333;21421.571568568543;966.9854952175723;1012.2256473797551;1013.2400699475394;1015.3349556040805;1085.2891356161726;10088.69733907984;11346.943265283786;11638.06895448362;12095.879041209651;12118.939405302917;658375;674843;683724.58137709;687971;694103;128978;130326;130477;130681;132378.33810831077;1102.7132945434246;1105.6738262212666;1135.0975974100609;1135.812590922505;1138.1463902073444;19244.326448574233;19876.801231988073;19966.90016549908;20081;20262.696059558824;37052.073698157765;48865.755671221414;50791.730206745124;52663.73668131788;52730.73634631802;62405.06392404027;73875.26124738899;76462.4707505858;78645.60677196577;79376.60311698404;8080.393970452122;8255.174482551936;8333.167815361372;8361.916380836357;8368.790780230544;9468.95265523668;11266.436678166223;13070.607881763563;13961;14251.857481425053;11080.61217857371;11569.305841649528;11773.411329433666;12144.33206173669;12227.510899564273;11674.76650466969;11825.46785394642;11915.106367022334;11994.100442467025;12065.81901271464;6074.665893375906;9608.75978100553;9985.301028927905;12117.69705757363;12407.441665125272;7357.484976051625;8347.248747612744;8394;8510.74467765968;8621.741347759576;12098.879011210127;12101.69745756328;12138.75722485563;12247.69380765488;12271.570495032987 22:57:22 INFO - PROCESS | 1665 | |3;mootools.html;1656.7929008873891;1670.6909221793965;2029.4317591074496;2064.5561204341057;2122.3420739570743;8218.958905205465;8918.866217006751;9053.683121090764;9243.029481904396;9262.768430789234;8960.82078358434;8982.640694372218;9036.77408064797;9084.727458176265;9091.681791137311;2501.3746563359205;2522.9495410091786;2534.4550921551863;2535.5055764126005;2541.2376287113793;2476.4675594747127;2512.5979843224995;2514.094925826701;2535.3408113890337;2535.9856057577026;2503.9749602503925;2512.108201588437;2514.283429222672;2543.173468622705;2557.6419301297833;2523.8485690858515;2540.745925407454;2547.8216524843083;2549.847009179455;2559.987200063997;2514.962275565868;2543.326018605071;2544.9872750636314;2552.680914885639;2559.910403135881;9099.590518426687;9151.222146117589;9153.08469153086;9158.313126515539;9196.402233854778;5600.243967064475;5719.25649665545;5724.198612194296;5731.197632331477;5734.512566431858;3658.067192865829;3705.314516814398;3761.567419746709;3764.849406023756;3767.698584113264;1059.1632610237903;1059.3061544688242;1087.320424734541;1095.1786160379716;1098.6923661374828;11009.99999999992;11391.544338226544;12424.937875310656;12449.626511204679;12453.439595218146;4849.951500485021;4881.877953051202;4953.72754498506;4964.776585053609;4973.5523802857915;10511.369317840801;10512.684619461428;10529.684109476728;10545.578176872992;10561.735956601147;5578.665280083208;5587.972060139673;5603.719814009283;5611.831645050655;5626.85932851682;4267.8079486423185;4565.178267911741;4958.256261560723;5044.571211447068;5090.796368145307;3570.821458927095;4034.0923292259595;4094.1811637672586;4140.834366625301;4197.874063778091;1232.2298563397876;1332.1673953778889;1369.137443410645;1377.9931100344434;1388.937497812601;1114.654457118296;1246.3581255653346;1434.8091084400132;1440.8343040550471;1464.3337281536876;7590.8861367079535;8424.283935865398;8479.491230526064;8499.872501912476;8514.361422893307;1295.6372215779597;1296.7795474769312;1296.9546065887648;1328.880400763936;1334.2328161307287;6454.19322584677;6464.547481676332;6486.318936511692;6487.2215334159255;6493.70778314977;10420.895791042145;10928.344299342065;11028.44857757125;11062.22564420499;11108.777824443467;10465.058144767008;10862.185336099667;10921.563137474572;10945.45272736376;10975.670729878117;8420.663173472993;8577.313814894793;8587.184217499327;8617.396782225183;8650.480971141626;8224.712135075244;8251.504909705436;8279.50322980623;8286.295664868312;8332.333413326798;9614.663486777928;9735.41587504752;9831.262655301025;9871.901280987098;9905.504724763878;9933.65232216869;10119.696409107739;10141.695749127539;10296.124829389286;10321;6935.271796461299;7605.809854753676;7629.427792915442;7731.652075656497;7848.960755196187;8415.53714545706;8442.36682248846;8444.986601607847;8455.492670439793;8468.407211495136;7282.4902256841515;7295.452841037049;7480.625968701652;7551.471397002157;7568.5837278950185;6167.753289868355;6210.347913469202;7274.599897005715;7316.451266154953;7386.556806591621;7862.803429914298;7914.287714105758;7917.485363451357;7942.880856787268;7970.641321140679;6973.58158510491;6981.0924579804305;6997.860042799217;7030.543014704028;7043.7182512700965;5721.885562288814;7103.289671032855;7124.5725256484775;7149.892751608626;7165.749198778018 22:57:22 INFO - PROCESS | 1665 | |4;prototype.html;342.63851636523464;349.7604141163303;351.9912002199943;353.26520836659745;361.983710733017;6814.727410903558;6824.4540436765;6861.656917154147;6864.588124712514;6885.931140688592;6030.185924900137;6287.685615719207;6732.057511948331;6784.864302713942;6829.590224586528;4763.404574428197;4885.340479035321;4973.452920178777;4998.10034193845;5036.899262014757;4937.111319962405;4972.552470277675;4976.954839483695;4988.825391111312;5003.649744517885;4915.705057696549;4922.8030878764985;4949.381327334083;4955.851324460272;4971.651984361078;4953.331300274453;4953.455119936804;4963.503649635026;4965.155923493015;4978.128827455199;4301.40847886281;4306.6124048835445;4309;4311.094670119279;4314.633256173198;6269.843253918643;6572.244191917895;6591.40677339037;6608.702608382634;6610.669466526702;4830.396200474941;5876.794312199096;5923.614965027259;5938.732757025944;5960.4635582797755;7387.374777548931;7409.88885166723;7418.221086785918;7424.034875466209;7429.665665045032;4349.826006959718;4737.620990320766;4747.501512341189;4756.381670382828;4767.570918617341;7294.96352518234;7729.111152217454;7784.961075194645;7817.921820781833;7829.256220659028;4616.961183733701;4712.5994290485005;4790.688605240648;4827.831025914076;4828.37231159954;4895.779689913962;4903.8528844134735;4905.362302900671;4916.606671466274;4924.212126059813;5828.941710582924;5925.170476133345;5933;5944.959632064371;5947.821565353046;6166.5991710539565;7302.941073544306;7413.851722965511;7430.294122058396;7436;1657.767912492252;1675.9162041897857;1695.669344477815;1699.8555122814453;1701.8042925063528;1059.5602824827765;1083.6725011860408;1085.0614218700766;1088.7278180454887;1091.2852081886376;5912.438318359749;6799.286074962157;6801.863962720718;6850.520463567602;6855.07456493377;7634;7640.350570201484;7669.3864490841715;7683.9231607683205;7715.4599178058115;6339.936600634027;6446.419822215929;6818.28408017161;6832.009358642969;6835.384815366641;3699.204670995695;3902.375619900802;3974.3839704845795;4009.599040095967;4040.959590404117;6180.042093475518;7011.544249623758;7973.521588704696;8061.153578874251;8072.475289106189;6427.357264273535;6955.791326260221;7351.595662238628;7942.563159026194;7975.361971042303;6812.659367031629;7988.281054705104;8005.759827205193;8013.679452821939;8117.391195660349;7376.852462950818;7671.616419178907;7698.2686644768655;7710.807229819075;7727.884081738666;7511.549307041595;7544.207858175032;7548.358389536731;7593.772186834404;7596.354309883722;7001.719931202695;7626.970359001686;8015.879761803461;8038.236367545074;8061.435699500978;4823.408275269223;4842.854714358575;4853.95146048535;4862.440819305683;4864.051509955525;5564.360098588714;7319.963400182965;7651.234876512304;7678.846423071619;7729.95645587861;7362.558246505227;7406.740764073232;7425.14610819741;7426.257374262531;7455.627218639155;7402.777916662509;7408.888866666896;7421.3691836192365;7547.471676982558;7601.695932162873;7041.839566012085;7114.466415018791;7239.927600723925;7258.600776957107;7273.709051638087;7317.0487836580905;7382.151052629017;7391.334779869838;7398.852022959618;7403.851922961403;6022.494376405899;7172.820679483055;7194.172670143001;7276;7280.089988751406 22:57:22 INFO - PROCESS | 1665 | |5;yui.html;669.8928171492562;693.9320823944645;709.3225969199411;745.414849343265;757.1444267977187;14984.925075374635;15170.317335719892;15392;15433.768493472593;15438.305276262581;15177.54467365978;15236.542903712878;15262;15271.770923436157;15304.464343747972;809.2837838512929;1001.1940387987667;1012.2812802909924;1023.6007956896816;1043.400044974143;1032.2516175772541;1036.2124785163292;1037.3309215555962;1042.2912419554739;1044.3785947361307;1086.8369744538304;1087.5758454202828;1089.9863127291615;1090.5692251560629;1090.5801266512372;1046.1317106801337;1068.1881769854888;1073.002108039523;1076.0745758647518;1076.5370890517074;800.1038836503154;810.6878851642101;813.6135335715547;815.787895147266;818.3984771193168;15277.770833437507;15481.458148964844;15750.527484175493;15845.60385990348;15921;13046.41291141901;13060.673483162902;13088.47646094155;13124.212547247196;13150.408231629599;9993.150582200522;10206.438645874474;10222.591096356136;10497.107745841613;10658.253922225447;3545.9290814183573;3554.2891421715503;3592.4970504129183;3657.4330978698076;3817.2556351511466;88603.22793544093;88974.5551272246;89178.10821891762;89478;90544.54727726319;9669.70990870275;9708.320417570701;9900.108990190918;9974.40153590787;10033.147182489423;22894.31317060491;22921.541569168523;22963.311100667008;22966.311010669706;23056.308310750705;6334.398232167937;7351.264873512606;7527.8118047048165;7582.696692132253;7649.57927313992;3951.9802400988387;3988.202359528106;4043.454133691973;4049.919001619951;4058.8782336529953;3573.910652233715;3674.853005879735;3690.1881586051027;3710.9980305317413;3718.981405092957;669.9631520266335;681.7954613615896;687.0930371909033;689.9689513971883;692.6017539914468;521.4498703867456;568.5338022821247;573.8049063318408;575.5711994564076;601.9097135429721;7841.137474877759;8152.959235203786;8449.746507604781;8684.261837743736;8766.298696104297;490.3380436410817;515.5514702209102;592.7154965616528;652.26620052441;656.8423578341163;3816.1222918728295;3880.5149356330458;3920.353141731582;3953.169834334822;4084.71407001513;90300;92230.53884730533;92751.53624231835;93160;93391.0660893396;78109.60945195238;79085.20914790893;80021.19978800138;80635.19364806394;81104.18895810966;69429.3057069433;69710;69976;70373.64813175901;71101;7895.960520197592;7897.091834438883;7905.56519391439;7915.762527124196;7937.722179723682;47976.7601161992;48734.26898596453;49124;49232.01535969332;49509.50490495049;63881.680591598604;66674.66662666849;66838;68995;69610;10243.897561024294;10266.948665256627;10352.948235258775;10361.4301213434;10386.792264154523;11024.50389732448;11027.724306892393;12006;12161.391930403268;12427.75144497123;11117.944410277896;11119.332840029625;11134.109271258118;11251.156163287622;11469.770604588028;11044.282121662067;11090.778184436105;11272.985431311221;11275.887241127812;12005.63983080509;9494.430334179971;11181.552737890719;11198;11371.090312775124;11754.882451175721;9500.42997420157;9556.42661440288;9604.327697061139;9697.709068727949;9726.805463890541;9465.905340946503;9539.761505962406;9574.138327550554;9605.423674579548;9857 22:57:22 INFO - PROCESS | 1665 | __end_tp_report 22:57:22 INFO - PROCESS | 1665 | __start_cc_report 22:57:22 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,356 22:57:22 INFO - PROCESS | 1665 | __end_cc_report 22:57:22 INFO - PROCESS | 1665 | __startTimestamp1461218242649__endTimestamp 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | ------- Summary: start ------- 22:57:22 INFO - PROCESS | 1665 | Number of tests: 6 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5879.01 Median:6147.60 stddev:654.02 (10.6%) stddev-sans-first:629.19 22:57:22 INFO - PROCESS | 1665 | Values: 3515.5 3560.5 3576.7 3624.6 3641.7 5956.8 5960.0 5961.5 5972.6 6045.7 5980.6 6003.4 6011.5 6036.6 6037.2 5352.0 5362.4 5378.2 5401.9 5474.5 5286.4 5385.2 5405.4 5408.9 5449.0 5344.9 5374.1 5393.5 5421.5 5427.6 5410.0 5430.2 5450.7 5450.9 5451.8 5312.4 5330.5 5350.4 5382.6 5412.8 5764.0 5811.2 5934.8 5982.6 6037.2 6260.1 6278.3 6284.7 6305.8 6322.7 6357.5 6369.2 6381.9 6393.8 6399.1 5962.3 6038.9 6114.2 6124.1 6127.2 6439.7 6468.5 6484.7 6491.5 6507.5 6278.6 6279.3 6289.6 6290.6 6292.3 6195.6 6258.9 6287.7 6353.6 6360.1 6231.7 6250.6 6286.9 6296.5 6303.0 6156.9 6239.0 6291.0 6313.3 6346.9 5021.3 5044.6 5061.2 5069.5 5100.4 4006.1 4423.9 4499.6 4514.5 4550.3 5846.3 5956.7 5972.8 5998.4 6059.7 5361.6 6197.6 6286.7 6368.2 6394.4 4777.9 4829.0 4963.4 5243.3 6075.2 5466.2 5547.5 5587.7 5597.4 5627.3 5772.6 6433.2 6545.8 6631.5 6665.8 6395.6 6395.7 6396.3 6397.9 6408.7 6357.0 6558.5 6611.2 6637.3 6669.2 6286.6 6311.9 6313.4 6316.9 6337.0 6231.6 6370.9 6381.3 6392.3 6398.8 6133.7 6134.9 6192.9 6233.4 6271.8 6217.4 6245.1 6246.9 6250.0 6271.2 6040.1 6152.5 6198.3 6216.4 6221.7 6123.6 6147.6 6233.4 6260.1 6273.0 5516.2 6174.3 6188.9 6206.6 6244.5 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:54820.31 Median:40243.28 stddev:99149.71 (246.4%) stddev-sans-first:99343.93 22:57:22 INFO - PROCESS | 1665 | Values: 486.5 579.4 590.6 682.0 689.2 39928.0 40769.6 40842.6 41048.4 41063.6 42750.9 43302.6 43317.8 43337.6 43416.9 9513.5 9638.5 9678.7 9726.2 9771.4 9565.5 9835.0 9835.6 9876.8 9917.6 9747.1 9809.0 9847.2 9848.4 9881.6 9729.5 9800.5 9810.6 9834.8 9873.7 9708.3 9806.4 9821.2 9824.1 9842.7 35178.3 42066.8 42273.6 42285.8 42579.0 3043.2 3124.9 3250.2 3311.8 3359.3 26084.7 28728.4 28824.0 29746.1 30200.7 2709.4 2775.8 2818.1 2828.1 2829.0 289293.0 308213.0 342105.0 343125.0 343214.0 50186.2 74827.0 75209.6 75511.5 75740.2 44243.3 49452.8 49638.8 49740.8 49842.5 14064.5 16659.0 16998.3 17008.2 17009.1 13414.3 13440.1 13524.4 13561.7 13566.4 13214.7 15636.9 15785.6 16044.9 16111.0 5715.4 6612.8 6724.4 6769.6 6792.7 946.7 955.2 1020.9 1064.7 1085.1 30773.5 31525.4 31660.7 32144.9 32577.2 1087.5 1108.2 1122.3 1170.8 1247.9 15220.0 16098.5 16187.0 16234.8 16335.8 341775.0 344933.0 346151.0 347921.0 347928.0 67680.3 68625.0 68637.7 68923.7 69121.3 1521.5 1534.6 1549.6 1558.5 1681.7 28904.6 30603.8 30691.2 31275.8 31470.0 57489.7 57515.7 58283.0 58434.0 58665.7 441968.0 445569.0 445793.0 447697.0 448294.0 35512.6 35769.0 36250.8 36420.6 36992.4 48229.8 49219.5 49919.8 50165.7 50239.0 40058.6 40310.0 40372.0 40436.6 41384.6 39667.8 39928.4 40117.8 40176.6 40945.8 32618.7 40351.0 41013.0 42282.4 42587.6 33345.7 35570.9 35647.6 35688.3 35990.5 26697.3 26715.1 26787.9 26866.2 27250.5 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:36664.00 Median:21066.29 stddev:112615.04 (534.6%) stddev-sans-first:112898.00 22:57:22 INFO - PROCESS | 1665 | Values: 383.6 385.0 388.0 392.7 395.5 17433.8 18477.5 19997.9 20922.5 20926.0 20548.6 20764.0 20826.7 20949.0 21133.9 5882.9 6149.7 6172.1 6176.8 6272.1 5658.4 5871.9 5930.4 5936.6 5953.6 5563.6 5803.9 5807.7 5814.3 5923.1 5682.6 5722.4 5746.5 5799.0 5812.9 5761.4 5780.2 5784.1 5787.5 5812.8 13222.7 13573.3 13757.8 13793.7 13826.4 2045.3 2246.4 2346.0 2441.6 2801.0 9729.8 9906.8 9971.5 10016.9 10234.0 1794.1 1801.2 1821.3 1858.5 1863.2 99429.0 107133.5 108152.5 110701.0 110963.0 11758.5 11809.8 11870.3 11933.1 11933.4 14530.0 14539.3 14541.7 14555.8 14586.6 9091.9 9117.2 9133.8 9154.7 9188.3 9223.4 9231.0 9275.0 9306.7 9316.6 3689.6 4615.8 4668.6 4681.4 4721.2 1947.7 2007.9 2046.3 2056.2 2056.8 930.9 999.3 1052.7 1116.6 1116.9 20736.8 21046.9 21085.7 21333.0 21421.6 967.0 1012.2 1013.2 1015.3 1085.3 10088.7 11346.9 11638.1 12095.9 12118.9 658375.0 674843.0 683724.6 687971.0 694103.0 128978.0 130326.0 130477.0 130681.0 132378.3 1102.7 1105.7 1135.1 1135.8 1138.1 19244.3 19876.8 19966.9 20081.0 20262.7 37052.1 48865.8 50791.7 52663.7 52730.7 62405.1 73875.3 76462.5 78645.6 79376.6 8080.4 8255.2 8333.2 8361.9 8368.8 9469.0 11266.4 13070.6 13961.0 14251.9 11080.6 11569.3 11773.4 12144.3 12227.5 11674.8 11825.5 11915.1 11994.1 12065.8 6074.7 9608.8 9985.3 12117.7 12407.4 7357.5 8347.2 8394.0 8510.7 8621.7 12098.9 12101.7 12138.8 12247.7 12271.6 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:6205.38 Median:4969.16 stddev:3238.90 (65.2%) stddev-sans-first:3229.94 22:57:22 INFO - PROCESS | 1665 | Values: 1656.8 1670.7 2029.4 2064.6 2122.3 8219.0 8918.9 9053.7 9243.0 9262.8 8960.8 8982.6 9036.8 9084.7 9091.7 2501.4 2522.9 2534.5 2535.5 2541.2 2476.5 2512.6 2514.1 2535.3 2536.0 2504.0 2512.1 2514.3 2543.2 2557.6 2523.8 2540.7 2547.8 2549.8 2560.0 2515.0 2543.3 2545.0 2552.7 2559.9 9099.6 9151.2 9153.1 9158.3 9196.4 5600.2 5719.3 5724.2 5731.2 5734.5 3658.1 3705.3 3761.6 3764.8 3767.7 1059.2 1059.3 1087.3 1095.2 1098.7 11010.0 11391.5 12424.9 12449.6 12453.4 4850.0 4881.9 4953.7 4964.8 4973.6 10511.4 10512.7 10529.7 10545.6 10561.7 5578.7 5588.0 5603.7 5611.8 5626.9 4267.8 4565.2 4958.3 5044.6 5090.8 3570.8 4034.1 4094.2 4140.8 4197.9 1232.2 1332.2 1369.1 1378.0 1388.9 1114.7 1246.4 1434.8 1440.8 1464.3 7590.9 8424.3 8479.5 8499.9 8514.4 1295.6 1296.8 1297.0 1328.9 1334.2 6454.2 6464.5 6486.3 6487.2 6493.7 10420.9 10928.3 11028.4 11062.2 11108.8 10465.1 10862.2 10921.6 10945.5 10975.7 8420.7 8577.3 8587.2 8617.4 8650.5 8224.7 8251.5 8279.5 8286.3 8332.3 9614.7 9735.4 9831.3 9871.9 9905.5 9933.7 10119.7 10141.7 10296.1 10321.0 6935.3 7605.8 7629.4 7731.7 7849.0 8415.5 8442.4 8445.0 8455.5 8468.4 7282.5 7295.5 7480.6 7551.5 7568.6 6167.8 6210.3 7274.6 7316.5 7386.6 7862.8 7914.3 7917.5 7942.9 7970.6 6973.6 6981.1 6997.9 7030.5 7043.7 5721.9 7103.3 7124.6 7149.9 7165.7 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5951.85 Median:6758.46 stddev:1916.13 (28.4%) stddev-sans-first:1874.68 22:57:22 INFO - PROCESS | 1665 | Values: 342.6 349.8 352.0 353.3 362.0 6814.7 6824.5 6861.7 6864.6 6885.9 6030.2 6287.7 6732.1 6784.9 6829.6 4763.4 4885.3 4973.5 4998.1 5036.9 4937.1 4972.6 4977.0 4988.8 5003.6 4915.7 4922.8 4949.4 4955.9 4971.7 4953.3 4953.5 4963.5 4965.2 4978.1 4301.4 4306.6 4309.0 4311.1 4314.6 6269.8 6572.2 6591.4 6608.7 6610.7 4830.4 5876.8 5923.6 5938.7 5960.5 7387.4 7409.9 7418.2 7424.0 7429.7 4349.8 4737.6 4747.5 4756.4 4767.6 7295.0 7729.1 7785.0 7817.9 7829.3 4617.0 4712.6 4790.7 4827.8 4828.4 4895.8 4903.9 4905.4 4916.6 4924.2 5828.9 5925.2 5933.0 5945.0 5947.8 6166.6 7302.9 7413.9 7430.3 7436.0 1657.8 1675.9 1695.7 1699.9 1701.8 1059.6 1083.7 1085.1 1088.7 1091.3 5912.4 6799.3 6801.9 6850.5 6855.1 7634.0 7640.4 7669.4 7683.9 7715.5 6339.9 6446.4 6818.3 6832.0 6835.4 3699.2 3902.4 3974.4 4009.6 4041.0 6180.0 7011.5 7973.5 8061.2 8072.5 6427.4 6955.8 7351.6 7942.6 7975.4 6812.7 7988.3 8005.8 8013.7 8117.4 7376.9 7671.6 7698.3 7710.8 7727.9 7511.5 7544.2 7548.4 7593.8 7596.4 7001.7 7627.0 8015.9 8038.2 8061.4 4823.4 4842.9 4854.0 4862.4 4864.1 5564.4 7320.0 7651.2 7678.8 7730.0 7362.6 7406.7 7425.1 7426.3 7455.6 7402.8 7408.9 7421.4 7547.5 7601.7 7041.8 7114.5 7239.9 7258.6 7273.7 7317.0 7382.2 7391.3 7398.9 7403.9 6022.5 7172.8 7194.2 7276.0 7280.1 22:57:22 INFO - PROCESS | 1665 | 22:57:22 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:18649.07 Median:4054.40 stddev:26354.51 (650.0%) stddev-sans-first:26393.86 22:57:22 INFO - PROCESS | 1665 | Values: 669.9 693.9 709.3 745.4 757.1 14984.9 15170.3 15392.0 15433.8 15438.3 15177.5 15236.5 15262.0 15271.8 15304.5 809.3 1001.2 1012.3 1023.6 1043.4 1032.3 1036.2 1037.3 1042.3 1044.4 1086.8 1087.6 1090.0 1090.6 1090.6 1046.1 1068.2 1073.0 1076.1 1076.5 800.1 810.7 813.6 815.8 818.4 15277.8 15481.5 15750.5 15845.6 15921.0 13046.4 13060.7 13088.5 13124.2 13150.4 9993.2 10206.4 10222.6 10497.1 10658.3 3545.9 3554.3 3592.5 3657.4 3817.3 88603.2 88974.6 89178.1 89478.0 90544.5 9669.7 9708.3 9900.1 9974.4 10033.1 22894.3 22921.5 22963.3 22966.3 23056.3 6334.4 7351.3 7527.8 7582.7 7649.6 3952.0 3988.2 4043.5 4049.9 4058.9 3573.9 3674.9 3690.2 3711.0 3719.0 670.0 681.8 687.1 690.0 692.6 521.4 568.5 573.8 575.6 601.9 7841.1 8153.0 8449.7 8684.3 8766.3 490.3 515.6 592.7 652.3 656.8 3816.1 3880.5 3920.4 3953.2 4084.7 90300.0 92230.5 92751.5 93160.0 93391.1 78109.6 79085.2 80021.2 80635.2 81104.2 69429.3 69710.0 69976.0 70373.6 71101.0 7896.0 7897.1 7905.6 7915.8 7937.7 47976.8 48734.3 49124.0 49232.0 49509.5 63881.7 66674.7 66838.0 68995.0 69610.0 10243.9 10266.9 10352.9 10361.4 10386.8 11024.5 11027.7 12006.0 12161.4 12427.8 11117.9 11119.3 11134.1 11251.2 11469.8 11044.3 11090.8 11273.0 11275.9 12005.6 9494.4 11181.6 11198.0 11371.1 11754.9 9500.4 9556.4 9604.3 9697.7 9726.8 9465.9 9539.8 9574.1 9605.4 9857.0 22:57:22 INFO - PROCESS | 1665 | -------- Summary: end -------- 22:57:22 INFO - PROCESS | 1665 | 22:57:23 INFO - TEST-INFO | 1665: exit 0 22:57:23 INFO - TEST-OK | dromaeo_css | took 1110934ms 22:57:23 INFO - TEST-START | kraken 22:57:23 INFO - operating with platform_type : mac_ 22:57:23 INFO - Initialising browser for kraken test... 22:57:23 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpC1KjnD/profile http://localhost:49275/getInfo.html) 22:57:26 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 22:57:26 INFO - PROCESS | 1672 | colorDepth:24 22:57:26 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 22:57:26 INFO - PROCESS | 1672 | __metrics 22:57:26 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 22:57:26 INFO - TEST-INFO | 1672: exit 0 22:57:26 INFO - Browser initialized. 22:57:26 INFO - Running cycle 1/1 for kraken test... 22:57:26 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpC1KjnD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:57:38 INFO - PROCESS | 1675 | RSS: Main: 218472448 22:57:38 INFO - PROCESS | 1675 | 22:57:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 22:57:41 INFO - PROCESS | 1675 | RSS: Main: 230420480 22:57:41 INFO - PROCESS | 1675 | 22:57:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 22:57:45 INFO - PROCESS | 1675 | RSS: Main: 239337472 22:57:45 INFO - PROCESS | 1675 | 22:57:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 22:57:49 INFO - PROCESS | 1675 | RSS: Main: 243367936 22:57:49 INFO - PROCESS | 1675 | 22:57:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 22:57:51 INFO - PROCESS | 1675 | RSS: Main: 243339264 22:57:51 INFO - PROCESS | 1675 | 22:57:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:57:54 INFO - PROCESS | 1675 | RSS: Main: 245481472 22:57:54 INFO - PROCESS | 1675 | 22:57:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 22:57:57 INFO - PROCESS | 1675 | RSS: Main: 244764672 22:57:57 INFO - PROCESS | 1675 | 22:58:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 22:58:01 INFO - PROCESS | 1675 | RSS: Main: 245256192 22:58:01 INFO - PROCESS | 1675 | 22:58:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 22:58:05 INFO - PROCESS | 1675 | RSS: Main: 245006336 22:58:05 INFO - PROCESS | 1675 | 22:58:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:58:07 INFO - PROCESS | 1675 | RSS: Main: 245067776 22:58:07 INFO - PROCESS | 1675 | 22:58:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:58:09 INFO - PROCESS | 1675 | RSS: Main: 245059584 22:58:09 INFO - PROCESS | 1675 | 22:58:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:58:12 INFO - PROCESS | 1675 | RSS: Main: 245334016 22:58:12 INFO - PROCESS | 1675 | 22:58:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:58:15 INFO - PROCESS | 1675 | RSS: Main: 245624832 22:58:15 INFO - PROCESS | 1675 | 22:58:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:58:18 INFO - PROCESS | 1675 | RSS: Main: 245559296 22:58:18 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 22:58:20 INFO - PROCESS | 1675 | RSS: Main: 246915072 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | __start_tp_report 22:58:20 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 22:58:20 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 22:58:20 INFO - PROCESS | 1675 | |i|pagename|runs| 22:58:20 INFO - PROCESS | 1675 | |0;ai-astar;130;159;158;117;116;129;114;115;114;115 22:58:20 INFO - PROCESS | 1675 | |1;audio-beat-detection;147;105;158;179;146;170;156;151;146;122 22:58:20 INFO - PROCESS | 1675 | |2;audio-dft;194;199;222;261;197;203;193;194;200;200 22:58:20 INFO - PROCESS | 1675 | |3;audio-fft;65;77;66;110;55;54;57;60;116;55 22:58:20 INFO - PROCESS | 1675 | |4;audio-oscillator;81;84;116;80;71;100;155;121;117;119 22:58:20 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;85;88;85;89;86;88;108;111;132 22:58:20 INFO - PROCESS | 1675 | |6;imaging-darkroom;150;142;94;109;152;127;94;94;134;93 22:58:20 INFO - PROCESS | 1675 | |7;imaging-desaturate;91;93;91;91;92;118;144;77;151;153 22:58:20 INFO - PROCESS | 1675 | |8;json-parse-financial;48;55;63;59;60;60;58;65;56;53 22:58:20 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;62;62;72;86;102;61;86;96;60;70 22:58:20 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;81;83;82;85;82;96;108;115;109;98 22:58:20 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;144;137;126;120;122;122;121;120;132;121 22:58:20 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;135;134;135;135;133;132;139;136;134;135 22:58:20 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;70;69;64;67;65;64;70;64;67 22:58:20 INFO - PROCESS | 1675 | __end_tp_report 22:58:20 INFO - PROCESS | 1675 | __start_cc_report 22:58:20 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,913 22:58:20 INFO - PROCESS | 1675 | __end_cc_report 22:58:20 INFO - PROCESS | 1675 | __startTimestamp1461218300627__endTimestamp 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | ------- Summary: start ------- 22:58:20 INFO - PROCESS | 1675 | Number of tests: 14 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:126.70 Median:123.00 stddev:17.78 (14.5%) stddev-sans-first:18.81 22:58:20 INFO - PROCESS | 1675 | Values: 130.0 159.0 158.0 117.0 116.0 129.0 114.0 115.0 114.0 115.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:153.50 stddev:21.48 (14.0%) stddev-sans-first:22.78 22:58:20 INFO - PROCESS | 1675 | Values: 147.0 105.0 158.0 179.0 146.0 170.0 156.0 151.0 146.0 122.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:206.30 Median:200.00 stddev:20.94 (10.5%) stddev-sans-first:21.74 22:58:20 INFO - PROCESS | 1675 | Values: 194.0 199.0 222.0 261.0 197.0 203.0 193.0 194.0 200.0 200.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:71.50 Median:58.50 stddev:22.99 (39.3%) stddev-sans-first:24.27 22:58:20 INFO - PROCESS | 1675 | Values: 65.0 77.0 66.0 110.0 55.0 54.0 57.0 60.0 116.0 55.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:104.40 Median:113.00 stddev:25.90 (22.9%) stddev-sans-first:26.05 22:58:20 INFO - PROCESS | 1675 | Values: 81.0 84.0 116.0 80.0 71.0 100.0 155.0 121.0 117.0 119.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:87.00 stddev:15.80 (18.2%) stddev-sans-first:16.50 22:58:20 INFO - PROCESS | 1675 | Values: 88.0 85.0 88.0 85.0 89.0 86.0 88.0 108.0 111.0 132.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:118.90 Median:122.50 stddev:24.76 (20.2%) stddev-sans-first:23.57 22:58:20 INFO - PROCESS | 1675 | Values: 150.0 142.0 94.0 109.0 152.0 127.0 94.0 94.0 134.0 93.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:110.10 Median:91.00 stddev:28.93 (31.8%) stddev-sans-first:29.85 22:58:20 INFO - PROCESS | 1675 | Values: 91.0 93.0 91.0 91.0 92.0 118.0 144.0 77.0 151.0 153.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:59.50 stddev:4.95 (8.3%) stddev-sans-first:3.80 22:58:20 INFO - PROCESS | 1675 | Values: 48.0 55.0 63.0 59.0 60.0 60.0 58.0 65.0 56.0 53.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:71.00 stddev:15.64 (22.0%) stddev-sans-first:15.78 22:58:20 INFO - PROCESS | 1675 | Values: 62.0 62.0 72.0 86.0 102.0 61.0 86.0 96.0 60.0 70.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:82.50 stddev:13.08 (15.9%) stddev-sans-first:13.02 22:58:20 INFO - PROCESS | 1675 | Values: 81.0 83.0 82.0 85.0 82.0 96.0 108.0 115.0 109.0 98.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:124.00 stddev:8.38 (6.8%) stddev-sans-first:6.04 22:58:20 INFO - PROCESS | 1675 | Values: 144.0 137.0 126.0 120.0 122.0 122.0 121.0 120.0 132.0 121.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.80 Median:135.00 stddev:1.87 (1.4%) stddev-sans-first:1.99 22:58:20 INFO - PROCESS | 1675 | Values: 135.0 134.0 135.0 135.0 133.0 132.0 139.0 136.0 134.0 135.0 22:58:20 INFO - PROCESS | 1675 | 22:58:20 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:2.44 (3.6%) stddev-sans-first:2.55 22:58:20 INFO - PROCESS | 1675 | Values: 68.0 70.0 69.0 64.0 67.0 65.0 64.0 70.0 64.0 67.0 22:58:20 INFO - PROCESS | 1675 | -------- Summary: end -------- 22:58:20 INFO - PROCESS | 1675 | 22:58:21 INFO - TEST-INFO | 1675: exit 0 22:58:21 INFO - TEST-OK | kraken | took 58174ms 22:58:21 INFO - SUITE-END | took 1169s 22:58:21 INFO - Completed test suite (00:19:29) 22:58:21 INFO - javascript benchmark 22:58:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3515.542979412676, 3560.5015297858295, 3576.7317451191166, 3624.601293857675, 3641.654042865927, 5956.791512297071, 5960.0, 5961.493273071783, 5972.581919265654, 6045.697715114237, 5980.641161530311, 6003.399660033994, 6011.489023433013, 6036.607620504664, 6037.2151620289305, 5352.036633405985, 5362.43694412086, 5378.220158077076, 5401.865608222278, 5474.534664553517, 5286.365636123652, 5385.246065550826, 5405.378381486121, 5408.91886621701, 5448.991936491742, 5344.946550534483, 5374.086405311087, 5393.460653934614, 5421.457854214586, 5427.592930530206, 5409.972950135263, 5430.239766432723, 5450.700211488366, 5450.91823622646, 5451.754671039813, 5312.362516498007, 5330.546903513206, 5350.411454739976, 5382.6232163748555, 5412.83761487156, 5764.048931926269, 5811.157382179603, 5934.821955341346, 5982.551308651869, 6037.243447035884, 6260.123582698426, 6278.340774218733, 6284.654344011077, 6305.8108256752375, 6322.71547780351, 6357.459615932651, 6369.235691717024, 6381.93618063818, 6393.776217832354, 6399.104125422445, 5962.314333851619, 6038.9396106038375, 6114.205153330038, 6124.142620033199, 6127.18952814863, 6439.678016099177, 6468.482521398277, 6484.675766211671, 6491.51313651478, 6507.544471886922, 6278.591891527036, 6279.277883043417, 6289.622622642656, 6290.55966082379, 6292.339304372975, 6195.628262304186, 6258.874822503524, 6287.717052732638, 6353.5870168438905, 6360.109584658151, 6231.688415579203, 6250.562460627803, 6286.9056964145575, 6296.527760417987, 6302.991521356561, 6156.938430615726, 6239.0, 6290.968545157337, 6313.305536390993, 6346.873062538723, 5021.271915572222, 5044.621653375938, 5061.240813877874, 5069.467705890902, 5100.413452452941, 4006.098627808778, 4423.867283981422, 4499.55004499554, 4514.503404625489, 4550.271956486946, 5846.269216347957, 5956.6723830188885, 5972.790952316648, 5998.400159984054, 6059.7273122709585, 5361.571074314045, 6197.628142311476, 6286.654234017082, 6368.203974503187, 6394.424501794861, 4777.9044419112115, 4829.0, 4963.354763880672, 5243.318368612132, 6075.2405949256345, 5466.207399926989, 5547.528460080938, 5587.7485513152005, 5597.356304025089, 5627.32472103342, 5772.567057470623, 6433.1636887205295, 6545.836354091186, 6631.535792494479, 6665.833354166185, 6395.616263024047, 6395.712192951235, 6396.296407395276, 6397.904031439439, 6408.679566021773, 6357.0, 6558.475321974326, 6611.173603299588, 6637.269900311058, 6669.199696036507, 6286.622802631857, 6311.936880631319, 6313.431791138636, 6316.905246421215, 6337.04944258365, 6231.594946328474, 6370.93629063722, 6381.266154392304, 6392.264889537762, 6398.840028999126, 6133.693315334304, 6134.926457743815, 6192.907106393498, 6233.438990490878, 6271.843203919939, 6217.378262173927, 6245.094461303176, 6246.937530624818, 6249.999999999978, 6271.184745982993, 6040.063790112539, 6152.477039451696, 6198.349173336915, 6216.409441103088, 6221.657808820558, 6123.571350005457, 6147.600405973598, 6233.438990490878, 6260.092286618506, 6272.968635156795, 5516.172574113915, 6174.25908890936, 6188.938110619017, 6206.565540412128, 6244.46922011616], "unit": "score", "name": "dojo.html", "value": 5838.487422338162}, {"lowerIsBetter": false, "replicates": [486.51834683663185, 579.3511267380537, 590.5806877117246, 681.9931800681995, 689.2383915773071, 39928.00179995502, 40769.59230407695, 40842.591574084254, 41048.384274235876, 41063.58936410635, 42750.93122671927, 43302.56697433025, 43317.7834110829, 43337.56662433375, 43416.91457713559, 9513.5243237838, 9638.518074096268, 9678.709638710814, 9726.17327527161, 9771.364861283993, 9565.473898935556, 9835.0, 9835.606575736962, 9876.753081172992, 9917.603295868194, 9747.074027967366, 9809.0, 9847.16299114576, 9848.40909545426, 9881.604735810559, 9729.513524323791, 9800.460974646427, 9810.558524866326, 9834.754131146707, 9873.654422095227, 9708.330209192458, 9806.411615303105, 9821.165200957927, 9824.06671366219, 9842.65550705729, 35178.29643407118, 42066.789666051474, 42273.57726422727, 42285.78857105726, 42579.0, 3043.163130139207, 3124.937501249985, 3250.203700093469, 3311.8344082795766, 3359.2777552826014, 26084.73915260842, 28728.42543149146, 28823.991160309288, 29746.107616771533, 30200.697993020007, 2709.403931135147, 2775.8334499929865, 2818.055951256332, 2828.1374180874823, 2829.0381270367966, 289293.0, 308213.0, 342105.0, 343125.0, 343214.0, 50186.24720629193, 74827.0, 75209.62395188099, 75511.4897702043, 75740.24259757443, 44243.33634995478, 49452.752736236085, 49638.75180624074, 49740.75129624401, 49842.50157498451, 14064.507742229178, 16659.0, 16998.320067197175, 17008.234629441704, 17009.064501452292, 13414.262215578237, 13440.126391784503, 13524.391402386917, 13561.660958475919, 13566.389512471766, 13214.73570528584, 15636.905416620955, 15785.605359866095, 16044.919775401047, 16111.033337999757, 5715.371309155989, 6612.768553100618, 6724.394804467622, 6769.627670478072, 6792.660366981631, 946.654471118037, 955.1833182628891, 1020.9234307426973, 1064.6646306413609, 1085.148158695436, 30773.538396924065, 31525.369492610018, 31660.683393165775, 32144.874929377358, 32577.18557036093, 1087.4671411008549, 1108.1744100645071, 1122.2985633978763, 1170.800963836133, 1247.9376031198403, 15220.0, 16098.517044488684, 16187.0, 16234.83765162357, 16335.836641633432, 341775.0, 344933.0, 346151.0, 347921.0, 347928.0, 67680.32319676838, 68625.0, 68637.65681171563, 68923.65538172277, 69121.30878691148, 1521.5435369389138, 1534.6316883947995, 1549.5893588199135, 1558.462330495977, 1681.7141086015163, 28904.554772261723, 30603.846980764953, 30691.192090452023, 31275.84362078266, 31470.0, 57489.712551436976, 57515.71242143762, 58283.0, 58434.0, 58665.70667146637, 441968.0, 445569.0, 445793.0, 447697.0, 448294.0, 35512.64487355093, 35769.0, 36250.8187459061, 36420.635793641726, 36992.445113322785, 48229.794255143905, 49219.50780492293, 49919.75040124921, 50165.74917125391, 50239.0, 40058.59941400549, 40310.0, 40372.0, 40436.59563404328, 41384.58615413807, 39667.801660991514, 39928.40107398333, 40117.79941100276, 40176.565406075635, 40945.79527102345, 32618.673813261565, 40351.0, 41013.0, 42282.36576451417, 42587.57412425836, 33345.66654333426, 35570.93287201388, 35647.64352356443, 35688.286234275685, 35990.4601430984, 26697.33256668521, 26715.06497272586, 26787.866060670352, 26866.194014179608, 27250.454990899674], "unit": "score", "name": "ext.html", "value": 19796.986116675595}, {"lowerIsBetter": false, "replicates": [383.5857274143923, 385.0431677281954, 388.0, 392.7093950476648, 395.48784324300027, 17433.82566174338, 18477.53806154847, 19997.90001049993, 20922.476938076536, 20926.0, 20548.589028219427, 20764.0, 20826.687599685978, 20948.952552372397, 21133.894330528325, 5882.911756323659, 6149.661768602725, 6172.135900973867, 6176.84557886052, 6272.1219029335925, 5658.377578466366, 5871.911921321183, 5930.406959304079, 5936.5547583931375, 5953.642781433106, 5563.638363506359, 5803.941960580382, 5807.73865176066, 5814.273215848019, 5923.08192230205, 5682.602217844753, 5722.427757224286, 5746.48281654651, 5798.971005144989, 5812.912806307888, 5761.3950535194035, 5780.248567686216, 5784.07454807233, 5787.450192231773, 5812.796552120655, 13222.66943326415, 13573.321333933263, 13757.79363309551, 13793.655158621115, 13826.377812998438, 2045.263705066175, 2246.4496198431325, 2346.0263990443946, 2441.5727247731575, 2800.9719902800916, 9729.805403891953, 9906.752331191707, 9971.451570163637, 10016.899831001743, 10234.0, 1794.0850166415091, 1801.1534578747967, 1821.253286152671, 1858.479625704805, 1863.1802007116826, 99429.0, 107133.46433267862, 108152.4592377041, 110700.9999999992, 110963.0, 11758.529658813552, 11809.822852657217, 11870.28778273289, 11933.105017123751, 11933.403329833474, 14530.0, 14539.27303634814, 14541.709165816836, 14555.781663274847, 14586.562403127924, 9091.863622045674, 9117.179453849185, 9133.817323653622, 9154.725358239264, 9188.264938805014, 9223.44659320443, 9230.984591694909, 9275.0, 9306.696122876418, 9316.580753866092, 3689.594144644087, 4615.838445654386, 4668.649851261169, 4681.438227412732, 4721.1501929652995, 1947.7370554974898, 2007.9397618071482, 2046.3042565527494, 2056.1569756399804, 2056.8354531637433, 930.8696782450462, 999.3003904912631, 1052.7420781908481, 1116.5980247111033, 1116.8603924509437, 20736.792632073786, 21046.894765526074, 21085.68371474429, 21333.0, 21421.571568568543, 966.9854952175723, 1012.2256473797551, 1013.2400699475394, 1015.3349556040805, 1085.2891356161726, 10088.69733907984, 11346.943265283786, 11638.06895448362, 12095.879041209651, 12118.939405302917, 658375.0, 674843.0, 683724.58137709, 687971.0, 694103.0, 128978.0, 130326.0, 130477.0, 130681.0, 132378.33810831077, 1102.7132945434246, 1105.6738262212666, 1135.0975974100609, 1135.812590922505, 1138.1463902073444, 19244.326448574233, 19876.801231988073, 19966.90016549908, 20081.0, 20262.696059558824, 37052.073698157765, 48865.755671221414, 50791.730206745124, 52663.73668131788, 52730.73634631802, 62405.06392404027, 73875.26124738899, 76462.4707505858, 78645.60677196577, 79376.60311698404, 8080.393970452122, 8255.174482551936, 8333.167815361372, 8361.916380836357, 8368.790780230544, 9468.95265523668, 11266.436678166223, 13070.607881763563, 13961.0, 14251.857481425053, 11080.61217857371, 11569.305841649528, 11773.411329433666, 12144.33206173669, 12227.510899564273, 11674.76650466969, 11825.46785394642, 11915.106367022334, 11994.100442467025, 12065.81901271464, 6074.665893375906, 9608.75978100553, 9985.301028927905, 12117.69705757363, 12407.441665125272, 7357.484976051625, 8347.248747612744, 8394.0, 8510.74467765968, 8621.741347759576, 12098.879011210127, 12101.69745756328, 12138.75722485563, 12247.69380765488, 12271.570495032987], "unit": "score", "name": "jquery.html", "value": 9617.270096128159}, {"lowerIsBetter": false, "replicates": [1656.7929008873891, 1670.6909221793965, 2029.4317591074496, 2064.5561204341057, 2122.3420739570743, 8218.958905205465, 8918.866217006751, 9053.683121090764, 9243.029481904396, 9262.768430789234, 8960.82078358434, 8982.640694372218, 9036.77408064797, 9084.727458176265, 9091.681791137311, 2501.3746563359205, 2522.9495410091786, 2534.4550921551863, 2535.5055764126005, 2541.2376287113793, 2476.4675594747127, 2512.5979843224995, 2514.094925826701, 2535.3408113890337, 2535.9856057577026, 2503.9749602503925, 2512.108201588437, 2514.283429222672, 2543.173468622705, 2557.6419301297833, 2523.8485690858515, 2540.745925407454, 2547.8216524843083, 2549.847009179455, 2559.987200063997, 2514.962275565868, 2543.326018605071, 2544.9872750636314, 2552.680914885639, 2559.910403135881, 9099.590518426687, 9151.222146117589, 9153.08469153086, 9158.313126515539, 9196.402233854778, 5600.243967064475, 5719.25649665545, 5724.198612194296, 5731.197632331477, 5734.512566431858, 3658.067192865829, 3705.314516814398, 3761.567419746709, 3764.849406023756, 3767.698584113264, 1059.1632610237903, 1059.3061544688242, 1087.320424734541, 1095.1786160379716, 1098.6923661374828, 11009.99999999992, 11391.544338226544, 12424.937875310656, 12449.626511204679, 12453.439595218146, 4849.951500485021, 4881.877953051202, 4953.72754498506, 4964.776585053609, 4973.5523802857915, 10511.369317840801, 10512.684619461428, 10529.684109476728, 10545.578176872992, 10561.735956601147, 5578.665280083208, 5587.972060139673, 5603.719814009283, 5611.831645050655, 5626.85932851682, 4267.8079486423185, 4565.178267911741, 4958.256261560723, 5044.571211447068, 5090.796368145307, 3570.821458927095, 4034.0923292259595, 4094.1811637672586, 4140.834366625301, 4197.874063778091, 1232.2298563397876, 1332.1673953778889, 1369.137443410645, 1377.9931100344434, 1388.937497812601, 1114.654457118296, 1246.3581255653346, 1434.8091084400132, 1440.8343040550471, 1464.3337281536876, 7590.8861367079535, 8424.283935865398, 8479.491230526064, 8499.872501912476, 8514.361422893307, 1295.6372215779597, 1296.7795474769312, 1296.9546065887648, 1328.880400763936, 1334.2328161307287, 6454.19322584677, 6464.547481676332, 6486.318936511692, 6487.2215334159255, 6493.70778314977, 10420.895791042145, 10928.344299342065, 11028.44857757125, 11062.22564420499, 11108.777824443467, 10465.058144767008, 10862.185336099667, 10921.563137474572, 10945.45272736376, 10975.670729878117, 8420.663173472993, 8577.313814894793, 8587.184217499327, 8617.396782225183, 8650.480971141626, 8224.712135075244, 8251.504909705436, 8279.50322980623, 8286.295664868312, 8332.333413326798, 9614.663486777928, 9735.41587504752, 9831.262655301025, 9871.901280987098, 9905.504724763878, 9933.65232216869, 10119.696409107739, 10141.695749127539, 10296.124829389286, 10321.0, 6935.271796461299, 7605.809854753676, 7629.427792915442, 7731.652075656497, 7848.960755196187, 8415.53714545706, 8442.36682248846, 8444.986601607847, 8455.492670439793, 8468.407211495136, 7282.4902256841515, 7295.452841037049, 7480.625968701652, 7551.471397002157, 7568.5837278950185, 6167.753289868355, 6210.347913469202, 7274.599897005715, 7316.451266154953, 7386.556806591621, 7862.803429914298, 7914.287714105758, 7917.485363451357, 7942.880856787268, 7970.641321140679, 6973.58158510491, 6981.0924579804305, 6997.860042799217, 7030.543014704028, 7043.7182512700965, 5721.885562288814, 7103.289671032855, 7124.5725256484775, 7149.892751608626, 7165.749198778018], "unit": "score", "name": "mootools.html", "value": 5098.530067490929}, {"lowerIsBetter": false, "replicates": [342.63851636523464, 349.7604141163303, 351.9912002199943, 353.26520836659745, 361.983710733017, 6814.727410903558, 6824.4540436765, 6861.656917154147, 6864.588124712514, 6885.931140688592, 6030.185924900137, 6287.685615719207, 6732.057511948331, 6784.864302713942, 6829.590224586528, 4763.404574428197, 4885.340479035321, 4973.452920178777, 4998.10034193845, 5036.899262014757, 4937.111319962405, 4972.552470277675, 4976.954839483695, 4988.825391111312, 5003.649744517885, 4915.705057696549, 4922.8030878764985, 4949.381327334083, 4955.851324460272, 4971.651984361078, 4953.331300274453, 4953.455119936804, 4963.503649635026, 4965.155923493015, 4978.128827455199, 4301.40847886281, 4306.6124048835445, 4309.0, 4311.094670119279, 4314.633256173198, 6269.843253918643, 6572.244191917895, 6591.40677339037, 6608.702608382634, 6610.669466526702, 4830.396200474941, 5876.794312199096, 5923.614965027259, 5938.732757025944, 5960.4635582797755, 7387.374777548931, 7409.88885166723, 7418.221086785918, 7424.034875466209, 7429.665665045032, 4349.826006959718, 4737.620990320766, 4747.501512341189, 4756.381670382828, 4767.570918617341, 7294.96352518234, 7729.111152217454, 7784.961075194645, 7817.921820781833, 7829.256220659028, 4616.961183733701, 4712.5994290485005, 4790.688605240648, 4827.831025914076, 4828.37231159954, 4895.779689913962, 4903.8528844134735, 4905.362302900671, 4916.606671466274, 4924.212126059813, 5828.941710582924, 5925.170476133345, 5933.0, 5944.959632064371, 5947.821565353046, 6166.5991710539565, 7302.941073544306, 7413.851722965511, 7430.294122058396, 7436.0, 1657.767912492252, 1675.9162041897857, 1695.669344477815, 1699.8555122814453, 1701.8042925063528, 1059.5602824827765, 1083.6725011860408, 1085.0614218700766, 1088.7278180454887, 1091.2852081886376, 5912.438318359749, 6799.286074962157, 6801.863962720718, 6850.520463567602, 6855.07456493377, 7634.0, 7640.350570201484, 7669.3864490841715, 7683.9231607683205, 7715.4599178058115, 6339.936600634027, 6446.419822215929, 6818.28408017161, 6832.009358642969, 6835.384815366641, 3699.204670995695, 3902.375619900802, 3974.3839704845795, 4009.599040095967, 4040.959590404117, 6180.042093475518, 7011.544249623758, 7973.521588704696, 8061.153578874251, 8072.475289106189, 6427.357264273535, 6955.791326260221, 7351.595662238628, 7942.563159026194, 7975.361971042303, 6812.659367031629, 7988.281054705104, 8005.759827205193, 8013.679452821939, 8117.391195660349, 7376.852462950818, 7671.616419178907, 7698.2686644768655, 7710.807229819075, 7727.884081738666, 7511.549307041595, 7544.207858175032, 7548.358389536731, 7593.772186834404, 7596.354309883722, 7001.719931202695, 7626.970359001686, 8015.879761803461, 8038.236367545074, 8061.435699500978, 4823.408275269223, 4842.854714358575, 4853.95146048535, 4862.440819305683, 4864.051509955525, 5564.360098588714, 7319.963400182965, 7651.234876512304, 7678.846423071619, 7729.95645587861, 7362.558246505227, 7406.740764073232, 7425.14610819741, 7426.257374262531, 7455.627218639155, 7402.777916662509, 7408.888866666896, 7421.3691836192365, 7547.471676982558, 7601.695932162873, 7041.839566012085, 7114.466415018791, 7239.927600723925, 7258.600776957107, 7273.709051638087, 7317.0487836580905, 7382.151052629017, 7391.334779869838, 7398.852022959618, 7403.851922961403, 6022.494376405899, 7172.820679483055, 7194.172670143001, 7276.0, 7280.089988751406], "unit": "score", "name": "prototype.html", "value": 5316.929289570789}, {"lowerIsBetter": false, "replicates": [669.8928171492562, 693.9320823944645, 709.3225969199411, 745.414849343265, 757.1444267977187, 14984.925075374635, 15170.317335719892, 15392.0, 15433.768493472593, 15438.305276262581, 15177.54467365978, 15236.542903712878, 15262.0, 15271.770923436157, 15304.464343747972, 809.2837838512929, 1001.1940387987667, 1012.2812802909924, 1023.6007956896816, 1043.400044974143, 1032.2516175772541, 1036.2124785163292, 1037.3309215555962, 1042.2912419554739, 1044.3785947361307, 1086.8369744538304, 1087.5758454202828, 1089.9863127291615, 1090.5692251560629, 1090.5801266512372, 1046.1317106801337, 1068.1881769854888, 1073.002108039523, 1076.0745758647518, 1076.5370890517074, 800.1038836503154, 810.6878851642101, 813.6135335715547, 815.787895147266, 818.3984771193168, 15277.770833437507, 15481.458148964844, 15750.527484175493, 15845.60385990348, 15921.0, 13046.41291141901, 13060.673483162902, 13088.47646094155, 13124.212547247196, 13150.408231629599, 9993.150582200522, 10206.438645874474, 10222.591096356136, 10497.107745841613, 10658.253922225447, 3545.9290814183573, 3554.2891421715503, 3592.4970504129183, 3657.4330978698076, 3817.2556351511466, 88603.22793544093, 88974.5551272246, 89178.10821891762, 89478.0, 90544.54727726319, 9669.70990870275, 9708.320417570701, 9900.108990190918, 9974.40153590787, 10033.147182489423, 22894.31317060491, 22921.541569168523, 22963.311100667008, 22966.311010669706, 23056.308310750705, 6334.398232167937, 7351.264873512606, 7527.8118047048165, 7582.696692132253, 7649.57927313992, 3951.9802400988387, 3988.202359528106, 4043.454133691973, 4049.919001619951, 4058.8782336529953, 3573.910652233715, 3674.853005879735, 3690.1881586051027, 3710.9980305317413, 3718.981405092957, 669.9631520266335, 681.7954613615896, 687.0930371909033, 689.9689513971883, 692.6017539914468, 521.4498703867456, 568.5338022821247, 573.8049063318408, 575.5711994564076, 601.9097135429721, 7841.137474877759, 8152.959235203786, 8449.746507604781, 8684.261837743736, 8766.298696104297, 490.3380436410817, 515.5514702209102, 592.7154965616528, 652.26620052441, 656.8423578341163, 3816.1222918728295, 3880.5149356330458, 3920.353141731582, 3953.169834334822, 4084.71407001513, 90300.0, 92230.53884730533, 92751.53624231835, 93160.0, 93391.0660893396, 78109.60945195238, 79085.20914790893, 80021.19978800138, 80635.19364806394, 81104.18895810966, 69429.3057069433, 69710.0, 69976.0, 70373.64813175901, 71101.0, 7895.960520197592, 7897.091834438883, 7905.56519391439, 7915.762527124196, 7937.722179723682, 47976.7601161992, 48734.26898596453, 49124.0, 49232.01535969332, 49509.50490495049, 63881.680591598604, 66674.66662666849, 66838.0, 68995.0, 69610.0, 10243.897561024294, 10266.948665256627, 10352.948235258775, 10361.4301213434, 10386.792264154523, 11024.50389732448, 11027.724306892393, 12006.0, 12161.391930403268, 12427.75144497123, 11117.944410277896, 11119.332840029625, 11134.109271258118, 11251.156163287622, 11469.770604588028, 11044.282121662067, 11090.778184436105, 11272.985431311221, 11275.887241127812, 12005.63983080509, 9494.430334179971, 11181.552737890719, 11198.0, 11371.090312775124, 11754.882451175721, 9500.42997420157, 9556.42661440288, 9604.327697061139, 9697.709068727949, 9726.805463890541, 9465.905340946503, 9539.761505962406, 9574.138327550554, 9605.423674579548, 9857.0], "unit": "score", "name": "yui.html", "value": 7091.157334155957}], "name": "dromaeo_css", "value": 7732.1653797142535}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [130.0, 159.0, 158.0, 117.0, 116.0, 129.0, 114.0, 115.0, 114.0, 115.0], "unit": "score", "name": "ai-astar", "value": 126.7}, {"lowerIsBetter": true, "replicates": [147.0, 105.0, 158.0, 179.0, 146.0, 170.0, 156.0, 151.0, 146.0, 122.0], "unit": "score", "name": "audio-beat-detection", "value": 148.0}, {"lowerIsBetter": true, "replicates": [194.0, 199.0, 222.0, 261.0, 197.0, 203.0, 193.0, 194.0, 200.0, 200.0], "unit": "score", "name": "audio-dft", "value": 206.3}, {"lowerIsBetter": true, "replicates": [65.0, 77.0, 66.0, 110.0, 55.0, 54.0, 57.0, 60.0, 116.0, 55.0], "unit": "score", "name": "audio-fft", "value": 71.5}, {"lowerIsBetter": true, "replicates": [81.0, 84.0, 116.0, 80.0, 71.0, 100.0, 155.0, 121.0, 117.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 104.4}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 88.0, 85.0, 89.0, 86.0, 88.0, 108.0, 111.0, 132.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 96.0}, {"lowerIsBetter": true, "replicates": [150.0, 142.0, 94.0, 109.0, 152.0, 127.0, 94.0, 94.0, 134.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 118.9}, {"lowerIsBetter": true, "replicates": [91.0, 93.0, 91.0, 91.0, 92.0, 118.0, 144.0, 77.0, 151.0, 153.0], "unit": "score", "name": "imaging-desaturate", "value": 110.1}, {"lowerIsBetter": true, "replicates": [48.0, 55.0, 63.0, 59.0, 60.0, 60.0, 58.0, 65.0, 56.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 57.7}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 72.0, 86.0, 102.0, 61.0, 86.0, 96.0, 60.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 75.7}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 82.0, 85.0, 82.0, 96.0, 108.0, 115.0, 109.0, 98.0], "unit": "score", "name": "stanford-crypto-aes", "value": 93.9}, {"lowerIsBetter": true, "replicates": [144.0, 137.0, 126.0, 120.0, 122.0, 122.0, 121.0, 120.0, 132.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.5}, {"lowerIsBetter": true, "replicates": [135.0, 134.0, 135.0, 135.0, 133.0, 132.0, 139.0, 136.0, 134.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.8}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 69.0, 64.0, 67.0, 65.0, 64.0, 70.0, 64.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.8}], "name": "kraken", "value": 1537.3}]} 22:58:21 INFO - Return code: 0 22:58:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:58:21 ERROR - # TBPL SUCCESS # 22:58:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:58:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:58:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:58:21 INFO - 2016-04-20 22:58:21.966 screenresolution[1680:10992] starting screenresolution argv=screenresolution get 22:58:21 INFO - 2016-04-20 22:58:21.969 screenresolution[1680:10992] Display 0: 1600x1200x32@60 22:58:21 INFO - 2016-04-20 22:58:21.975 screenresolution[1681:10995] starting screenresolution argv=screenresolution list 22:58:21 INFO - Available Modes on Display 0 22:58:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:58:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:58:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:58:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:58:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:58:22 INFO - Intel Iris: 22:58:22 INFO - Chipset Model: Intel Iris 22:58:22 INFO - Type: GPU 22:58:22 INFO - Bus: Built-In 22:58:22 INFO - VRAM (Dynamic, Max): 1536 MB 22:58:22 INFO - Vendor: Intel (0x8086) 22:58:22 INFO - Device ID: 0x0a2e 22:58:22 INFO - Revision ID: 0x0009 22:58:22 INFO - Displays: 22:58:22 INFO - Display: 22:58:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:58:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:58:22 INFO - Display Serial Number: 1600x1200 60 22:58:22 INFO - Main Display: Yes 22:58:22 INFO - Mirror: Off 22:58:22 INFO - Online: Yes 22:58:22 INFO - Rotation: Supported 22:58:22 INFO - Return code: 0 22:58:22 INFO - Running post-action listener: _resource_record_post_action 22:58:22 INFO - Running post-run listener: _resource_record_post_run 22:58:23 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 31677952; Write bytes: 667289088; Read time: 326; Write time: 1932 22:58:23 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353546752; Write bytes: 354138112; Read time: 26986; Write time: 627 22:58:23 INFO - run-tests - Wall time: 1172s; CPU: 54.0%; Read bytes: 30752256; Write bytes: 302808576; Read time: 313; Write time: 1272 22:58:23 INFO - Running post-run listener: _upload_blobber_files 22:58:23 INFO - Blob upload gear active. 22:58:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:58:23 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 22:58:23 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'] 22:58:23 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 22:58:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:58:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:58:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:58:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:58:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:58:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:58:25 INFO - (blobuploader) - INFO - Done attempting. 22:58:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:58:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:58:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:58:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:58:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:58:31 INFO - (blobuploader) - INFO - Done attempting. 22:58:31 INFO - (blobuploader) - INFO - Iteration through files over. 22:58:31 INFO - Return code: 0 22:58:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:58:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:58:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f446d689394219e87987cf71d8519d99859320125c7d3ed652d0979735a8159f3607c30fc09b77a91a7b32524befcc273b8d4ee667ef262f7e19fac789cf08dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa5632eb60dcc2a461fe63720cd1c0591b6bc64e796bace7056169b69632b86dfafe0d8b57d5a41f7c9e1cde5e6f49fe9bd18543024142b7d5cdba8b2dcb5c33"} 22:58:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:58:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:58:31 INFO - Contents: 22:58:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f446d689394219e87987cf71d8519d99859320125c7d3ed652d0979735a8159f3607c30fc09b77a91a7b32524befcc273b8d4ee667ef262f7e19fac789cf08dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa5632eb60dcc2a461fe63720cd1c0591b6bc64e796bace7056169b69632b86dfafe0d8b57d5a41f7c9e1cde5e6f49fe9bd18543024142b7d5cdba8b2dcb5c33"} 22:58:31 INFO - Running post-run listener: copy_logs_to_upload_dir 22:58:31 INFO - Copying logs to upload dir... 22:58:31 INFO - mkdir: /builds/slave/test/build/upload/logs 22:58:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.884479 ========= master_lag: 15.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-04-20 22:58:46.119027) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-20 22:58:46.127950) ========= 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.bHjSg6W1pj/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.MxP46X7dys/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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/f446d689394219e87987cf71d8519d99859320125c7d3ed652d0979735a8159f3607c30fc09b77a91a7b32524befcc273b8d4ee667ef262f7e19fac789cf08dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa5632eb60dcc2a461fe63720cd1c0591b6bc64e796bace7056169b69632b86dfafe0d8b57d5a41f7c9e1cde5e6f49fe9bd18543024142b7d5cdba8b2dcb5c33"} build_url:https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012160 build_url: 'https://queue.taskcluster.net/v1/task/WBzFk0n-QXGs3pgm4RwGMA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f446d689394219e87987cf71d8519d99859320125c7d3ed652d0979735a8159f3607c30fc09b77a91a7b32524befcc273b8d4ee667ef262f7e19fac789cf08dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa5632eb60dcc2a461fe63720cd1c0591b6bc64e796bace7056169b69632b86dfafe0d8b57d5a41f7c9e1cde5e6f49fe9bd18543024142b7d5cdba8b2dcb5c33"}' ========= master_lag: 7.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-20 22:58:53.148022) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-04-20 22:58:53.148337) ========= 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.bHjSg6W1pj/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.MxP46X7dys/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.005301 ========= master_lag: 9.42 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-04-20 22:59:02.575762) ========= ========= Started reboot skipped (results: 3, elapsed: 7 secs) (at 2016-04-20 22:59:02.576085) ========= ========= Finished reboot skipped (results: 3, elapsed: 7 secs) (at 2016-04-20 22:59:10.260114) ========= ========= Total master_lag: 40.71 =========