builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0326 starttime: 1464779803.87 results: success (0) buildid: 20160601021650 builduid: 1548571e00b64466a2baddee8cc67cdc revision: b73cfa00dab309c8b999550363df0c0456128b90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:43.868203) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:43.868695) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:43.868969) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:43.899937) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:43.900314) ========= 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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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-06-01 04:16:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.5M=0s 2016-06-01 04:16:44 (39.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120857 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:44.041385) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:44.041705) ========= 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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.025799 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:44.090423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 04:16:44.090780) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b73cfa00dab309c8b999550363df0c0456128b90 --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 b73cfa00dab309c8b999550363df0c0456128b90 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tj5o8GBjrb/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.IRcPGhnmdP/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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-06-01 04:16:44,171 truncating revision to first 12 chars 2016-06-01 04:16:44,171 Setting DEBUG logging. 2016-06-01 04:16:44,171 attempt 1/10 2016-06-01 04:16:44,171 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b73cfa00dab3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 04:16:45,273 unpacking tar archive at: mozilla-aurora-b73cfa00dab3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370990 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 04:16:45.481562) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:45.481881) ========= script_repo_revision: b73cfa00dab309c8b999550363df0c0456128b90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:45.482255) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:45.482530) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 04:16:45.499419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-01 04:16:45.499777) ========= /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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:16:45 INFO - MultiFileLogger online at 20160601 04:16:45 in /builds/slave/test 04:16:45 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 04:16:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:16:45 INFO - {'append_to_log': False, 04:16:45 INFO - 'base_work_dir': '/builds/slave/test', 04:16:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:16:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:16:45 INFO - 'branch': 'Mozilla-Aurora', 04:16:45 INFO - 'buildbot_json_path': 'buildprops.json', 04:16:45 INFO - 'config_files': ('talos/mac_config.py',), 04:16:45 INFO - 'default_actions': ('clobber', 04:16:45 INFO - 'read-buildbot-config', 04:16:45 INFO - 'download-and-extract', 04:16:45 INFO - 'populate-webroot', 04:16:45 INFO - 'create-virtualenv', 04:16:45 INFO - 'install', 04:16:45 INFO - 'run-tests'), 04:16:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:16:45 INFO - 'download_minidump_stackwalk': True, 04:16:45 INFO - 'download_symbols': 'ondemand', 04:16:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:16:45 INFO - 'tooltool.py': '/tools/tooltool.py', 04:16:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:16:45 INFO - '/tools/misc-python/virtualenv.py')}, 04:16:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:16:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:16:45 INFO - 'installer_path': 'installer.exe', 04:16:45 INFO - 'log_level': 'info', 04:16:45 INFO - 'log_name': 'talos', 04:16:45 INFO - 'log_to_console': True, 04:16:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:16:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:16:45 INFO - 'opt_config_files': (), 04:16:45 INFO - 'pip_index': False, 04:16:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:16:45 INFO - 'cmd': ('bash', 04:16:45 INFO - '-c', 04:16:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:16:45 INFO - 'enabled': True, 04:16:45 INFO - 'halt_on_failure': False, 04:16:45 INFO - 'name': 'check_screen_resolution'},), 04:16:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:16:45 INFO - 'cmd': ('bash', 04:16:45 INFO - '-c', 04:16:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:16:45 INFO - 'enabled': True, 04:16:45 INFO - 'halt_on_failure': False, 04:16:45 INFO - 'name': 'check_screen_resolution'},), 04:16:45 INFO - 'run_cmd_checks_enabled': True, 04:16:45 INFO - 'sps_profile': False, 04:16:45 INFO - 'sps_profile_interval': 0, 04:16:45 INFO - 'suite': 'dromaeojs-e10s', 04:16:45 INFO - 'system_bits': '32', 04:16:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:16:45 INFO - 'title': 't-yosemite-r7-0326', 04:16:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:16:45 INFO - 'use_talos_json': True, 04:16:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:16:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:16:45 INFO - 'work_dir': 'build'} 04:16:45 INFO - ##### 04:16:45 INFO - ##### Running clobber step. 04:16:45 INFO - ##### 04:16:45 INFO - Running pre-action listener: _resource_record_pre_action 04:16:45 INFO - Running main action method: clobber 04:16:45 INFO - rmtree: /builds/slave/test/build 04:16:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:16:48 INFO - Running post-action listener: _resource_record_post_action 04:16:48 INFO - ##### 04:16:48 INFO - ##### Running read-buildbot-config step. 04:16:48 INFO - ##### 04:16:48 INFO - Running pre-action listener: _resource_record_pre_action 04:16:48 INFO - Running main action method: read_buildbot_config 04:16:48 INFO - Using buildbot properties: 04:16:48 INFO - { 04:16:48 INFO - "project": "", 04:16:48 INFO - "product": "firefox", 04:16:48 INFO - "script_repo_revision": "production", 04:16:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 04:16:48 INFO - "repository": "", 04:16:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 04:16:48 INFO - "buildid": "20160601021650", 04:16:48 INFO - "pgo_build": "False", 04:16:48 INFO - "basedir": "/builds/slave/test", 04:16:48 INFO - "buildnumber": 17, 04:16:48 INFO - "slavename": "t-yosemite-r7-0326", 04:16:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:16:48 INFO - "platform": "yosemite_r7", 04:16:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:16:48 INFO - "branch": "mozilla-aurora", 04:16:48 INFO - "repo_path": "releases/mozilla-aurora", 04:16:48 INFO - "revision": "b73cfa00dab309c8b999550363df0c0456128b90", 04:16:48 INFO - "stage_platform": "macosx64", 04:16:48 INFO - "builduid": "1548571e00b64466a2baddee8cc67cdc", 04:16:48 INFO - "slavebuilddir": "test" 04:16:48 INFO - } 04:16:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 04:16:48 INFO - Running post-action listener: _resource_record_post_action 04:16:48 INFO - ##### 04:16:48 INFO - ##### Running download-and-extract step. 04:16:48 INFO - ##### 04:16:48 INFO - Running pre-action listener: _resource_record_pre_action 04:16:48 INFO - Running main action method: download_and_extract 04:16:48 INFO - mkdir: /builds/slave/test/build/tests 04:16: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')]} 04:16:48 INFO - https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:16:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:16:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:16:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:16:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 04:16:49 INFO - Downloaded 1199 bytes. 04:16:49 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:16:49 INFO - Using the following test package requirements: 04:16:49 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:16:49 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 04:16:49 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 04:16:49 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'jsshell-mac.zip'], 04:16:49 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 04:16:49 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:16:49 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 04:16:49 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 04:16:49 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 04:16:49 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:16:49 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 04:16:49 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:49 INFO - https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:16:50 INFO - Downloaded 18463893 bytes. 04:16:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:16:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:16:53 INFO - Return code: 0 04:16:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:16: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')]} 04:16:53 INFO - https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:16:54 INFO - Downloaded 18463893 bytes. 04:16:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:16:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:16:58 INFO - Return code: 0 04:16:58 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')]} 04:16:58 INFO - https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:16:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:16:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:16:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:16:59 INFO - Downloaded 10840145 bytes. 04:16:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:16:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:16:59 INFO - Return code: 0 04:16:59 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')]} 04:16:59 INFO - https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 04:16:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:16:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:16:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 04:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 04:17:03 INFO - Downloaded 114192982 bytes. 04:17:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:17:03 INFO - mkdir: /builds/slave/test/properties 04:17:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:17:03 INFO - Writing to file /builds/slave/test/properties/build_url 04:17:03 INFO - Contents: 04:17:03 INFO - build_url:https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:17:04 INFO - Running post-action listener: _resource_record_post_action 04:17:04 INFO - Running post-action listener: set_extra_try_arguments 04:17:04 INFO - ##### 04:17:04 INFO - ##### Running populate-webroot step. 04:17:04 INFO - ##### 04:17:04 INFO - Running pre-action listener: _resource_record_pre_action 04:17:04 INFO - Running main action method: populate_webroot 04:17:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:17:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:17:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:17:04 INFO - '--tppagecycles', 04:17:04 INFO - '7'], 04:17:04 INFO - 'tests': ['tsvgm']}}, 04:17:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:17:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:17:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:17:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:17:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['damp', 'tps']}, 04:17:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'tests': ['damp', 'tps']}, 04:17:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['dromaeo_dom']}, 04:17:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:17:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['a11yr', 04:17:04 INFO - 'ts_paint', 04:17:04 INFO - 'tpaint', 04:17:04 INFO - 'sessionrestore', 04:17:04 INFO - 'sessionrestore_no_auto_restore', 04:17:04 INFO - 'tabpaint']}, 04:17:04 INFO - 'other-e10s': {'tests': ['a11yr', 04:17:04 INFO - 'ts_paint', 04:17:04 INFO - 'tpaint', 04:17:04 INFO - 'sessionrestore', 04:17:04 INFO - 'sessionrestore_no_auto_restore', 04:17:04 INFO - 'tabpaint']}, 04:17:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:17:04 INFO - 'ts_paint', 04:17:04 INFO - 'tpaint', 04:17:04 INFO - 'sessionrestore', 04:17:04 INFO - 'sessionrestore_no_auto_restore', 04:17:04 INFO - 'tabpaint']}, 04:17:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:17:04 INFO - 'ts_paint', 04:17:04 INFO - 'tpaint', 04:17:04 INFO - 'sessionrestore', 04:17:04 INFO - 'sessionrestore_no_auto_restore', 04:17:04 INFO - 'tabpaint']}, 04:17:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['a11yr', 04:17:04 INFO - 'ts_paint', 04:17:04 INFO - 'tpaint', 04:17:04 INFO - 'sessionrestore', 04:17:04 INFO - 'sessionrestore_no_auto_restore', 04:17:04 INFO - 'tabpaint']}, 04:17:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['a11yr', 04:17:04 INFO - 'ts_paint', 04:17:04 INFO - 'tpaint', 04:17:04 INFO - 'sessionrestore', 04:17:04 INFO - 'sessionrestore_no_auto_restore', 04:17:04 INFO - 'tabpaint']}, 04:17:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['tsvgx', 04:17:04 INFO - 'tsvgr_opacity', 04:17:04 INFO - 'tart', 04:17:04 INFO - 'tscrollx', 04:17:04 INFO - 'cart']}, 04:17:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:17:04 INFO - 'tsvgr_opacity', 04:17:04 INFO - 'tart', 04:17:04 INFO - 'tscrollx', 04:17:04 INFO - 'cart']}, 04:17:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'talos_options': ['--disable-e10s'], 04:17:04 INFO - 'tests': ['tp5o']}, 04:17:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'tests': ['tp5o']}, 04:17:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'talos_options': ['--disable-e10s', 04:17:04 INFO - '--xperf_path', 04:17:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:04 INFO - 'tests': ['tp5n']}, 04:17:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:04 INFO - 'talos_options': ['--xperf_path', 04:17:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:04 INFO - 'tests': ['tp5n']}}, 04:17:04 INFO - 'talos.zip': {'path': '', 04:17:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:17:04 INFO - Running post-action listener: _resource_record_post_action 04:17:04 INFO - ##### 04:17:04 INFO - ##### Running create-virtualenv step. 04:17:04 INFO - ##### 04:17:04 INFO - Running pre-action listener: _resource_record_pre_action 04:17:04 INFO - Running main action method: create_virtualenv 04:17:04 INFO - Creating virtualenv /builds/slave/test/build/venv 04:17:04 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 04:17:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:04 INFO - Using real prefix '/tools/python27' 04:17:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:05 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:07 INFO - Installing pip.................done. 04:17:07 INFO - Return code: 0 04:17:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:07 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:07 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 04:17:07 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 04:17:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:07 INFO - 'HOME': '/Users/cltbld', 04:17:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:07 INFO - 'LOGNAME': 'cltbld', 04:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:07 INFO - 'MOZ_NO_REMOTE': '1', 04:17:07 INFO - 'NO_EM_RESTART': '1', 04:17:07 INFO - 'PAGER': '/bin/cat', 04:17:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:07 INFO - 'PWD': '/builds/slave/test', 04:17:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:07 INFO - 'SHELL': '/bin/bash', 04:17:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:07 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:07 INFO - 'USER': 'cltbld', 04:17:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:07 INFO - 'XPC_FLAGS': '0x0', 04:17:07 INFO - 'XPC_SERVICE_NAME': '0', 04:17:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:08 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 04:17:08 INFO - Cleaning up... 04:17:08 INFO - Return code: 0 04:17:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:08 INFO - 'HOME': '/Users/cltbld', 04:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:08 INFO - 'LOGNAME': 'cltbld', 04:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:08 INFO - 'MOZ_NO_REMOTE': '1', 04:17:08 INFO - 'NO_EM_RESTART': '1', 04:17:08 INFO - 'PAGER': '/bin/cat', 04:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:08 INFO - 'PWD': '/builds/slave/test', 04:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:08 INFO - 'SHELL': '/bin/bash', 04:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:08 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:08 INFO - 'USER': 'cltbld', 04:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:08 INFO - 'XPC_FLAGS': '0x0', 04:17:08 INFO - 'XPC_SERVICE_NAME': '0', 04:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:08 INFO - Downloading/unpacking psutil>=0.7.1 04:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:11 INFO - Installing collected packages: psutil 04:17:11 INFO - Running setup.py install for psutil 04:17:11 INFO - building 'psutil._psutil_osx' extension 04:17:11 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 04:17:12 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 04:17:12 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 04:17:12 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 04:17:12 INFO - building 'psutil._psutil_posix' extension 04:17:12 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 04:17:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:17:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:17:12 INFO - ^ 04:17:12 INFO - 1 warning generated. 04:17:12 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 04:17:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:12 INFO - Successfully installed psutil 04:17:12 INFO - Cleaning up... 04:17:12 INFO - Return code: 0 04:17:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:12 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:12 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 04:17:12 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 04:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:12 INFO - 'HOME': '/Users/cltbld', 04:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:12 INFO - 'LOGNAME': 'cltbld', 04:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:12 INFO - 'MOZ_NO_REMOTE': '1', 04:17:12 INFO - 'NO_EM_RESTART': '1', 04:17:12 INFO - 'PAGER': '/bin/cat', 04:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:12 INFO - 'PWD': '/builds/slave/test', 04:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:12 INFO - 'SHELL': '/bin/bash', 04:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:12 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:12 INFO - 'USER': 'cltbld', 04:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:12 INFO - 'XPC_FLAGS': '0x0', 04:17:12 INFO - 'XPC_SERVICE_NAME': '0', 04:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:17:12 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 04:17:12 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 04:17:12 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 04:17:12 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 04:17:12 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 04:17:12 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 04:17:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:17:15 INFO - Installing collected packages: mozsystemmonitor 04:17:15 INFO - Running setup.py install for mozsystemmonitor 04:17:15 INFO - Successfully installed mozsystemmonitor 04:17:15 INFO - Cleaning up... 04:17:15 INFO - Return code: 0 04:17:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:15 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:15 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 04:17:15 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 04:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:15 INFO - 'HOME': '/Users/cltbld', 04:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:15 INFO - 'LOGNAME': 'cltbld', 04:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:15 INFO - 'MOZ_NO_REMOTE': '1', 04:17:15 INFO - 'NO_EM_RESTART': '1', 04:17:15 INFO - 'PAGER': '/bin/cat', 04:17:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:15 INFO - 'PWD': '/builds/slave/test', 04:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:15 INFO - 'SHELL': '/bin/bash', 04:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:15 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:15 INFO - 'USER': 'cltbld', 04:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:15 INFO - 'XPC_FLAGS': '0x0', 04:17:15 INFO - 'XPC_SERVICE_NAME': '0', 04:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:15 INFO - Downloading/unpacking blobuploader==1.2.4 04:17:15 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 04:17:15 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 04:17:15 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 04:17:15 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 04:17:15 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 04:17:15 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 04:17:18 INFO - Downloading blobuploader-1.2.4.tar.gz 04:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:17:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:17:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 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 04:17:18 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 04:17:19 INFO - Downloading docopt-0.6.1.tar.gz 04:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:17:19 INFO - Installing collected packages: blobuploader, requests, docopt 04:17:19 INFO - Running setup.py install for blobuploader 04:17:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:17:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:17:19 INFO - Running setup.py install for requests 04:17:20 INFO - Running setup.py install for docopt 04:17:20 INFO - Successfully installed blobuploader requests docopt 04:17:20 INFO - Cleaning up... 04:17:20 INFO - Return code: 0 04:17:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:20 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:20 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 04:17:20 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 04:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:20 INFO - 'HOME': '/Users/cltbld', 04:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:20 INFO - 'LOGNAME': 'cltbld', 04:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:20 INFO - 'MOZ_NO_REMOTE': '1', 04:17:20 INFO - 'NO_EM_RESTART': '1', 04:17:20 INFO - 'PAGER': '/bin/cat', 04:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:20 INFO - 'PWD': '/builds/slave/test', 04:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:20 INFO - 'SHELL': '/bin/bash', 04:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:20 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:20 INFO - 'USER': 'cltbld', 04:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:20 INFO - 'XPC_FLAGS': '0x0', 04:17:20 INFO - 'XPC_SERVICE_NAME': '0', 04:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-PFgbWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-LP5Rng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-FQQCGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-mIaN_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-IV500v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-1dGVC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-nQvVwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-6uUBPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-BsOiWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Y6WHT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-nyC5DC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ZWB1rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-izXwNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-KV7W82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:22 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-nnVyCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:22 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-B7b_ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:22 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-VFeIBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:17:22 INFO - Running setup.py install for manifestparser 04:17:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:17:22 INFO - Running setup.py install for mozcrash 04:17:22 INFO - Running setup.py install for mozdebug 04:17:22 INFO - Running setup.py install for mozdevice 04:17:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Running setup.py install for mozfile 04:17:23 INFO - Running setup.py install for mozhttpd 04:17:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Running setup.py install for mozinfo 04:17:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Running setup.py install for mozInstall 04:17:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Running setup.py install for mozleak 04:17:23 INFO - Running setup.py install for mozlog 04:17:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:17:23 INFO - Running setup.py install for moznetwork 04:17:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:17:24 INFO - Running setup.py install for mozprocess 04:17:24 INFO - Running setup.py install for mozprofile 04:17:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:17:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:17:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:17:24 INFO - Running setup.py install for mozrunner 04:17:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:17:24 INFO - Running setup.py install for mozscreenshot 04:17:24 INFO - Running setup.py install for moztest 04:17:24 INFO - Running setup.py install for mozversion 04:17:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:17:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:17:24 INFO - Cleaning up... 04:17:25 INFO - Return code: 0 04:17:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:25 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:25 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 04:17:25 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 04:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:25 INFO - 'HOME': '/Users/cltbld', 04:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:25 INFO - 'LOGNAME': 'cltbld', 04:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:25 INFO - 'MOZ_NO_REMOTE': '1', 04:17:25 INFO - 'NO_EM_RESTART': '1', 04:17:25 INFO - 'PAGER': '/bin/cat', 04:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:25 INFO - 'PWD': '/builds/slave/test', 04:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:25 INFO - 'SHELL': '/bin/bash', 04:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:25 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:25 INFO - 'USER': 'cltbld', 04:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:25 INFO - 'XPC_FLAGS': '0x0', 04:17:25 INFO - 'XPC_SERVICE_NAME': '0', 04:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-4QrDKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:25 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)) 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-1E9Y5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:25 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)) 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-itGpyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:25 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)) 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-apz7Cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:25 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)) 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-eeiqK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:25 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)) 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ZJIIBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:25 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)) 04:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-fe6c12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Y77BbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-B730BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-A7KqVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-zk3B96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-XNZU0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Ui9G2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-CbIN8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-dKT1CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-KR8u1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:26 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)) 04:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-YKQRGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:27 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)) 04:17:27 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)) 04:17:27 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)) 04:17:27 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)) 04:17:27 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)) 04:17:27 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)) 04:17:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17:27 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 04:17:27 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 04:17:27 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 04:17:27 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 04:17:27 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 04:17:27 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 04:17:29 INFO - Downloading blessings-1.6.tar.gz 04:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:17:29 INFO - Installing collected packages: blessings 04:17:29 INFO - Running setup.py install for blessings 04:17:29 INFO - Successfully installed blessings 04:17:29 INFO - Cleaning up... 04:17:29 INFO - Return code: 0 04:17:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:17:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:30 INFO - Reading from file tmpfile_stdout 04:17:30 INFO - Current package versions: 04:17:30 INFO - blessings == 1.6 04:17:30 INFO - blobuploader == 1.2.4 04:17:30 INFO - docopt == 0.6.1 04:17:30 INFO - manifestparser == 1.1 04:17:30 INFO - mozInstall == 1.12 04:17:30 INFO - mozcrash == 0.17 04:17:30 INFO - mozdebug == 0.1 04:17:30 INFO - mozdevice == 0.48 04:17:30 INFO - mozfile == 1.2 04:17:30 INFO - mozhttpd == 0.7 04:17:30 INFO - mozinfo == 0.9 04:17:30 INFO - mozleak == 0.1 04:17:30 INFO - mozlog == 3.1 04:17:30 INFO - moznetwork == 0.27 04:17:30 INFO - mozprocess == 0.22 04:17:30 INFO - mozprofile == 0.28 04:17:30 INFO - mozrunner == 6.11 04:17:30 INFO - mozscreenshot == 0.1 04:17:30 INFO - mozsystemmonitor == 0.0 04:17:30 INFO - moztest == 0.7 04:17:30 INFO - mozversion == 1.4 04:17:30 INFO - psutil == 3.1.1 04:17:30 INFO - requests == 1.2.3 04:17:30 INFO - wsgiref == 0.1.2 04:17:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:30 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:30 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 04:17:30 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 04:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:30 INFO - 'HOME': '/Users/cltbld', 04:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:30 INFO - 'LOGNAME': 'cltbld', 04:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:30 INFO - 'MOZ_NO_REMOTE': '1', 04:17:30 INFO - 'NO_EM_RESTART': '1', 04:17:30 INFO - 'PAGER': '/bin/cat', 04:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:30 INFO - 'PWD': '/builds/slave/test', 04:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:30 INFO - 'SHELL': '/bin/bash', 04:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:30 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:30 INFO - 'USER': 'cltbld', 04:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:30 INFO - 'XPC_FLAGS': '0x0', 04:17:30 INFO - 'XPC_SERVICE_NAME': '0', 04:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 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)) 04:17:30 INFO - Cleaning up... 04:17:30 INFO - Return code: 0 04:17:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:30 INFO - 'HOME': '/Users/cltbld', 04:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:30 INFO - 'LOGNAME': 'cltbld', 04:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:30 INFO - 'MOZ_NO_REMOTE': '1', 04:17:30 INFO - 'NO_EM_RESTART': '1', 04:17:30 INFO - 'PAGER': '/bin/cat', 04:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:30 INFO - 'PWD': '/builds/slave/test', 04:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:30 INFO - 'SHELL': '/bin/bash', 04:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:30 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:30 INFO - 'USER': 'cltbld', 04:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:30 INFO - 'XPC_FLAGS': '0x0', 04:17:30 INFO - 'XPC_SERVICE_NAME': '0', 04:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:30 INFO - Downloading/unpacking jsonschema 04:17:30 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 04:17:30 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 04:17:30 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 04:17:30 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 04:17:30 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 04:17:30 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 04:17:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:17:33 INFO - Downloading/unpacking functools32 (from jsonschema) 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:17:33 INFO - warning: no files found matching '*.txt' 04:17:33 INFO - no previously-included directories found matching 'build' 04:17:33 INFO - no previously-included directories found matching 'dist' 04:17:33 INFO - no previously-included directories found matching '.git*' 04:17:33 INFO - Installing collected packages: jsonschema, functools32 04:17:33 INFO - Running setup.py install for functools32 04:17:33 INFO - warning: no files found matching '*.txt' 04:17:33 INFO - no previously-included directories found matching 'build' 04:17:33 INFO - no previously-included directories found matching 'dist' 04:17:33 INFO - no previously-included directories found matching '.git*' 04:17:33 INFO - Successfully installed jsonschema functools32 04:17:33 INFO - Cleaning up... 04:17:33 INFO - Return code: 0 04:17:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:33 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 0x106fcea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fde420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7cbca0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, '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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 04:17:33 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 04:17:33 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 04:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:33 INFO - 'HOME': '/Users/cltbld', 04:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:33 INFO - 'LOGNAME': 'cltbld', 04:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:33 INFO - 'MOZ_NO_REMOTE': '1', 04:17:33 INFO - 'NO_EM_RESTART': '1', 04:17:33 INFO - 'PAGER': '/bin/cat', 04:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:33 INFO - 'PWD': '/builds/slave/test', 04:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:33 INFO - 'SHELL': '/bin/bash', 04:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:17:33 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:33 INFO - 'USER': 'cltbld', 04:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:33 INFO - 'XPC_FLAGS': '0x0', 04:17:33 INFO - 'XPC_SERVICE_NAME': '0', 04:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:34 INFO - Downloading/unpacking flake8 04:17:34 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 04:17:34 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 04:17:34 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 04:17:34 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 04:17:34 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 04:17:34 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 04:17:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:17:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:17:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:17:37 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 04:17:37 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 04:17:37 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 04:17:37 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 04:17:37 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 04:17:37 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 04:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:17:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:17:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:17:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:17:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:17:37 INFO - no previously-included directories found matching 'docs/_build' 04:17:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:17:37 INFO - Running setup.py install for pep8 04:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:17:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:17:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:17:38 INFO - no previously-included directories found matching 'docs/_build' 04:17:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:17:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:17:38 INFO - Cleaning up... 04:17:38 INFO - Return code: 0 04:17:38 INFO - Running post-action listener: _resource_record_post_action 04:17:38 INFO - Running post-action listener: _start_resource_monitoring 04:17:38 INFO - Starting resource monitoring. 04:17:38 INFO - ##### 04:17:38 INFO - ##### Running install step. 04:17:38 INFO - ##### 04:17:38 INFO - Running pre-action listener: _resource_record_pre_action 04:17:38 INFO - Running main action method: install 04:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:38 INFO - Reading from file tmpfile_stdout 04:17:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:17:38 INFO - Reading from file tmpfile_stdout 04:17:38 INFO - Output received: 04:17:38 INFO - Usage: mozinstall [options] installer 04:17:38 INFO - Options: 04:17:38 INFO - -h, --help show this help message and exit 04:17:38 INFO - -d DEST, --destination=DEST 04:17:38 INFO - Directory to install application into. [default: 04:17:38 INFO - "/builds/slave/test"] 04:17:38 INFO - --app=APP Application being installed. [default: firefox] 04:17:38 INFO - mkdir: /builds/slave/test/build/application 04:17:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:18:10 INFO - Reading from file tmpfile_stdout 04:18:10 INFO - Output received: 04:18:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:18:10 INFO - Running post-action listener: _resource_record_post_action 04:18:10 INFO - ##### 04:18:10 INFO - ##### Running run-tests step. 04:18:10 INFO - ##### 04:18:10 INFO - Running pre-action listener: _resource_record_pre_action 04:18:10 INFO - Running main action method: run_tests 04:18:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:10 INFO - 2016-06-01 04:18:10.210 screenresolution[1642:7686] starting screenresolution argv=screenresolution get 04:18:10 INFO - 2016-06-01 04:18:10.213 screenresolution[1642:7686] Display 0: 1600x1200x32@60 04:18:10 INFO - 2016-06-01 04:18:10.218 screenresolution[1643:7688] starting screenresolution argv=screenresolution list 04:18:10 INFO - Available Modes on Display 0 04:18:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:10 INFO - Intel Iris: 04:18:10 INFO - Chipset Model: Intel Iris 04:18:10 INFO - Type: GPU 04:18:10 INFO - Bus: Built-In 04:18:10 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:10 INFO - Vendor: Intel (0x8086) 04:18:10 INFO - Device ID: 0x0a2e 04:18:10 INFO - Revision ID: 0x0009 04:18:10 INFO - Displays: 04:18:10 INFO - Display: 04:18:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:10 INFO - Display Serial Number: 1600x1200 60 04:18:10 INFO - Main Display: Yes 04:18:10 INFO - Mirror: Off 04:18:10 INFO - Online: Yes 04:18:10 INFO - Rotation: Supported 04:18:10 INFO - Return code: 0 04:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:10 INFO - Python 2.7.3 04:18:10 INFO - Return code: 0 04:18:10 INFO - grabbing minidump binary from tooltool 04:18: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')]} 04:18:10 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 0x106e6de00>, '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 0x106cd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e68b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:10 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 04:18:10 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 04:18:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:10 INFO - Return code: 0 04:18:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:18:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:18:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:18:11 INFO - Return code: 0 04:18:11 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/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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 04:18:11 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/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0326 --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 04:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tj5o8GBjrb/Render', 04:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:11 INFO - 'HOME': '/Users/cltbld', 04:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:11 INFO - 'LOGNAME': 'cltbld', 04:18:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:11 INFO - 'MOZ_NO_REMOTE': '1', 04:18:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:11 INFO - 'NO_EM_RESTART': '1', 04:18:11 INFO - 'PAGER': '/bin/cat', 04:18:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:11 INFO - 'PWD': '/builds/slave/test', 04:18:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:11 INFO - 'SHELL': '/bin/bash', 04:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRcPGhnmdP/Listeners', 04:18:11 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 04:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:11 INFO - 'USER': 'cltbld', 04:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:11 INFO - 'XPC_FLAGS': '0x0', 04:18:11 INFO - 'XPC_SERVICE_NAME': '0', 04:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:11 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/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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 04:18:11 INFO - mozversion application_buildid: 20160601021650 04:18:11 INFO - mozversion application_changeset: b73cfa00dab309c8b999550363df0c0456128b90 04:18:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:18:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:11 INFO - mozversion application_name: Firefox 04:18:11 INFO - mozversion application_remotingname: firefox-dev 04:18:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:18:11 INFO - mozversion application_vendor: Mozilla 04:18:11 INFO - mozversion application_version: 48.0a2 04:18:11 INFO - mozversion platform_buildid: 20160601021650 04:18:11 INFO - mozversion platform_changeset: b73cfa00dab309c8b999550363df0c0456128b90 04:18:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:18:11 INFO - mozversion platform_version: 48.0a2 04:18:11 INFO - using testdate: 1464779891 04:18:11 INFO - actual date: 1464779891 04:18:11 INFO - starting webserver on 'localhost:49280' 04:18:11 INFO - SUITE-START | Running 2 tests 04:18:11 INFO - TEST-START | dromaeo_css 04:18:11 INFO - operating with platform_type : mac_ 04:18:11 INFO - Initialising browser for dromaeo_css test... 04:18:11 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpAI2MVT/profile http://localhost:49280/getInfo.html) 04:18:14 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 04:18:14 INFO - PROCESS | 1661 | colorDepth:24 04:18:14 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 04:18:14 INFO - PROCESS | 1661 | __metrics 04:18:14 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 04:18:15 INFO - TEST-INFO | 1661: exit 0 04:18:15 INFO - Browser initialized. 04:18:15 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:15 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpAI2MVT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:27 INFO - PROCESS | 1664 | RSS: Main: 220495872 04:18:27 INFO - PROCESS | 1664 | 04:19:16 INFO - PROCESS | 1664 | 1464779956953 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] 04:21:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 04:21:28 INFO - PROCESS | 1664 | RSS: Main: 231133184 04:21:28 INFO - PROCESS | 1664 | 04:24:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 04:24:31 INFO - PROCESS | 1664 | RSS: Main: 243212288 04:24:31 INFO - PROCESS | 1664 | 04:27:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 04:27:33 INFO - PROCESS | 1664 | RSS: Main: 244838400 04:27:33 INFO - PROCESS | 1664 | 04:30:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 04:30:36 INFO - PROCESS | 1664 | RSS: Main: 246714368 04:30:36 INFO - PROCESS | 1664 | 04:33:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 04:33:39 INFO - PROCESS | 1664 | RSS: Main: 249667584 04:33:39 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 04:36:41 INFO - PROCESS | 1664 | RSS: Main: 250302464 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | __start_tp_report 04:36:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 04:36:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 04:36:41 INFO - PROCESS | 1664 | |i|pagename|runs| 04:36:41 INFO - PROCESS | 1664 | |0;dojo.html;3142.3872344892748;3448.706859916907;3474.322507111113;3482.1468740158666;3516.3318969395814;5682.119271512922;5716.971415142929;5717.571182161339;5718.941995730787;5723.284589426322;5650.74571644276;5709.914351284734;5711.200431939521;5720.628159169651;5743.712814359286;5219.295395121667;5226.398964119127;5247.86880327991;5291.761870715808;5328.333958255218;5171.095058364771;5332.973335133338;5348.358197016363;5384.3807962084275;5395.379531353886;5295.126304159809;5314.840554783363;5344.652597581165;5391.029614669358;5408.8377348679605;4272.636825869805;5328.200769884529;5373.623846330719;5389.003034438641;5438.265834112423;4796.688215265996;5066.670666406671;5218.843434696965;5327.147656374961;5339.893202135935;5510.393856675762;5542.39033706292;5556.222128901916;5573.888522229573;5594.944050559483;6217.87564248717;6233;6233.812985610439;6285;6292.716827742763;5284.603654725873;6186.536009799283;6190.0405437157315;6236.345183755686;6265.028920517281;4644.164050470914;5923.289205295349;5972.761089556369;5991.310999235144;5996.760129594811;6267.467265282502;6282.528810339243;6303.873922521524;6306.747730090837;6309.652969086653;6108.389161083856;6127.111568822585;6139.662318572432;6148.647297594522;6170.691465426711;6235.657038862815;6240.063990401476;6250.749970001149;6269;6289.811305660837;6172.660503672341;6198.0702894566175;6213.03697926822;6221.160143380676;6223.408776166257;5981.850453738604;6160.322364539897;6209.068639704081;6247.96876015617;6257.655828929453;4925.9014819704125;4948.356713627278;4957.7273249971595;4985.351904252424;4991.226359914146;4340.978295108567;4482.417285752831;4483.75339356332;4494.235979883428;4523.344115103291;5604.8318550443555;5713.371529131792;5792.247007888947;5898.203742494794;5922.585419020714;6054.789042191579;6143.511877689453;6242.75028998844;6251.937480625135;6252.6873656317;5787.074068149076;5877.324107727581;5919.526437884959;5950.256217972754;6000.789972350947;4621.560951709582;5429.158480435539;5436.10304299794;5476.91784623223;5517.834464966058;5225.425203227642;6317.4630156437215;6339.683015849281;6352.237731472253;6364.299927008025;6100.572959892765;6262.874742505033;6279.340669229665;6280.5289603279025;6281.183446151971;6020.247469066367;6285.5600107992;6304.684765761602;6339.492840572835;6442.581232219891;6128.540359472968;6148.016317389375;6150.446459818638;6151.96924015377;6152.600080994721;5142.357205349332;6128.387161283836;6251.937480625317;6252.687365631791;6268.843278917881;5525.033119204204;5593.636413633101;5857.970710146422;6155.1382806406245;6183.721732522137;6179.81460556184;6198.473129783839;6204.5967012144065;6234.875302493834;6239.282482514569;4787.305840653155;6010.7295171716505;6089.786857459968;6137.232845894263;6165.0444181152;6142.314611233224;6192.690365481798;6212.875742485216;6221.3156552780065;6234.906476402767;6053.3946605339115;6102.877942441038;6108.816735497942;6109.266887973472;6138.324784273816 04:36:41 INFO - PROCESS | 1664 | |1;ext.html;510.30853193922235;522.7882707503461;545.2557259340998;601.6149664214903;644.4972921121521;39199.60800391995;39930.401043984326;39952.80023599885;39972;40095.398569021454;41644;42132.94667633311;42255.52105676302;42269.788651056704;42341.788291058576;9389.765255868606;9559.187469065104;9597.520123993807;9607.087326703986;9617.32678712487;9469.337146399756;9519;9711.12599866012;9714;9754.609815607368;9673;9687.467189304585;9700.805983880318;9784;9797.951010244939;9678.70963871085;9727.173190278834;9727.27045471589;9737.610495580167;9781.755456113582;9541.188998935098;9612.038796120332;9639.132478076937;9643.180329671986;9675.467849268287;39983.40024899629;39991;40151;40321.99195020118;40927.38608920869;2547.5159719653207;3145.685431456859;3231.418344697953;3286.8849590264467;3325.7671962962604;26151.476970460673;26291.605625915625;26462.73537264622;26778.732212678013;26881.731182688312;2702.108304259609;2718.945621087587;2741.4791189673897;2769.4599553087155;2922.2402175434327;224820;263763;265360.6731966347;265953.3404665948;269750;36695;37499.4375084374;61234.4691382719;61542;61727.38272617216;31562.526562101586;34076.82961585226;36170.63829361673;36212.45681314782;36365.090872727866;16304.918475407547;16413.42553010663;16440.342386304415;16460.506184814476;16476.505704828873;12528.060395470377;13155.67110822218;13836.03147779666;13878;13988.790168147487;8138.552379619177;8294.419390642717;8319.084900660922;8349.33205343571;8384.958075209706;3439.071450708295;3478.669526394988;3491.511188433621;3507.2810073935293;3517.314123745845;1028.2134167361974;1050.298516403416;1112.5827814569536;1123.3035517978906;1129.6046383765615;26542.46915061688;27502.17493475199;30173.943911962975;30884.84557577198;31480.37039259248;999.3853779925294;1111.4276147783899;1150.2925700694013;1165.7959857024987;1168.807146820782;15566.766498502531;15667.529974100795;15968.600784980235;16096.597585060234;16380.918095409446;539180;646478.7676061684;652013;653127;661730;63262;63731.681341593925;63947.36052639414;64118.038229426595;64221.35778642247;1283.4160456992047;1458.5697219320325;1481.4148411377662;1481.4370539195038;1487.620656732548;30335.848320758254;30445.543316850708;31160;31611.05166844998;32156.356872861943;58064.12903806362;58424.707876462046;58920.705396472746;58944.115838261605;59251.40748592459;586682;597265;600224;600322;601540;42344.36483452689;43498;43965;44097.55902441063;45259.54740452553;48447;48655;48937.75531122321;48981.51018489769;50262.497375025785;39271;39420.40869386904;40082.79751607456;40359.39460908147;40690.9827254321;38979;39303.41044884272;39802.60197398105;39866.80066599648;39882.002949926486;32525.67474325321;38217;38580.228395432496;38984.83045508639;39893.60106398899;28156.71843281623;31536;32923.34153316968;33110.00669979904;33206;20071.39629910934;20965.790342096385;23842.88078559596;25840.8707956459;25958 04:36:41 INFO - PROCESS | 1664 | |2;jquery.html;361.1350814798557;392.628965627482;397.8508059477695;400.8271973042404;406.8087998640639;14969.401223951056;16496.505104846845;20649.690254646193;20842.583148337042;21219.469513262196;20353.28763493278;20486.692699609517;20606.896965515152;20715.896420517915;20995.95020248989;5302.761375738101;5781.653100813944;6100.847478813043;6102.298235702907;6110.358412366704;5981.461668449839;5987.640741555499;6022.705118399544;6048.39516048396;6086.056661217518;5698.572607054467;5875.059990401536;5984.670843103628;6005.2193214882;6010.8497287567725;5395.244665746799;5802.245708057925;5872.941270587304;5938.168656388109;6008.819735407923;4980.651354405193;5137.2037334213255;5140.974295128538;5262.631615786897;5270.262163297141;26682.19953401401;27850.860745696344;27933.301667458272;28077.438451231064;28383.86464541416;2018.8687735297158;2145.549434618732;2180.716506854115;2281.0761641535205;2349.48311371498;8298.170182981714;10135.341202821794;10225.284230103896;13860.792088118686;14830.629234269121;1724.336130589732;1735.9913200434044;1755.3768412213608;1759.6920538905767;1770.2034084661825;114030.85969140369;117638;118470;120400;120568.98577535544;9936.552855121538;12037.458314375874;12258.325792081521;12268.877311226952;12327.137100402886;14909.70180596382;15210.08739475635;15273.694526109415;15366.385344586315;15370.538883833502;8963.282937364995;9056.456612603264;9064.274858011344;9151;9193.770155746026;9080.591373388213;9191.494467804201;9220.769480763034;9247.029061948533;9306.06939306064;4778.95221047792;4791.257355109963;4828.275758636162;4852.44196917359;4875.561199491992;1985.2555291765589;2040.734704488407;2044.9079791409422;2079.7504299483858;2087.6242276390394;915.789368445248;934.9158575728217;965.5992763003416;971.1987610221596;1028.4960369418932;20483.897580512003;21265.255716050175;21269.255576054762;21674.133034678438;21919.232826850985;955.5652178259014;1006.4716024087413;1042.968710938673;1057.0486562094177;1072.92489525734;11396.14528910335;11544.191906566628;11599.942000289944;11602.76794464106;11669.35818529989;597138;604424;635013;635173;636940;109060;127685;130237.34881325722;130884;131252;1028.8508166315992;1031.968031968032;1042.968710938673;1043.2592859069994;1044.9425281609585;17311.653766924843;18685.252589896252;19034.714479282546;19537.902310488356;20492.282770102975;49731.99999999855;53114.46885531095;53719.194212087634;53795.73102134621;54669.72665136649;71054.64472677604;71650.05924674193;72080.27919720736;75276;77415.61292193503;8127.75616731499;8291.212334828182;8344.457610255315;8484.575771211292;8548.529830859363;12226.816597750863;12803.423845927142;12899.677508062374;12957.676058098623;13121.671958201121;7497.550146991198;9381.733465982277;11238.494267757807;11369.147313951322;11804.881951180378;7929.920700792918;10935.83596246041;11060;11252.718682033015;11468.426578670866;10484.370937743759;11046.613368532384;11449.59926402572;11534.307941523535;11704.356260405759;6837.829054273683;7941.729323308474;8298.958505207434;8390.622421990904;8550.187732165434;7432.182459929511;10416;10836.187285953427;11281.548738050386;11572.6528204154 04:36:41 INFO - PROCESS | 1664 | |3;mootools.html;1423.4377420918738;1589.2609936379586;1829.5791967847395;1939.3309308288635;1995.3515107590038;7807.141214466405;8426;8568.614412351444;8787.780305492357;8805.1194880512;8561.315094792417;8563.700270490534;8588.098249683771;8640.265577425911;8679.91320086799;2459.323685986359;2468.5803413419717;2489;2494.887730052152;2500.112460076665;2441.6825812644333;2504.8121390895662;2504.9248522544353;2508.7205525182108;2515.9119430819924;2462.0890270599903;2462.3597864555168;2467.814913881457;2474.1958863369387;2475.5667758142345;2436.4639779248537;2458.754124587536;2498.6252062190633;2501.137107697841;2507.247825652297;2451.460678650694;2461.59383701689;2470.234227389515;2494.8004159667184;2501.0871032073346;8678.305735541142;8755.518446485441;8756.8686469703;8775.78060548485;8790.428622139605;4614.215583350805;5438.483344082306;5529.695866727328;5545.722713864331;5558.749856256478;3516.156122530574;3552.342816578941;3572.928541429183;3618.3486972344967;3687.3547129252274;1032.2516175772505;1059.6820953713932;1060.0459586372249;1061.0556604621893;1061.4639606998494;11433.028192603639;11667.883321166764;11673.357965311905;11693.298402095816;11724.765504689858;4763.333133361332;4810.573124984488;4867.3915760529935;4874.8781280467565;4881;9942.552585133542;9966.750831229132;10058.497075146213;10096.596136154472;10168.33905796121;5431.293931788921;5463.890722185534;5465.59008074396;5467.425920278393;5471.097268950579;4408.470983482002;4767.332573439721;4861.368022157097;4877.219644856806;4942.456329803718;3871.8965094947803;4082.081531655413;4129.511993876342;4174.83300667976;4175.498940127204;1244.7012716947868;1353.3977380065776;1357.395958798345;1364.2632978191864;1376.7315373502272;869.9304055675531;881.8897637795276;898.8943599372808;903.0112027330063;943.5848226780195;4471.731696098244;4964;5281.943611277759;5531.723413829292;6024.1566180734735;875.9562021898879;1035.8498017787379;1046.4140081554353;1061.304845326297;1085.4138765066775;3721.6650501455;3840.5967373425947;3869.28418242626;3871.8644847430205;3876.8836934891997;10455.268131230892;10714.142868570496;10754.623588174376;10859.945700271448;10872.510737016853;10472.214583906238;10487.475626218658;10501.474926253657;10522.526486307981;10601.84097238542;7896.526208427513;8073.838523229502;8212.917870821335;8213.260806527322;8308.875366869503;5645.237892884408;5721.942780572141;5730.111832665943;5804.854878628068;5836.416358364129;9115.680951166678;9127.361084724005;9190.448573085636;9226.492542910204;9346.953265233631;9337.299702522203;9418.293627977791;9495.572699228413;9512.429254244491;9529.714108576754;6629.6685165740555;7169.820754481179;7394.741184058532;7418.740344087931;7548.094228692592;8137.6338064788415;8213.671453141807;8246.3815213858;8296.087430382533;8307.376946728898;7265.382442492447;7508.061492313461;7508.662110204945;7533.2466753324225;7702.114256860309;7532.510386824839;7581.241875812595;7630.16068232505;7691.923080769121;7735;6833.58998460094;7040.1551813780625;7060.6116663584;7074.009638650696;7083.327083927019;4981.850544483671;5021.974890125526;5113.846584602468;5164.586833053271;5172.7413629319135;4949.109160351171;4969.931464735099;5215.791368345223;5269.920951185659;5377.758000890048 04:36:41 INFO - PROCESS | 1664 | |4;prototype.html;326.6961725595197;327.73780975219825;333.68466798875056;334.1912571576784;336.71547542326755;6714.294999025105;6723.529352945296;6726.091977583019;6739.427148692367;6756.662166891654;6665.433438157763;6695.430888374494;6699.631520266384;6710.765123220676;6776.491763117761;4727.90544189116;4762.380890484233;4870.707757534559;4876.2685597160535;4877.34155888954;4792.712437253758;4863.781129849165;4874.317595536628;4877.487863774306;4883.023395320933;4833.105875413043;4871.366722326093;4882.511748825124;4883.12103821312;4898.902021959559;4888.97555512222;4891.706497610155;4894.755262236874;4896.4124305083615;4933.70397776131;4197.496300443936;4198.5591512891315;4207.537170911197;4208.747475151501;4218.135282267128;4675.625949923998;5474.315710536183;5604.075327570946;6506.186726659168;6507.121538592277;5754.741036653319;5797.768089276424;5799.3040835100055;5828.97085514574;5833.533317334603;7183.281671832827;7298.233685463003;7299.343059124704;7301.671424785897;7308.598027108507;4663.953360466386;4673.135469938038;4676.275177347517;4704.623630109617;4707.811687532495;7648.464607477479;7711.768646940544;7731.381489480828;7733.45865789395;7740.148583655793;4560.931586026212;4687.882802929886;4724.291356296514;4744.430668319824;4752.691075080109;4770.570648641569;4812.735299558488;4813.7593120344545;4817.662763606514;4822.252550854623;5794.1308803679785;5803.448672376117;5807.593468457252;5814.244148260699;5827.59206855516;7325.047743793273;7328.706851725978;7341.669624866894;7346.485745997836;7348.559086454829;1654.7766051582882;1678.5048410718866;1681.8906771059842;1683.8905471144337;1685.8398452147026;1017.8574999500075;1050.3855244681947;1064.6380230721593;1064.8455973883742;1077.5528155815405;6708.19501659804;6802.55783374089;6813.625250611165;6816.284290149562;6824.897626535606;7331.8533629328185;7633.122190948112;7634.6946122155605;7646.273604007611;7656.349210317074;6080.300765411946;6286.497080233662;6610.8347291318105;6775.62734049632;6802.65986700663;4009.498812648419;4036.1524079942956;4052.149048699806;4052.574479679591;4059.9594004060173;7245.710171593183;7999.520028798291;8012.799680008046;8017.438779285394;8030;7122.60825654594;7613.42899282556;7926.564038977912;7942.205779422012;7942.483738556975;7238.638068096574;7946.284834364935;8003.719869804529;8011.599420028975;8017.518948863087;7652.38780897538;7677.577733224726;7692.230776922487;7707.267809558083;7751.302382785577;6006.579539432198;6178.41305076017;7503.1746507883;7538.434617403607;7578.772636820904;6393.648349340645;6731.091302674272;7756.612169391621;7775.650095745818;7999.160088190889;4118.608732170439;4528.2075636762775;4673.719576825401;4683.695559788603;4747.691400058985;7591.43064270193;7594.696212151452;7601.0878694557005;7605.935169076223;7609.315161635479;7282.053333066668;7297.708091676273;7312.780616581511;7316.487845850739;7326.120865496175;5598.3282006159525;7055.717771289091;7311.122665279988;7324.230955749783;7372.225916278928;7157.391621712213;7158.498905076594;7162.068931038932;7172.641367931478;7180.748673796392;7312.341889229995;7321.194668586558;7335.303146201102;7338.853222935405;7364.742234021784;6920.446364290945;7135.714571417293;7137.785866424016;7188.794555185066;7191.388731957633 04:36:41 INFO - PROCESS | 1664 | |5;yui.html;701.4143190435985;723.0130871360594;734.0544572920555;744.5569885917877;770.653206057274;14622.049566778152;14648.633784155401;14769.261536923152;14960.476383326586;14986.850131498695;13424.463021479129;13695.657608559792;13920.373583188755;13957.371918263678;13972.930135349308;719.8164468060637;903.7424334064793;910.1308250620641;922.9815403691956;929.330881765128;905.41148253635;905.950172740499;909.8999110097884;913.0777914306532;915.2312057871387;938.699616122841;974.1768205866032;974.5176137811793;975.3221511049824;980.1619615228983;961.0053594529663;964.4984608003869;966.8839739231302;969.016448304971;969.5636963366513;685.9657017149123;709.8473828126926;712.6365553567666;723.8552289542113;728.2208037399984;14432.422703091865;14456.855431445656;14679.706405871822;14794.260286985607;14836.10983341003;12737.426815793311;12781;13001.544945926942;13004.089713720043;13112.541061062913;9858.9507052465;9967.80064398708;10128.291019628628;10150.847737283946;10164.796704065951;3468.8265586720813;3543.557055368079;3544.131687736495;3563.322968635951;3580.230250496156;56226.59433514154;73223;79823.8026429604;79847;79945.60027199826;9186.63253469867;9323.860142097874;9383.155516003457;9418.387804792666;9461.479618620904;20590.27934022302;20632.896835516025;20821.583568328548;20859;20889.686654700192;6747;6911.7926462206215;7209.783706488814;7433.814154646069;7493.850122997618;4013.3578627420056;4031.113155105872;4035.3139966205817;4036.3138266494766;4057.8985525361513;3675.687566556819;3755.8497660093835;3756.042209236627;3758.9060273492832;3768.7927164005696;577.711144427786;664.3655309179721;672.9932700673029;674.436845234235;677.6273049822578;484.36548121960345;580.1413907417046;603.3122240645667;610.2128254551578;614.7633161232868;6841.418479429205;7841.882371764428;8305.127961564069;8306.70926517569;8387.454815436977;474.6795912758874;487.9243717223836;592.8310431526994;602.1509671363357;634.9587276826992;3604.3151801157696;3675.5956844747056;3887.455756194135;3943.132510847609;4021.235965166609;71034.64482677553;77738;86064;91525.54237228772;94505;75020;76683;76778.23221767823;77384.22615773883;77479;66487.7158781339;66541.66729166324;67564;67940;68438;8386.790330241793;8425.031121421114;8607.784805379915;8661.04728479854;8873.73378798637;45337;46796.532034679214;46923.296150557086;48228;49727.75136124441;63117;65701.67149164414;67024.3297567018;67381.66309168423;68611;10930.945345273223;11223.38271395081;11225.66323010311;11277.548898043986;11296.265742726697;11830.17188796776;11862.762744744885;11886.702832428913;12178;12396.628101156979;11402.82895756581;11539.307641541534;11577.131715121232;11726.237794543327;11878.227915185485;11049.834252486058;11344.319340839576;11380.203385762918;11422.657320280072;11468.4265786712;11049.392283424168;11126.054285385833;11235.831462527905;11313.377764223047;11614.245074070159;9572.712818615453;9634.181094606756;9653.613855445703;9718.951405242928;9882.654107106217;9462.810743784948;9850.211983041196;9892.307538472525;9938.304318697621;9951.800963980824 04:36:41 INFO - PROCESS | 1664 | __end_tp_report 04:36:41 INFO - PROCESS | 1664 | __start_cc_report 04:36:41 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,415 04:36:41 INFO - PROCESS | 1664 | __end_cc_report 04:36:41 INFO - PROCESS | 1664 | __startTimestamp1464781001688__endTimestamp 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | ------- Summary: start ------- 04:36:41 INFO - PROCESS | 1664 | Number of tests: 6 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5736.70 Median:6000.79 stddev:652.54 (10.9%) stddev-sans-first:622.00 04:36:41 INFO - PROCESS | 1664 | Values: 3142.4 3448.7 3474.3 3482.1 3516.3 5682.1 5717.0 5717.6 5718.9 5723.3 5650.7 5709.9 5711.2 5720.6 5743.7 5219.3 5226.4 5247.9 5291.8 5328.3 5171.1 5333.0 5348.4 5384.4 5395.4 5295.1 5314.8 5344.7 5391.0 5408.8 4272.6 5328.2 5373.6 5389.0 5438.3 4796.7 5066.7 5218.8 5327.1 5339.9 5510.4 5542.4 5556.2 5573.9 5594.9 6217.9 6233.0 6233.8 6285.0 6292.7 5284.6 6186.5 6190.0 6236.3 6265.0 4644.2 5923.3 5972.8 5991.3 5996.8 6267.5 6282.5 6303.9 6306.7 6309.7 6108.4 6127.1 6139.7 6148.6 6170.7 6235.7 6240.1 6250.7 6269.0 6289.8 6172.7 6198.1 6213.0 6221.2 6223.4 5981.9 6160.3 6209.1 6248.0 6257.7 4925.9 4948.4 4957.7 4985.4 4991.2 4341.0 4482.4 4483.8 4494.2 4523.3 5604.8 5713.4 5792.2 5898.2 5922.6 6054.8 6143.5 6242.8 6251.9 6252.7 5787.1 5877.3 5919.5 5950.3 6000.8 4621.6 5429.2 5436.1 5476.9 5517.8 5225.4 6317.5 6339.7 6352.2 6364.3 6100.6 6262.9 6279.3 6280.5 6281.2 6020.2 6285.6 6304.7 6339.5 6442.6 6128.5 6148.0 6150.4 6152.0 6152.6 5142.4 6128.4 6251.9 6252.7 6268.8 5525.0 5593.6 5858.0 6155.1 6183.7 6179.8 6198.5 6204.6 6234.9 6239.3 4787.3 6010.7 6089.8 6137.2 6165.0 6142.3 6192.7 6212.9 6221.3 6234.9 6053.4 6102.9 6108.8 6109.3 6138.3 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:63477.80 Median:39834.70 stddev:140720.35 (353.3%) stddev-sans-first:141035.69 04:36:41 INFO - PROCESS | 1664 | Values: 510.3 522.8 545.3 601.6 644.5 39199.6 39930.4 39952.8 39972.0 40095.4 41644.0 42132.9 42255.5 42269.8 42341.8 9389.8 9559.2 9597.5 9607.1 9617.3 9469.3 9519.0 9711.1 9714.0 9754.6 9673.0 9687.5 9700.8 9784.0 9798.0 9678.7 9727.2 9727.3 9737.6 9781.8 9541.2 9612.0 9639.1 9643.2 9675.5 39983.4 39991.0 40151.0 40322.0 40927.4 2547.5 3145.7 3231.4 3286.9 3325.8 26151.5 26291.6 26462.7 26778.7 26881.7 2702.1 2718.9 2741.5 2769.5 2922.2 224820.0 263763.0 265360.7 265953.3 269750.0 36695.0 37499.4 61234.5 61542.0 61727.4 31562.5 34076.8 36170.6 36212.5 36365.1 16304.9 16413.4 16440.3 16460.5 16476.5 12528.1 13155.7 13836.0 13878.0 13988.8 8138.6 8294.4 8319.1 8349.3 8385.0 3439.1 3478.7 3491.5 3507.3 3517.3 1028.2 1050.3 1112.6 1123.3 1129.6 26542.5 27502.2 30173.9 30884.8 31480.4 999.4 1111.4 1150.3 1165.8 1168.8 15566.8 15667.5 15968.6 16096.6 16380.9 539180.0 646478.8 652013.0 653127.0 661730.0 63262.0 63731.7 63947.4 64118.0 64221.4 1283.4 1458.6 1481.4 1481.4 1487.6 30335.8 30445.5 31160.0 31611.1 32156.4 58064.1 58424.7 58920.7 58944.1 59251.4 586682.0 597265.0 600224.0 600322.0 601540.0 42344.4 43498.0 43965.0 44097.6 45259.5 48447.0 48655.0 48937.8 48981.5 50262.5 39271.0 39420.4 40082.8 40359.4 40691.0 38979.0 39303.4 39802.6 39866.8 39882.0 32525.7 38217.0 38580.2 38984.8 39893.6 28156.7 31536.0 32923.3 33110.0 33206.0 20071.4 20965.8 23842.9 25840.9 25958.0 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:35630.96 Median:12049.97 stddev:103487.10 (858.8%) stddev-sans-first:103743.52 04:36:41 INFO - PROCESS | 1664 | Values: 361.1 392.6 397.9 400.8 406.8 14969.4 16496.5 20649.7 20842.6 21219.5 20353.3 20486.7 20606.9 20715.9 20996.0 5302.8 5781.7 6100.8 6102.3 6110.4 5981.5 5987.6 6022.7 6048.4 6086.1 5698.6 5875.1 5984.7 6005.2 6010.8 5395.2 5802.2 5872.9 5938.2 6008.8 4980.7 5137.2 5141.0 5262.6 5270.3 26682.2 27850.9 27933.3 28077.4 28383.9 2018.9 2145.5 2180.7 2281.1 2349.5 8298.2 10135.3 10225.3 13860.8 14830.6 1724.3 1736.0 1755.4 1759.7 1770.2 114030.9 117638.0 118470.0 120400.0 120569.0 9936.6 12037.5 12258.3 12268.9 12327.1 14909.7 15210.1 15273.7 15366.4 15370.5 8963.3 9056.5 9064.3 9151.0 9193.8 9080.6 9191.5 9220.8 9247.0 9306.1 4779.0 4791.3 4828.3 4852.4 4875.6 1985.3 2040.7 2044.9 2079.8 2087.6 915.8 934.9 965.6 971.2 1028.5 20483.9 21265.3 21269.3 21674.1 21919.2 955.6 1006.5 1043.0 1057.0 1072.9 11396.1 11544.2 11599.9 11602.8 11669.4 597138.0 604424.0 635013.0 635173.0 636940.0 109060.0 127685.0 130237.3 130884.0 131252.0 1028.9 1032.0 1043.0 1043.3 1044.9 17311.7 18685.3 19034.7 19537.9 20492.3 49732.0 53114.5 53719.2 53795.7 54669.7 71054.6 71650.1 72080.3 75276.0 77415.6 8127.8 8291.2 8344.5 8484.6 8548.5 12226.8 12803.4 12899.7 12957.7 13121.7 7497.6 9381.7 11238.5 11369.1 11804.9 7929.9 10935.8 11060.0 11252.7 11468.4 10484.4 11046.6 11449.6 11534.3 11704.4 6837.8 7941.7 8299.0 8390.6 8550.2 7432.2 10416.0 10836.2 11281.5 11572.7 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5690.07 Median:4966.97 stddev:3104.59 (62.5%) stddev-sans-first:3096.74 04:36:41 INFO - PROCESS | 1664 | Values: 1423.4 1589.3 1829.6 1939.3 1995.4 7807.1 8426.0 8568.6 8787.8 8805.1 8561.3 8563.7 8588.1 8640.3 8679.9 2459.3 2468.6 2489.0 2494.9 2500.1 2441.7 2504.8 2504.9 2508.7 2515.9 2462.1 2462.4 2467.8 2474.2 2475.6 2436.5 2458.8 2498.6 2501.1 2507.2 2451.5 2461.6 2470.2 2494.8 2501.1 8678.3 8755.5 8756.9 8775.8 8790.4 4614.2 5438.5 5529.7 5545.7 5558.7 3516.2 3552.3 3572.9 3618.3 3687.4 1032.3 1059.7 1060.0 1061.1 1061.5 11433.0 11667.9 11673.4 11693.3 11724.8 4763.3 4810.6 4867.4 4874.9 4881.0 9942.6 9966.8 10058.5 10096.6 10168.3 5431.3 5463.9 5465.6 5467.4 5471.1 4408.5 4767.3 4861.4 4877.2 4942.5 3871.9 4082.1 4129.5 4174.8 4175.5 1244.7 1353.4 1357.4 1364.3 1376.7 869.9 881.9 898.9 903.0 943.6 4471.7 4964.0 5281.9 5531.7 6024.2 876.0 1035.8 1046.4 1061.3 1085.4 3721.7 3840.6 3869.3 3871.9 3876.9 10455.3 10714.1 10754.6 10859.9 10872.5 10472.2 10487.5 10501.5 10522.5 10601.8 7896.5 8073.8 8212.9 8213.3 8308.9 5645.2 5721.9 5730.1 5804.9 5836.4 9115.7 9127.4 9190.4 9226.5 9347.0 9337.3 9418.3 9495.6 9512.4 9529.7 6629.7 7169.8 7394.7 7418.7 7548.1 8137.6 8213.7 8246.4 8296.1 8307.4 7265.4 7508.1 7508.7 7533.2 7702.1 7532.5 7581.2 7630.2 7691.9 7735.0 6833.6 7040.2 7060.6 7074.0 7083.3 4981.9 5022.0 5113.8 5164.6 5172.7 4949.1 4969.9 5215.8 5269.9 5377.8 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5899.57 Median:6709.48 stddev:1921.70 (28.6%) stddev-sans-first:1881.02 04:36:41 INFO - PROCESS | 1664 | Values: 326.7 327.7 333.7 334.2 336.7 6714.3 6723.5 6726.1 6739.4 6756.7 6665.4 6695.4 6699.6 6710.8 6776.5 4727.9 4762.4 4870.7 4876.3 4877.3 4792.7 4863.8 4874.3 4877.5 4883.0 4833.1 4871.4 4882.5 4883.1 4898.9 4889.0 4891.7 4894.8 4896.4 4933.7 4197.5 4198.6 4207.5 4208.7 4218.1 4675.6 5474.3 5604.1 6506.2 6507.1 5754.7 5797.8 5799.3 5829.0 5833.5 7183.3 7298.2 7299.3 7301.7 7308.6 4664.0 4673.1 4676.3 4704.6 4707.8 7648.5 7711.8 7731.4 7733.5 7740.1 4560.9 4687.9 4724.3 4744.4 4752.7 4770.6 4812.7 4813.8 4817.7 4822.3 5794.1 5803.4 5807.6 5814.2 5827.6 7325.0 7328.7 7341.7 7346.5 7348.6 1654.8 1678.5 1681.9 1683.9 1685.8 1017.9 1050.4 1064.6 1064.8 1077.6 6708.2 6802.6 6813.6 6816.3 6824.9 7331.9 7633.1 7634.7 7646.3 7656.3 6080.3 6286.5 6610.8 6775.6 6802.7 4009.5 4036.2 4052.1 4052.6 4060.0 7245.7 7999.5 8012.8 8017.4 8030.0 7122.6 7613.4 7926.6 7942.2 7942.5 7238.6 7946.3 8003.7 8011.6 8017.5 7652.4 7677.6 7692.2 7707.3 7751.3 6006.6 6178.4 7503.2 7538.4 7578.8 6393.6 6731.1 7756.6 7775.7 7999.2 4118.6 4528.2 4673.7 4683.7 4747.7 7591.4 7594.7 7601.1 7605.9 7609.3 7282.1 7297.7 7312.8 7316.5 7326.1 5598.3 7055.7 7311.1 7324.2 7372.2 7157.4 7158.5 7162.1 7172.6 7180.7 7312.3 7321.2 7335.3 7338.9 7364.7 6920.4 7135.7 7137.8 7188.8 7191.4 04:36:41 INFO - PROCESS | 1664 | 04:36:41 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17635.96 Median:33168.32 stddev:24270.36 (73.2%) stddev-sans-first:24305.13 04:36:41 INFO - PROCESS | 1664 | Values: 701.4 723.0 734.1 744.6 770.7 14622.0 14648.6 14769.3 14960.5 14986.9 13424.5 13695.7 13920.4 13957.4 13972.9 719.8 903.7 910.1 923.0 929.3 905.4 906.0 909.9 913.1 915.2 938.7 974.2 974.5 975.3 980.2 961.0 964.5 966.9 969.0 969.6 686.0 709.8 712.6 723.9 728.2 14432.4 14456.9 14679.7 14794.3 14836.1 12737.4 12781.0 13001.5 13004.1 13112.5 9859.0 9967.8 10128.3 10150.8 10164.8 3468.8 3543.6 3544.1 3563.3 3580.2 56226.6 73223.0 79823.8 79847.0 79945.6 9186.6 9323.9 9383.2 9418.4 9461.5 20590.3 20632.9 20821.6 20859.0 20889.7 6747.0 6911.8 7209.8 7433.8 7493.9 4013.4 4031.1 4035.3 4036.3 4057.9 3675.7 3755.8 3756.0 3758.9 3768.8 577.7 664.4 673.0 674.4 677.6 484.4 580.1 603.3 610.2 614.8 6841.4 7841.9 8305.1 8306.7 8387.5 474.7 487.9 592.8 602.2 635.0 3604.3 3675.6 3887.5 3943.1 4021.2 71034.6 77738.0 86064.0 91525.5 94505.0 75020.0 76683.0 76778.2 77384.2 77479.0 66487.7 66541.7 67564.0 67940.0 68438.0 8386.8 8425.0 8607.8 8661.0 8873.7 45337.0 46796.5 46923.3 48228.0 49727.8 63117.0 65701.7 67024.3 67381.7 68611.0 10930.9 11223.4 11225.7 11277.5 11296.3 11830.2 11862.8 11886.7 12178.0 12396.6 11402.8 11539.3 11577.1 11726.2 11878.2 11049.8 11344.3 11380.2 11422.7 11468.4 11049.4 11126.1 11235.8 11313.4 11614.2 9572.7 9634.2 9653.6 9719.0 9882.7 9462.8 9850.2 9892.3 9938.3 9951.8 04:36:41 INFO - PROCESS | 1664 | -------- Summary: end -------- 04:36:41 INFO - PROCESS | 1664 | 04:36:42 INFO - TEST-INFO | 1664: exit 0 04:36:42 INFO - TEST-OK | dromaeo_css | took 1110413ms 04:36:42 INFO - TEST-START | kraken 04:36:42 INFO - operating with platform_type : mac_ 04:36:42 INFO - Initialising browser for kraken test... 04:36:42 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpQvnZYl/profile http://localhost:49280/getInfo.html) 04:36:45 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 04:36:45 INFO - PROCESS | 1677 | colorDepth:24 04:36:45 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 04:36:45 INFO - PROCESS | 1677 | __metrics 04:36:45 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 04:36:45 INFO - TEST-INFO | 1677: exit 0 04:36:45 INFO - Browser initialized. 04:36:45 INFO - Running cycle 1/1 for kraken test... 04:36:45 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpQvnZYl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:36:57 INFO - PROCESS | 1680 | RSS: Main: 220528640 04:36:57 INFO - PROCESS | 1680 | 04:37:00 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:01 INFO - PROCESS | 1680 | RSS: Main: 232792064 04:37:01 INFO - PROCESS | 1680 | 04:37:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 04:37:04 INFO - PROCESS | 1680 | RSS: Main: 241725440 04:37:04 INFO - PROCESS | 1680 | 04:37:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 04:37:08 INFO - PROCESS | 1680 | RSS: Main: 245633024 04:37:08 INFO - PROCESS | 1680 | 04:37:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 04:37:11 INFO - PROCESS | 1680 | RSS: Main: 247128064 04:37:11 INFO - PROCESS | 1680 | 04:37:13 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:13 INFO - PROCESS | 1680 | RSS: Main: 246816768 04:37:13 INFO - PROCESS | 1680 | 04:37:16 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:17 INFO - PROCESS | 1680 | RSS: Main: 247590912 04:37:17 INFO - PROCESS | 1680 | 04:37:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:20 INFO - PROCESS | 1680 | RSS: Main: 247537664 04:37:20 INFO - PROCESS | 1680 | 04:37:23 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 04:37:24 INFO - PROCESS | 1680 | RSS: Main: 247726080 04:37:24 INFO - PROCESS | 1680 | 04:37:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:26 INFO - PROCESS | 1680 | RSS: Main: 247123968 04:37:26 INFO - PROCESS | 1680 | 04:37:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:37:29 INFO - PROCESS | 1680 | RSS: Main: 247222272 04:37:29 INFO - PROCESS | 1680 | 04:37:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:37:32 INFO - PROCESS | 1680 | RSS: Main: 247521280 04:37:32 INFO - PROCESS | 1680 | 04:37:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:37:35 INFO - PROCESS | 1680 | RSS: Main: 247496704 04:37:35 INFO - PROCESS | 1680 | 04:37:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:37:39 INFO - PROCESS | 1680 | RSS: Main: 247488512 04:37:39 INFO - PROCESS | 1680 | 04:37:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 04:37:41 INFO - PROCESS | 1680 | RSS: Main: 249442304 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | __start_tp_report 04:37:41 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 04:37:41 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 04:37:41 INFO - PROCESS | 1680 | |i|pagename|runs| 04:37:41 INFO - PROCESS | 1680 | |0;ai-astar;113;121;108;117;119;117;115;167;121;118 04:37:41 INFO - PROCESS | 1680 | |1;audio-beat-detection;144;126;158;154;122;137;138;125;170;123 04:37:41 INFO - PROCESS | 1680 | |2;audio-dft;199;198;196;200;197;197;202;200;199;190 04:37:41 INFO - PROCESS | 1680 | |3;audio-fft;70;81;76;160;82;84;57;64;119;58 04:37:41 INFO - PROCESS | 1680 | |4;audio-oscillator;80;83;127;77;80;82;119;81;80;122 04:37:41 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;90;87;89;88;90;87;90;87;88;87 04:37:41 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;104;148;117;148;147;94;96;95;95 04:37:41 INFO - PROCESS | 1680 | |7;imaging-desaturate;90;93;90;91;92;100;143;133;91;101 04:37:41 INFO - PROCESS | 1680 | |8;json-parse-financial;61;63;66;65;62;65;65;80;61;59 04:37:41 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;64;68;67;85;108;101;82;68;69;70 04:37:41 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;82;87;92;103;84;79;82;85;105;82 04:37:41 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;131;127;127;131;122;149;149;153;145;165 04:37:41 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;168;170;178;165;172;184;171;170;166;167 04:37:41 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;71;66;71;66;69;67;75;91;95;81 04:37:41 INFO - PROCESS | 1680 | __end_tp_report 04:37:41 INFO - PROCESS | 1680 | __start_cc_report 04:37:41 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1106 04:37:41 INFO - PROCESS | 1680 | __end_cc_report 04:37:41 INFO - PROCESS | 1680 | __startTimestamp1464781061149__endTimestamp 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | ------- Summary: start ------- 04:37:41 INFO - PROCESS | 1680 | Number of tests: 14 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:121.60 Median:118.50 stddev:16.42 (13.9%) stddev-sans-first:17.12 04:37:41 INFO - PROCESS | 1680 | Values: 113.0 121.0 108.0 117.0 119.0 117.0 115.0 167.0 121.0 118.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:141.00 stddev:16.61 (11.8%) stddev-sans-first:17.54 04:37:41 INFO - PROCESS | 1680 | Values: 144.0 126.0 158.0 154.0 122.0 137.0 138.0 125.0 170.0 123.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:197.80 Median:199.00 stddev:3.26 (1.6%) stddev-sans-first:3.43 04:37:41 INFO - PROCESS | 1680 | Values: 199.0 198.0 196.0 200.0 197.0 197.0 202.0 200.0 199.0 190.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:85.10 Median:73.00 stddev:31.74 (43.5%) stddev-sans-first:33.19 04:37:41 INFO - PROCESS | 1680 | Values: 70.0 81.0 76.0 160.0 82.0 84.0 57.0 64.0 119.0 58.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:80.00 stddev:20.55 (25.7%) stddev-sans-first:21.24 04:37:41 INFO - PROCESS | 1680 | Values: 80.0 83.0 127.0 77.0 80.0 82.0 119.0 81.0 80.0 122.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:1.34 (1.5%) stddev-sans-first:1.27 04:37:41 INFO - PROCESS | 1680 | Values: 90.0 87.0 89.0 88.0 90.0 87.0 90.0 87.0 88.0 87.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:94.50 stddev:24.31 (25.7%) stddev-sans-first:24.80 04:37:41 INFO - PROCESS | 1680 | Values: 95.0 104.0 148.0 117.0 148.0 147.0 94.0 96.0 95.0 95.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:90.50 stddev:19.31 (21.3%) stddev-sans-first:19.95 04:37:41 INFO - PROCESS | 1680 | Values: 90.0 93.0 90.0 91.0 92.0 100.0 143.0 133.0 91.0 101.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:5.83 (9.0%) stddev-sans-first:6.03 04:37:41 INFO - PROCESS | 1680 | Values: 61.0 63.0 66.0 65.0 62.0 65.0 65.0 80.0 61.0 59.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:68.50 stddev:15.48 (22.6%) stddev-sans-first:15.54 04:37:41 INFO - PROCESS | 1680 | Values: 64.0 68.0 67.0 85.0 108.0 101.0 82.0 68.0 69.0 70.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:83.00 stddev:9.10 (11.0%) stddev-sans-first:9.38 04:37:41 INFO - PROCESS | 1680 | Values: 82.0 87.0 92.0 103.0 84.0 79.0 82.0 85.0 105.0 82.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:147.00 stddev:14.16 (9.6%) stddev-sans-first:14.65 04:37:41 INFO - PROCESS | 1680 | Values: 131.0 127.0 127.0 131.0 122.0 149.0 149.0 153.0 145.0 165.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:170.50 stddev:5.84 (3.4%) stddev-sans-first:6.09 04:37:41 INFO - PROCESS | 1680 | Values: 168.0 170.0 178.0 165.0 172.0 184.0 171.0 170.0 166.0 167.0 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:73.00 stddev:10.46 (14.3%) stddev-sans-first:10.99 04:37:41 INFO - PROCESS | 1680 | Values: 71.0 66.0 71.0 66.0 69.0 67.0 75.0 91.0 95.0 81.0 04:37:41 INFO - PROCESS | 1680 | -------- Summary: end -------- 04:37:41 INFO - PROCESS | 1680 | 04:37:41 INFO - TEST-INFO | 1680: exit 0 04:37:41 INFO - TEST-OK | kraken | took 59613ms 04:37:41 INFO - SUITE-END | took 1170s 04:37:42 INFO - Completed test suite (00:19:30) 04:37:42 INFO - javascript benchmark 04:37:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.3872344892748, 3448.706859916907, 3474.322507111113, 3482.1468740158666, 3516.3318969395814, 5682.119271512922, 5716.971415142929, 5717.571182161339, 5718.941995730787, 5723.284589426322, 5650.74571644276, 5709.914351284734, 5711.200431939521, 5720.628159169651, 5743.712814359286, 5219.295395121667, 5226.398964119127, 5247.86880327991, 5291.761870715808, 5328.333958255218, 5171.095058364771, 5332.973335133338, 5348.358197016363, 5384.3807962084275, 5395.379531353886, 5295.126304159809, 5314.840554783363, 5344.652597581165, 5391.029614669358, 5408.8377348679605, 4272.636825869805, 5328.200769884529, 5373.623846330719, 5389.003034438641, 5438.265834112423, 4796.688215265996, 5066.670666406671, 5218.843434696965, 5327.147656374961, 5339.893202135935, 5510.393856675762, 5542.39033706292, 5556.222128901916, 5573.888522229573, 5594.944050559483, 6217.87564248717, 6233.0, 6233.812985610439, 6285.0, 6292.716827742763, 5284.603654725873, 6186.536009799283, 6190.0405437157315, 6236.345183755686, 6265.028920517281, 4644.164050470914, 5923.289205295349, 5972.761089556369, 5991.310999235144, 5996.760129594811, 6267.467265282502, 6282.528810339243, 6303.873922521524, 6306.747730090837, 6309.652969086653, 6108.389161083856, 6127.111568822585, 6139.662318572432, 6148.647297594522, 6170.691465426711, 6235.657038862815, 6240.063990401476, 6250.749970001149, 6269.0, 6289.811305660837, 6172.660503672341, 6198.0702894566175, 6213.03697926822, 6221.160143380676, 6223.408776166257, 5981.850453738604, 6160.322364539897, 6209.068639704081, 6247.96876015617, 6257.655828929453, 4925.9014819704125, 4948.356713627278, 4957.7273249971595, 4985.351904252424, 4991.226359914146, 4340.978295108567, 4482.417285752831, 4483.75339356332, 4494.235979883428, 4523.344115103291, 5604.8318550443555, 5713.371529131792, 5792.247007888947, 5898.203742494794, 5922.585419020714, 6054.789042191579, 6143.511877689453, 6242.75028998844, 6251.937480625135, 6252.6873656317, 5787.074068149076, 5877.324107727581, 5919.526437884959, 5950.256217972754, 6000.789972350947, 4621.560951709582, 5429.158480435539, 5436.10304299794, 5476.91784623223, 5517.834464966058, 5225.425203227642, 6317.4630156437215, 6339.683015849281, 6352.237731472253, 6364.299927008025, 6100.572959892765, 6262.874742505033, 6279.340669229665, 6280.5289603279025, 6281.183446151971, 6020.247469066367, 6285.5600107992, 6304.684765761602, 6339.492840572835, 6442.581232219891, 6128.540359472968, 6148.016317389375, 6150.446459818638, 6151.96924015377, 6152.600080994721, 5142.357205349332, 6128.387161283836, 6251.937480625317, 6252.687365631791, 6268.843278917881, 5525.033119204204, 5593.636413633101, 5857.970710146422, 6155.1382806406245, 6183.721732522137, 6179.81460556184, 6198.473129783839, 6204.5967012144065, 6234.875302493834, 6239.282482514569, 4787.305840653155, 6010.7295171716505, 6089.786857459968, 6137.232845894263, 6165.0444181152, 6142.314611233224, 6192.690365481798, 6212.875742485216, 6221.3156552780065, 6234.906476402767, 6053.3946605339115, 6102.877942441038, 6108.816735497942, 6109.266887973472, 6138.324784273816], "value": 5697.914892160516, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.30853193922235, 522.7882707503461, 545.2557259340998, 601.6149664214903, 644.4972921121521, 39199.60800391995, 39930.401043984326, 39952.80023599885, 39972.0, 40095.398569021454, 41644.0, 42132.94667633311, 42255.52105676302, 42269.788651056704, 42341.788291058576, 9389.765255868606, 9559.187469065104, 9597.520123993807, 9607.087326703986, 9617.32678712487, 9469.337146399756, 9519.0, 9711.12599866012, 9714.0, 9754.609815607368, 9673.0, 9687.467189304585, 9700.805983880318, 9784.0, 9797.951010244939, 9678.70963871085, 9727.173190278834, 9727.27045471589, 9737.610495580167, 9781.755456113582, 9541.188998935098, 9612.038796120332, 9639.132478076937, 9643.180329671986, 9675.467849268287, 39983.40024899629, 39991.0, 40151.0, 40321.99195020118, 40927.38608920869, 2547.5159719653207, 3145.685431456859, 3231.418344697953, 3286.8849590264467, 3325.7671962962604, 26151.476970460673, 26291.605625915625, 26462.73537264622, 26778.732212678013, 26881.731182688312, 2702.108304259609, 2718.945621087587, 2741.4791189673897, 2769.4599553087155, 2922.2402175434327, 224820.0, 263763.0, 265360.6731966347, 265953.3404665948, 269750.0, 36695.0, 37499.4375084374, 61234.4691382719, 61542.0, 61727.38272617216, 31562.526562101586, 34076.82961585226, 36170.63829361673, 36212.45681314782, 36365.090872727866, 16304.918475407547, 16413.42553010663, 16440.342386304415, 16460.506184814476, 16476.505704828873, 12528.060395470377, 13155.67110822218, 13836.03147779666, 13878.0, 13988.790168147487, 8138.552379619177, 8294.419390642717, 8319.084900660922, 8349.33205343571, 8384.958075209706, 3439.071450708295, 3478.669526394988, 3491.511188433621, 3507.2810073935293, 3517.314123745845, 1028.2134167361974, 1050.298516403416, 1112.5827814569536, 1123.3035517978906, 1129.6046383765615, 26542.46915061688, 27502.17493475199, 30173.943911962975, 30884.84557577198, 31480.37039259248, 999.3853779925294, 1111.4276147783899, 1150.2925700694013, 1165.7959857024987, 1168.807146820782, 15566.766498502531, 15667.529974100795, 15968.600784980235, 16096.597585060234, 16380.918095409446, 539180.0, 646478.7676061684, 652013.0, 653127.0, 661730.0, 63262.0, 63731.681341593925, 63947.36052639414, 64118.038229426595, 64221.35778642247, 1283.4160456992047, 1458.5697219320325, 1481.4148411377662, 1481.4370539195038, 1487.620656732548, 30335.848320758254, 30445.543316850708, 31160.0, 31611.05166844998, 32156.356872861943, 58064.12903806362, 58424.707876462046, 58920.705396472746, 58944.115838261605, 59251.40748592459, 586682.0, 597265.0, 600224.0, 600322.0, 601540.0, 42344.36483452689, 43498.0, 43965.0, 44097.55902441063, 45259.54740452553, 48447.0, 48655.0, 48937.75531122321, 48981.51018489769, 50262.497375025785, 39271.0, 39420.40869386904, 40082.79751607456, 40359.39460908147, 40690.9827254321, 38979.0, 39303.41044884272, 39802.60197398105, 39866.80066599648, 39882.002949926486, 32525.67474325321, 38217.0, 38580.228395432496, 38984.83045508639, 39893.60106398899, 28156.71843281623, 31536.0, 32923.34153316968, 33110.00669979904, 33206.0, 20071.39629910934, 20965.790342096385, 23842.88078559596, 25840.8707956459, 25958.0], "value": 18804.859275076113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.1350814798557, 392.628965627482, 397.8508059477695, 400.8271973042404, 406.8087998640639, 14969.401223951056, 16496.505104846845, 20649.690254646193, 20842.583148337042, 21219.469513262196, 20353.28763493278, 20486.692699609517, 20606.896965515152, 20715.896420517915, 20995.95020248989, 5302.761375738101, 5781.653100813944, 6100.847478813043, 6102.298235702907, 6110.358412366704, 5981.461668449839, 5987.640741555499, 6022.705118399544, 6048.39516048396, 6086.056661217518, 5698.572607054467, 5875.059990401536, 5984.670843103628, 6005.2193214882, 6010.8497287567725, 5395.244665746799, 5802.245708057925, 5872.941270587304, 5938.168656388109, 6008.819735407923, 4980.651354405193, 5137.2037334213255, 5140.974295128538, 5262.631615786897, 5270.262163297141, 26682.19953401401, 27850.860745696344, 27933.301667458272, 28077.438451231064, 28383.86464541416, 2018.8687735297158, 2145.549434618732, 2180.716506854115, 2281.0761641535205, 2349.48311371498, 8298.170182981714, 10135.341202821794, 10225.284230103896, 13860.792088118686, 14830.629234269121, 1724.336130589732, 1735.9913200434044, 1755.3768412213608, 1759.6920538905767, 1770.2034084661825, 114030.85969140369, 117638.0, 118470.0, 120400.0, 120568.98577535544, 9936.552855121538, 12037.458314375874, 12258.325792081521, 12268.877311226952, 12327.137100402886, 14909.70180596382, 15210.08739475635, 15273.694526109415, 15366.385344586315, 15370.538883833502, 8963.282937364995, 9056.456612603264, 9064.274858011344, 9151.0, 9193.770155746026, 9080.591373388213, 9191.494467804201, 9220.769480763034, 9247.029061948533, 9306.06939306064, 4778.95221047792, 4791.257355109963, 4828.275758636162, 4852.44196917359, 4875.561199491992, 1985.2555291765589, 2040.734704488407, 2044.9079791409422, 2079.7504299483858, 2087.6242276390394, 915.789368445248, 934.9158575728217, 965.5992763003416, 971.1987610221596, 1028.4960369418932, 20483.897580512003, 21265.255716050175, 21269.255576054762, 21674.133034678438, 21919.232826850985, 955.5652178259014, 1006.4716024087413, 1042.968710938673, 1057.0486562094177, 1072.92489525734, 11396.14528910335, 11544.191906566628, 11599.942000289944, 11602.76794464106, 11669.35818529989, 597138.0, 604424.0, 635013.0, 635173.0, 636940.0, 109060.0, 127685.0, 130237.34881325722, 130884.0, 131252.0, 1028.8508166315992, 1031.968031968032, 1042.968710938673, 1043.2592859069994, 1044.9425281609585, 17311.653766924843, 18685.252589896252, 19034.714479282546, 19537.902310488356, 20492.282770102975, 49731.99999999855, 53114.46885531095, 53719.194212087634, 53795.73102134621, 54669.72665136649, 71054.64472677604, 71650.05924674193, 72080.27919720736, 75276.0, 77415.61292193503, 8127.75616731499, 8291.212334828182, 8344.457610255315, 8484.575771211292, 8548.529830859363, 12226.816597750863, 12803.423845927142, 12899.677508062374, 12957.676058098623, 13121.671958201121, 7497.550146991198, 9381.733465982277, 11238.494267757807, 11369.147313951322, 11804.881951180378, 7929.920700792918, 10935.83596246041, 11060.0, 11252.718682033015, 11468.426578670866, 10484.370937743759, 11046.613368532384, 11449.59926402572, 11534.307941523535, 11704.356260405759, 6837.829054273683, 7941.729323308474, 8298.958505207434, 8390.622421990904, 8550.187732165434, 7432.182459929511, 10416.0, 10836.187285953427, 11281.548738050386, 11572.6528204154], "value": 9691.722883683145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.4377420918738, 1589.2609936379586, 1829.5791967847395, 1939.3309308288635, 1995.3515107590038, 7807.141214466405, 8426.0, 8568.614412351444, 8787.780305492357, 8805.1194880512, 8561.315094792417, 8563.700270490534, 8588.098249683771, 8640.265577425911, 8679.91320086799, 2459.323685986359, 2468.5803413419717, 2489.0, 2494.887730052152, 2500.112460076665, 2441.6825812644333, 2504.8121390895662, 2504.9248522544353, 2508.7205525182108, 2515.9119430819924, 2462.0890270599903, 2462.3597864555168, 2467.814913881457, 2474.1958863369387, 2475.5667758142345, 2436.4639779248537, 2458.754124587536, 2498.6252062190633, 2501.137107697841, 2507.247825652297, 2451.460678650694, 2461.59383701689, 2470.234227389515, 2494.8004159667184, 2501.0871032073346, 8678.305735541142, 8755.518446485441, 8756.8686469703, 8775.78060548485, 8790.428622139605, 4614.215583350805, 5438.483344082306, 5529.695866727328, 5545.722713864331, 5558.749856256478, 3516.156122530574, 3552.342816578941, 3572.928541429183, 3618.3486972344967, 3687.3547129252274, 1032.2516175772505, 1059.6820953713932, 1060.0459586372249, 1061.0556604621893, 1061.4639606998494, 11433.028192603639, 11667.883321166764, 11673.357965311905, 11693.298402095816, 11724.765504689858, 4763.333133361332, 4810.573124984488, 4867.3915760529935, 4874.8781280467565, 4881.0, 9942.552585133542, 9966.750831229132, 10058.497075146213, 10096.596136154472, 10168.33905796121, 5431.293931788921, 5463.890722185534, 5465.59008074396, 5467.425920278393, 5471.097268950579, 4408.470983482002, 4767.332573439721, 4861.368022157097, 4877.219644856806, 4942.456329803718, 3871.8965094947803, 4082.081531655413, 4129.511993876342, 4174.83300667976, 4175.498940127204, 1244.7012716947868, 1353.3977380065776, 1357.395958798345, 1364.2632978191864, 1376.7315373502272, 869.9304055675531, 881.8897637795276, 898.8943599372808, 903.0112027330063, 943.5848226780195, 4471.731696098244, 4964.0, 5281.943611277759, 5531.723413829292, 6024.1566180734735, 875.9562021898879, 1035.8498017787379, 1046.4140081554353, 1061.304845326297, 1085.4138765066775, 3721.6650501455, 3840.5967373425947, 3869.28418242626, 3871.8644847430205, 3876.8836934891997, 10455.268131230892, 10714.142868570496, 10754.623588174376, 10859.945700271448, 10872.510737016853, 10472.214583906238, 10487.475626218658, 10501.474926253657, 10522.526486307981, 10601.84097238542, 7896.526208427513, 8073.838523229502, 8212.917870821335, 8213.260806527322, 8308.875366869503, 5645.237892884408, 5721.942780572141, 5730.111832665943, 5804.854878628068, 5836.416358364129, 9115.680951166678, 9127.361084724005, 9190.448573085636, 9226.492542910204, 9346.953265233631, 9337.299702522203, 9418.293627977791, 9495.572699228413, 9512.429254244491, 9529.714108576754, 6629.6685165740555, 7169.820754481179, 7394.741184058532, 7418.740344087931, 7548.094228692592, 8137.6338064788415, 8213.671453141807, 8246.3815213858, 8296.087430382533, 8307.376946728898, 7265.382442492447, 7508.061492313461, 7508.662110204945, 7533.2466753324225, 7702.114256860309, 7532.510386824839, 7581.241875812595, 7630.16068232505, 7691.923080769121, 7735.0, 6833.58998460094, 7040.1551813780625, 7060.6116663584, 7074.009638650696, 7083.327083927019, 4981.850544483671, 5021.974890125526, 5113.846584602468, 5164.586833053271, 5172.7413629319135, 4949.109160351171, 4969.931464735099, 5215.791368345223, 5269.920951185659, 5377.758000890048], "value": 4630.986486282731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.6961725595197, 327.73780975219825, 333.68466798875056, 334.1912571576784, 336.71547542326755, 6714.294999025105, 6723.529352945296, 6726.091977583019, 6739.427148692367, 6756.662166891654, 6665.433438157763, 6695.430888374494, 6699.631520266384, 6710.765123220676, 6776.491763117761, 4727.90544189116, 4762.380890484233, 4870.707757534559, 4876.2685597160535, 4877.34155888954, 4792.712437253758, 4863.781129849165, 4874.317595536628, 4877.487863774306, 4883.023395320933, 4833.105875413043, 4871.366722326093, 4882.511748825124, 4883.12103821312, 4898.902021959559, 4888.97555512222, 4891.706497610155, 4894.755262236874, 4896.4124305083615, 4933.70397776131, 4197.496300443936, 4198.5591512891315, 4207.537170911197, 4208.747475151501, 4218.135282267128, 4675.625949923998, 5474.315710536183, 5604.075327570946, 6506.186726659168, 6507.121538592277, 5754.741036653319, 5797.768089276424, 5799.3040835100055, 5828.97085514574, 5833.533317334603, 7183.281671832827, 7298.233685463003, 7299.343059124704, 7301.671424785897, 7308.598027108507, 4663.953360466386, 4673.135469938038, 4676.275177347517, 4704.623630109617, 4707.811687532495, 7648.464607477479, 7711.768646940544, 7731.381489480828, 7733.45865789395, 7740.148583655793, 4560.931586026212, 4687.882802929886, 4724.291356296514, 4744.430668319824, 4752.691075080109, 4770.570648641569, 4812.735299558488, 4813.7593120344545, 4817.662763606514, 4822.252550854623, 5794.1308803679785, 5803.448672376117, 5807.593468457252, 5814.244148260699, 5827.59206855516, 7325.047743793273, 7328.706851725978, 7341.669624866894, 7346.485745997836, 7348.559086454829, 1654.7766051582882, 1678.5048410718866, 1681.8906771059842, 1683.8905471144337, 1685.8398452147026, 1017.8574999500075, 1050.3855244681947, 1064.6380230721593, 1064.8455973883742, 1077.5528155815405, 6708.19501659804, 6802.55783374089, 6813.625250611165, 6816.284290149562, 6824.897626535606, 7331.8533629328185, 7633.122190948112, 7634.6946122155605, 7646.273604007611, 7656.349210317074, 6080.300765411946, 6286.497080233662, 6610.8347291318105, 6775.62734049632, 6802.65986700663, 4009.498812648419, 4036.1524079942956, 4052.149048699806, 4052.574479679591, 4059.9594004060173, 7245.710171593183, 7999.520028798291, 8012.799680008046, 8017.438779285394, 8030.0, 7122.60825654594, 7613.42899282556, 7926.564038977912, 7942.205779422012, 7942.483738556975, 7238.638068096574, 7946.284834364935, 8003.719869804529, 8011.599420028975, 8017.518948863087, 7652.38780897538, 7677.577733224726, 7692.230776922487, 7707.267809558083, 7751.302382785577, 6006.579539432198, 6178.41305076017, 7503.1746507883, 7538.434617403607, 7578.772636820904, 6393.648349340645, 6731.091302674272, 7756.612169391621, 7775.650095745818, 7999.160088190889, 4118.608732170439, 4528.2075636762775, 4673.719576825401, 4683.695559788603, 4747.691400058985, 7591.43064270193, 7594.696212151452, 7601.0878694557005, 7605.935169076223, 7609.315161635479, 7282.053333066668, 7297.708091676273, 7312.780616581511, 7316.487845850739, 7326.120865496175, 5598.3282006159525, 7055.717771289091, 7311.122665279988, 7324.230955749783, 7372.225916278928, 7157.391621712213, 7158.498905076594, 7162.068931038932, 7172.641367931478, 7180.748673796392, 7312.341889229995, 7321.194668586558, 7335.303146201102, 7338.853222935405, 7364.742234021784, 6920.446364290945, 7135.714571417293, 7137.785866424016, 7188.794555185066, 7191.388731957633], "value": 5255.784387314102, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.4143190435985, 723.0130871360594, 734.0544572920555, 744.5569885917877, 770.653206057274, 14622.049566778152, 14648.633784155401, 14769.261536923152, 14960.476383326586, 14986.850131498695, 13424.463021479129, 13695.657608559792, 13920.373583188755, 13957.371918263678, 13972.930135349308, 719.8164468060637, 903.7424334064793, 910.1308250620641, 922.9815403691956, 929.330881765128, 905.41148253635, 905.950172740499, 909.8999110097884, 913.0777914306532, 915.2312057871387, 938.699616122841, 974.1768205866032, 974.5176137811793, 975.3221511049824, 980.1619615228983, 961.0053594529663, 964.4984608003869, 966.8839739231302, 969.016448304971, 969.5636963366513, 685.9657017149123, 709.8473828126926, 712.6365553567666, 723.8552289542113, 728.2208037399984, 14432.422703091865, 14456.855431445656, 14679.706405871822, 14794.260286985607, 14836.10983341003, 12737.426815793311, 12781.0, 13001.544945926942, 13004.089713720043, 13112.541061062913, 9858.9507052465, 9967.80064398708, 10128.291019628628, 10150.847737283946, 10164.796704065951, 3468.8265586720813, 3543.557055368079, 3544.131687736495, 3563.322968635951, 3580.230250496156, 56226.59433514154, 73223.0, 79823.8026429604, 79847.0, 79945.60027199826, 9186.63253469867, 9323.860142097874, 9383.155516003457, 9418.387804792666, 9461.479618620904, 20590.27934022302, 20632.896835516025, 20821.583568328548, 20859.0, 20889.686654700192, 6747.0, 6911.7926462206215, 7209.783706488814, 7433.814154646069, 7493.850122997618, 4013.3578627420056, 4031.113155105872, 4035.3139966205817, 4036.3138266494766, 4057.8985525361513, 3675.687566556819, 3755.8497660093835, 3756.042209236627, 3758.9060273492832, 3768.7927164005696, 577.711144427786, 664.3655309179721, 672.9932700673029, 674.436845234235, 677.6273049822578, 484.36548121960345, 580.1413907417046, 603.3122240645667, 610.2128254551578, 614.7633161232868, 6841.418479429205, 7841.882371764428, 8305.127961564069, 8306.70926517569, 8387.454815436977, 474.6795912758874, 487.9243717223836, 592.8310431526994, 602.1509671363357, 634.9587276826992, 3604.3151801157696, 3675.5956844747056, 3887.455756194135, 3943.132510847609, 4021.235965166609, 71034.64482677553, 77738.0, 86064.0, 91525.54237228772, 94505.0, 75020.0, 76683.0, 76778.23221767823, 77384.22615773883, 77479.0, 66487.7158781339, 66541.66729166324, 67564.0, 67940.0, 68438.0, 8386.790330241793, 8425.031121421114, 8607.784805379915, 8661.04728479854, 8873.73378798637, 45337.0, 46796.532034679214, 46923.296150557086, 48228.0, 49727.75136124441, 63117.0, 65701.67149164414, 67024.3297567018, 67381.66309168423, 68611.0, 10930.945345273223, 11223.38271395081, 11225.66323010311, 11277.548898043986, 11296.265742726697, 11830.17188796776, 11862.762744744885, 11886.702832428913, 12178.0, 12396.628101156979, 11402.82895756581, 11539.307641541534, 11577.131715121232, 11726.237794543327, 11878.227915185485, 11049.834252486058, 11344.319340839576, 11380.203385762918, 11422.657320280072, 11468.4265786712, 11049.392283424168, 11126.054285385833, 11235.831462527905, 11313.377764223047, 11614.245074070159, 9572.712818615453, 9634.181094606756, 9653.613855445703, 9718.951405242928, 9882.654107106217, 9462.810743784948, 9850.211983041196, 9892.307538472525, 9938.304318697621, 9951.800963980824], "value": 6848.358788538455, "unit": "score"}], "name": "dromaeo_css", "value": 7465.402856777151, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 121.0, 108.0, 117.0, 119.0, 117.0, 115.0, 167.0, 121.0, 118.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 126.0, 158.0, 154.0, 122.0, 137.0, 138.0, 125.0, 170.0, 123.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 196.0, 200.0, 197.0, 197.0, 202.0, 200.0, 199.0, 190.0], "value": 197.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 76.0, 160.0, 82.0, 84.0, 57.0, 64.0, 119.0, 58.0], "value": 85.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 127.0, 77.0, 80.0, 82.0, 119.0, 81.0, 80.0, 122.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 88.0, 90.0, 87.0, 90.0, 87.0, 88.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 104.0, 148.0, 117.0, 148.0, 147.0, 94.0, 96.0, 95.0, 95.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 90.0, 91.0, 92.0, 100.0, 143.0, 133.0, 91.0, 101.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 66.0, 65.0, 62.0, 65.0, 65.0, 80.0, 61.0, 59.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 67.0, 85.0, 108.0, 101.0, 82.0, 68.0, 69.0, 70.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 92.0, 103.0, 84.0, 79.0, 82.0, 85.0, 105.0, 82.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 127.0, 127.0, 131.0, 122.0, 149.0, 149.0, 153.0, 145.0, 165.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 178.0, 165.0, 172.0, 184.0, 171.0, 170.0, 166.0, 167.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 71.0, 66.0, 69.0, 67.0, 75.0, 91.0, 95.0, 81.0], "value": 75.2, "unit": "score"}], "name": "kraken", "value": 1559.1, "alertThreshold": 2.0}]} 04:37:42 INFO - Return code: 0 04:37:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:37:42 ERROR - # TBPL SUCCESS # 04:37:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:37:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:37:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:37:42 INFO - 2016-06-01 04:37:42.427 screenresolution[1685:10635] starting screenresolution argv=screenresolution get 04:37:42 INFO - 2016-06-01 04:37:42.430 screenresolution[1685:10635] Display 0: 1600x1200x32@60 04:37:42 INFO - 2016-06-01 04:37:42.436 screenresolution[1686:10638] starting screenresolution argv=screenresolution list 04:37:42 INFO - Available Modes on Display 0 04:37:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:37:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:37:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:37:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:37:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:37:42 INFO - Intel Iris: 04:37:42 INFO - Chipset Model: Intel Iris 04:37:42 INFO - Type: GPU 04:37:42 INFO - Bus: Built-In 04:37:42 INFO - VRAM (Dynamic, Max): 1536 MB 04:37:42 INFO - Vendor: Intel (0x8086) 04:37:42 INFO - Device ID: 0x0a2e 04:37:42 INFO - Revision ID: 0x0009 04:37:42 INFO - Displays: 04:37:42 INFO - Display: 04:37:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:37:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:37:42 INFO - Display Serial Number: 1600x1200 60 04:37:42 INFO - Main Display: Yes 04:37:42 INFO - Mirror: Off 04:37:42 INFO - Online: Yes 04:37:42 INFO - Rotation: Supported 04:37:42 INFO - Return code: 0 04:37:42 INFO - Running post-action listener: _resource_record_post_action 04:37:42 INFO - Running post-run listener: _resource_record_post_run 04:37:43 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 36857344; Write bytes: 655499264; Read time: 246; Write time: 1855 04:37:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342974976; Write bytes: 335796224; Read time: 26609; Write time: 668 04:37:43 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 35902976; Write bytes: 299317248; Read time: 233; Write time: 1128 04:37:43 INFO - Running post-run listener: _upload_blobber_files 04:37:43 INFO - Blob upload gear active. 04:37:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:37:43 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 04:37:43 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'] 04:37:43 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 04:37:44 INFO - (blobuploader) - INFO - Open directory for files ... 04:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:37:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:45 INFO - (blobuploader) - INFO - Done attempting. 04:37:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:37:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:46 INFO - (blobuploader) - INFO - Done attempting. 04:37:46 INFO - (blobuploader) - INFO - Iteration through files over. 04:37:46 INFO - Return code: 0 04:37:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:37:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:37:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/806d7c5e749f4170b81890311f82bf63e8c3add0ae989627211c8a991a5de16beb3e538f26f63c6f359c758c1e26bedbf23b6f11790b30f31cd139c2e3ea1b5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a110fbe1315f3a038c31bb5488c8e239914223bae123ddc50a7f67a44ba85f13e3b5e653bca96d60b5869284f3809700fe93f16c1b9ce78bc69f0a891a02da"} 04:37:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:37:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:37:46 INFO - Contents: 04:37:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/806d7c5e749f4170b81890311f82bf63e8c3add0ae989627211c8a991a5de16beb3e538f26f63c6f359c758c1e26bedbf23b6f11790b30f31cd139c2e3ea1b5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a110fbe1315f3a038c31bb5488c8e239914223bae123ddc50a7f67a44ba85f13e3b5e653bca96d60b5869284f3809700fe93f16c1b9ce78bc69f0a891a02da"} 04:37:46 INFO - Running post-run listener: copy_logs_to_upload_dir 04:37:46 INFO - Copying logs to upload dir... 04:37:46 INFO - mkdir: /builds/slave/test/build/upload/logs 04:37:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.914042 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-01 04:37:46.452407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 04:37:46.455964) ========= 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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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/806d7c5e749f4170b81890311f82bf63e8c3add0ae989627211c8a991a5de16beb3e538f26f63c6f359c758c1e26bedbf23b6f11790b30f31cd139c2e3ea1b5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a110fbe1315f3a038c31bb5488c8e239914223bae123ddc50a7f67a44ba85f13e3b5e653bca96d60b5869284f3809700fe93f16c1b9ce78bc69f0a891a02da"} build_url:https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/ECW-SRrBTA-ub3MjUvs1kQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/806d7c5e749f4170b81890311f82bf63e8c3add0ae989627211c8a991a5de16beb3e538f26f63c6f359c758c1e26bedbf23b6f11790b30f31cd139c2e3ea1b5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a110fbe1315f3a038c31bb5488c8e239914223bae123ddc50a7f67a44ba85f13e3b5e653bca96d60b5869284f3809700fe93f16c1b9ce78bc69f0a891a02da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 04:37:46.486684) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 04:37:46.486981) ========= 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.tj5o8GBjrb/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.IRcPGhnmdP/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.005075 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 04:37:46.535134) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 04:37:46.535427) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 04:37:46.535757) ========= ========= Total master_lag: 0.16 =========