builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0017 starttime: 1461888847.6 results: success (0) buildid: 20160428152347 builduid: 60ba72def4614e3796b976b8310e0068 revision: 91cd25848c032866d7cca41df8d074795b594ea7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.604682) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.605155) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.605467) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.625812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.626195) ========= 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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-28 17:14:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.05M=0.001s 2016-04-28 17:14:07 (8.05 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106239 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.750119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.752739) ========= 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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.019919 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:07.803253) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 17:14:07.803624) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 91cd25848c032866d7cca41df8d074795b594ea7 --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 91cd25848c032866d7cca41df8d074795b594ea7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ul1UWhCCWI/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.E4zalcdnFf/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-28 17:14:07,878 truncating revision to first 12 chars 2016-04-28 17:14:07,878 Setting DEBUG logging. 2016-04-28 17:14:07,878 attempt 1/10 2016-04-28 17:14:07,878 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/91cd25848c03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 17:14:09,034 unpacking tar archive at: mozilla-aurora-91cd25848c03/testing/mozharness/ program finished with exit code 0 elapsedTime=1.406605 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 17:14:09.230190) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:09.230515) ========= script_repo_revision: 91cd25848c032866d7cca41df8d074795b594ea7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:09.230893) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:09.231215) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 17:14:09.248685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-28 17:14:09.248988) ========= /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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:09 INFO - MultiFileLogger online at 20160428 17:14:09 in /builds/slave/test 17:14:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:14:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:14:09 INFO - {'append_to_log': False, 17:14:09 INFO - 'base_work_dir': '/builds/slave/test', 17:14:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:14:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:14:09 INFO - 'branch': 'Mozilla-Aurora', 17:14:09 INFO - 'buildbot_json_path': 'buildprops.json', 17:14:09 INFO - 'config_files': ('talos/mac_config.py',), 17:14:09 INFO - 'default_actions': ('clobber', 17:14:09 INFO - 'read-buildbot-config', 17:14:09 INFO - 'download-and-extract', 17:14:09 INFO - 'populate-webroot', 17:14:09 INFO - 'create-virtualenv', 17:14:09 INFO - 'install', 17:14:09 INFO - 'run-tests'), 17:14:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:14:09 INFO - 'download_minidump_stackwalk': True, 17:14:09 INFO - 'download_symbols': 'ondemand', 17:14:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:14:09 INFO - 'tooltool.py': '/tools/tooltool.py', 17:14:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:14:09 INFO - '/tools/misc-python/virtualenv.py')}, 17:14:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:14:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:14:09 INFO - 'installer_path': 'installer.exe', 17:14:09 INFO - 'log_level': 'info', 17:14:09 INFO - 'log_name': 'talos', 17:14:09 INFO - 'log_to_console': True, 17:14:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:14:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:14:09 INFO - 'opt_config_files': (), 17:14:09 INFO - 'pip_index': False, 17:14:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:14:09 INFO - 'cmd': ('bash', 17:14:09 INFO - '-c', 17:14:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:14:09 INFO - 'enabled': True, 17:14:09 INFO - 'halt_on_failure': False, 17:14:09 INFO - 'name': 'check_screen_resolution'},), 17:14:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:14:09 INFO - 'cmd': ('bash', 17:14:09 INFO - '-c', 17:14:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:14:09 INFO - 'enabled': True, 17:14:09 INFO - 'halt_on_failure': False, 17:14:09 INFO - 'name': 'check_screen_resolution'},), 17:14:09 INFO - 'run_cmd_checks_enabled': True, 17:14:09 INFO - 'sps_profile': False, 17:14:09 INFO - 'sps_profile_interval': 0, 17:14:09 INFO - 'suite': 'dromaeojs-e10s', 17:14:09 INFO - 'system_bits': '32', 17:14:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:14:09 INFO - 'title': 't-yosemite-r7-0017', 17:14:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:14:09 INFO - 'use_talos_json': True, 17:14:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:14:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:14:09 INFO - 'work_dir': 'build'} 17:14:09 INFO - ##### 17:14:09 INFO - ##### Running clobber step. 17:14:09 INFO - ##### 17:14:09 INFO - Running pre-action listener: _resource_record_pre_action 17:14:09 INFO - Running main action method: clobber 17:14:09 INFO - rmtree: /builds/slave/test/build 17:14:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:14:10 INFO - Running post-action listener: _resource_record_post_action 17:14:10 INFO - ##### 17:14:10 INFO - ##### Running read-buildbot-config step. 17:14:10 INFO - ##### 17:14:10 INFO - Running pre-action listener: _resource_record_pre_action 17:14:10 INFO - Running main action method: read_buildbot_config 17:14:10 INFO - Using buildbot properties: 17:14:10 INFO - { 17:14:10 INFO - "project": "", 17:14:10 INFO - "product": "firefox", 17:14:10 INFO - "script_repo_revision": "production", 17:14:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 17:14:10 INFO - "repository": "", 17:14:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 17:14:10 INFO - "buildid": "20160428152347", 17:14:10 INFO - "pgo_build": "False", 17:14:10 INFO - "basedir": "/builds/slave/test", 17:14:10 INFO - "buildnumber": 272, 17:14:10 INFO - "slavename": "t-yosemite-r7-0017", 17:14:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:14:10 INFO - "platform": "yosemite_r7", 17:14:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:14:10 INFO - "branch": "mozilla-aurora", 17:14:10 INFO - "repo_path": "releases/mozilla-aurora", 17:14:10 INFO - "revision": "91cd25848c032866d7cca41df8d074795b594ea7", 17:14:10 INFO - "stage_platform": "macosx64", 17:14:10 INFO - "builduid": "60ba72def4614e3796b976b8310e0068", 17:14:10 INFO - "slavebuilddir": "test" 17:14:10 INFO - } 17:14:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 17:14:10 INFO - Running post-action listener: _resource_record_post_action 17:14:10 INFO - ##### 17:14:10 INFO - ##### Running download-and-extract step. 17:14:10 INFO - ##### 17:14:10 INFO - Running pre-action listener: _resource_record_pre_action 17:14:10 INFO - Running main action method: download_and_extract 17:14:10 INFO - mkdir: /builds/slave/test/build/tests 17:14: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')]} 17:14:10 INFO - https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:14:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:14:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:14:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:12 INFO - Downloaded 1199 bytes. 17:14:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:14:12 INFO - Using the following test package requirements: 17:14:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:14:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 17:14:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 17:14:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'jsshell-mac.zip'], 17:14:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 17:14:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:14:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 17:14:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 17:14:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 17:14:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:14:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 17:14:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:14: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')]} 17:14:12 INFO - https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:14:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:14:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:14:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:13 INFO - Downloaded 18110509 bytes. 17:14:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:14:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:14:16 INFO - Return code: 0 17:14:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 17:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:16 INFO - https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:14:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:14:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:14:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:18 INFO - Downloaded 18110509 bytes. 17:14:18 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'] 17:14:18 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 17:14:21 INFO - Return code: 0 17:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:21 INFO - https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:14:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:14:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:14:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:22 INFO - Downloaded 10840145 bytes. 17:14:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:14:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:14:22 INFO - Return code: 0 17:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:22 INFO - https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 17:14:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:14:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:14:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 17:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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 17:14:27 INFO - Downloaded 114075154 bytes. 17:14:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:14:27 INFO - mkdir: /builds/slave/test/properties 17:14:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:14:27 INFO - Writing to file /builds/slave/test/properties/build_url 17:14:27 INFO - Contents: 17:14:27 INFO - build_url:https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:14:28 INFO - Running post-action listener: _resource_record_post_action 17:14:28 INFO - Running post-action listener: set_extra_try_arguments 17:14:28 INFO - ##### 17:14:28 INFO - ##### Running populate-webroot step. 17:14:28 INFO - ##### 17:14:28 INFO - Running pre-action listener: _resource_record_pre_action 17:14:28 INFO - Running main action method: populate_webroot 17:14:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:14:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:14:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:14:28 INFO - '--tppagecycles', 17:14:28 INFO - '7'], 17:14:28 INFO - 'tests': ['tsvgm']}}, 17:14:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:14:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:14:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:14:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:14:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['damp', 'tps']}, 17:14:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'tests': ['damp', 'tps']}, 17:14:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['dromaeo_dom']}, 17:14:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:14:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['a11yr', 17:14:28 INFO - 'ts_paint', 17:14:28 INFO - 'tpaint', 17:14:28 INFO - 'sessionrestore', 17:14:28 INFO - 'sessionrestore_no_auto_restore', 17:14:28 INFO - 'tabpaint']}, 17:14:28 INFO - 'other-e10s': {'tests': ['a11yr', 17:14:28 INFO - 'ts_paint', 17:14:28 INFO - 'tpaint', 17:14:28 INFO - 'sessionrestore', 17:14:28 INFO - 'sessionrestore_no_auto_restore', 17:14:28 INFO - 'tabpaint']}, 17:14:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:14:28 INFO - 'ts_paint', 17:14:28 INFO - 'tpaint', 17:14:28 INFO - 'sessionrestore', 17:14:28 INFO - 'sessionrestore_no_auto_restore', 17:14:28 INFO - 'tabpaint']}, 17:14:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:14:28 INFO - 'ts_paint', 17:14:28 INFO - 'tpaint', 17:14:28 INFO - 'sessionrestore', 17:14:28 INFO - 'sessionrestore_no_auto_restore', 17:14:28 INFO - 'tabpaint']}, 17:14:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['a11yr', 17:14:28 INFO - 'ts_paint', 17:14:28 INFO - 'tpaint', 17:14:28 INFO - 'sessionrestore', 17:14:28 INFO - 'sessionrestore_no_auto_restore', 17:14:28 INFO - 'tabpaint']}, 17:14:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['a11yr', 17:14:28 INFO - 'ts_paint', 17:14:28 INFO - 'tpaint', 17:14:28 INFO - 'sessionrestore', 17:14:28 INFO - 'sessionrestore_no_auto_restore', 17:14:28 INFO - 'tabpaint']}, 17:14:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['tsvgx', 17:14:28 INFO - 'tsvgr_opacity', 17:14:28 INFO - 'tart', 17:14:28 INFO - 'tscrollx', 17:14:28 INFO - 'cart']}, 17:14:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:14:28 INFO - 'tsvgr_opacity', 17:14:28 INFO - 'tart', 17:14:28 INFO - 'tscrollx', 17:14:28 INFO - 'cart']}, 17:14:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'talos_options': ['--disable-e10s'], 17:14:28 INFO - 'tests': ['tp5o']}, 17:14:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'tests': ['tp5o']}, 17:14:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'talos_options': ['--disable-e10s', 17:14:28 INFO - '--xperf_path', 17:14:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:28 INFO - 'tests': ['tp5n']}, 17:14:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:28 INFO - 'talos_options': ['--xperf_path', 17:14:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:28 INFO - 'tests': ['tp5n']}}, 17:14:28 INFO - 'talos.zip': {'path': '', 17:14:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:14:28 INFO - Running post-action listener: _resource_record_post_action 17:14:28 INFO - ##### 17:14:28 INFO - ##### Running create-virtualenv step. 17:14:28 INFO - ##### 17:14:28 INFO - Running pre-action listener: _resource_record_pre_action 17:14:28 INFO - Running main action method: create_virtualenv 17:14:28 INFO - Creating virtualenv /builds/slave/test/build/venv 17:14:28 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 17:14:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:14:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:14:28 INFO - Using real prefix '/tools/python27' 17:14:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:14:29 INFO - Installing distribute.............................................................................................................................................................................................done. 17:14:32 INFO - Installing pip.................done. 17:14:32 INFO - Return code: 0 17:14:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:14:32 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')]} 17:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:32 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')]} 17:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:32 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:32 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 17:14:32 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 17:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:32 INFO - 'HOME': '/Users/cltbld', 17:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:32 INFO - 'LOGNAME': 'cltbld', 17:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:32 INFO - 'MOZ_NO_REMOTE': '1', 17:14:32 INFO - 'NO_EM_RESTART': '1', 17:14:32 INFO - 'PAGER': '/bin/cat', 17:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:32 INFO - 'PWD': '/builds/slave/test', 17:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:32 INFO - 'SHELL': '/bin/bash', 17:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:32 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:32 INFO - 'USER': 'cltbld', 17:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:32 INFO - 'XPC_FLAGS': '0x0', 17:14:32 INFO - 'XPC_SERVICE_NAME': '0', 17:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:32 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 17:14:32 INFO - Cleaning up... 17:14:32 INFO - Return code: 0 17:14:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:14:32 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')]} 17:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:32 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')]} 17:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:32 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:32 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 17:14:32 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 17:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:32 INFO - 'HOME': '/Users/cltbld', 17:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:32 INFO - 'LOGNAME': 'cltbld', 17:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:32 INFO - 'MOZ_NO_REMOTE': '1', 17:14:32 INFO - 'NO_EM_RESTART': '1', 17:14:32 INFO - 'PAGER': '/bin/cat', 17:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:32 INFO - 'PWD': '/builds/slave/test', 17:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:32 INFO - 'SHELL': '/bin/bash', 17:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:32 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:32 INFO - 'USER': 'cltbld', 17:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:32 INFO - 'XPC_FLAGS': '0x0', 17:14:32 INFO - 'XPC_SERVICE_NAME': '0', 17:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:32 INFO - Downloading/unpacking psutil>=0.7.1 17:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:35 INFO - Installing collected packages: psutil 17:14:35 INFO - Running setup.py install for psutil 17:14:35 INFO - building 'psutil._psutil_osx' extension 17:14:35 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 17:14:36 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 17:14:36 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 17:14:36 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 17:14:36 INFO - building 'psutil._psutil_posix' extension 17:14:36 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 17:14:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:14:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:14:36 INFO - ^ 17:14:36 INFO - 1 warning generated. 17:14:36 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 17:14:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:36 INFO - Successfully installed psutil 17:14:36 INFO - Cleaning up... 17:14:36 INFO - Return code: 0 17:14:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:36 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:36 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 17:14:36 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 17:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:36 INFO - 'HOME': '/Users/cltbld', 17:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:36 INFO - 'LOGNAME': 'cltbld', 17:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:36 INFO - 'MOZ_NO_REMOTE': '1', 17:14:36 INFO - 'NO_EM_RESTART': '1', 17:14:36 INFO - 'PAGER': '/bin/cat', 17:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:36 INFO - 'PWD': '/builds/slave/test', 17:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:36 INFO - 'SHELL': '/bin/bash', 17:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:36 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:36 INFO - 'USER': 'cltbld', 17:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:36 INFO - 'XPC_FLAGS': '0x0', 17:14:36 INFO - 'XPC_SERVICE_NAME': '0', 17:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:14: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 17:14: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 17:14: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 17:14: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 17:14: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 17:14: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 17:14:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:14:39 INFO - Installing collected packages: mozsystemmonitor 17:14:39 INFO - Running setup.py install for mozsystemmonitor 17:14:39 INFO - Successfully installed mozsystemmonitor 17:14:39 INFO - Cleaning up... 17:14:39 INFO - Return code: 0 17:14:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:14:39 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')]} 17:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:39 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')]} 17:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:39 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:39 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 17:14:39 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 17:14:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:39 INFO - 'HOME': '/Users/cltbld', 17:14:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:39 INFO - 'LOGNAME': 'cltbld', 17:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:39 INFO - 'MOZ_NO_REMOTE': '1', 17:14:39 INFO - 'NO_EM_RESTART': '1', 17:14:39 INFO - 'PAGER': '/bin/cat', 17:14:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:39 INFO - 'PWD': '/builds/slave/test', 17:14:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:39 INFO - 'SHELL': '/bin/bash', 17:14:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:39 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:39 INFO - 'USER': 'cltbld', 17:14:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:39 INFO - 'XPC_FLAGS': '0x0', 17:14:39 INFO - 'XPC_SERVICE_NAME': '0', 17:14:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:39 INFO - Downloading/unpacking blobuploader==1.2.4 17:14:39 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 17:14:39 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 17:14:39 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 17:14:39 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 17:14:39 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 17:14:39 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 17:14:42 INFO - Downloading blobuploader-1.2.4.tar.gz 17:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:14:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:14:42 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 17:14:42 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 17:14:42 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 17:14:42 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 17:14:42 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 17:14:42 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 17:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:14:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:14:43 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 17:14:43 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 17:14:43 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 17:14:43 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 17:14:43 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 17:14:43 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 17:14:43 INFO - Downloading docopt-0.6.1.tar.gz 17:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:14:43 INFO - Installing collected packages: blobuploader, requests, docopt 17:14:43 INFO - Running setup.py install for blobuploader 17:14:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:14:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:14:43 INFO - Running setup.py install for requests 17:14:44 INFO - Running setup.py install for docopt 17:14:44 INFO - Successfully installed blobuploader requests docopt 17:14:44 INFO - Cleaning up... 17:14:44 INFO - Return code: 0 17:14:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:44 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:44 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 17:14:44 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 17:14:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:44 INFO - 'HOME': '/Users/cltbld', 17:14:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:44 INFO - 'LOGNAME': 'cltbld', 17:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:44 INFO - 'MOZ_NO_REMOTE': '1', 17:14:44 INFO - 'NO_EM_RESTART': '1', 17:14:44 INFO - 'PAGER': '/bin/cat', 17:14:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:44 INFO - 'PWD': '/builds/slave/test', 17:14:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:44 INFO - 'SHELL': '/bin/bash', 17:14:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:44 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:44 INFO - 'USER': 'cltbld', 17:14:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:44 INFO - 'XPC_FLAGS': '0x0', 17:14:44 INFO - 'XPC_SERVICE_NAME': '0', 17:14:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:44 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Ul1gMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:44 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Q_S1qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:44 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-lsZG_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:44 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-pvCXfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-RCRXnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-1DtW8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-zA7TBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-_vA2MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-bTK5E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-AJ4S79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-7NNkDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-d48WXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:45 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-PdLJfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-i7kTHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-XPsILF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-mmG0Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-SB8Vxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:14:46 INFO - Running setup.py install for manifestparser 17:14:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:14:46 INFO - Running setup.py install for mozcrash 17:14:46 INFO - Running setup.py install for mozdebug 17:14:46 INFO - Running setup.py install for mozdevice 17:14:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for mozfile 17:14:47 INFO - Running setup.py install for mozhttpd 17:14:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for mozinfo 17:14:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for mozInstall 17:14:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for mozleak 17:14:47 INFO - Running setup.py install for mozlog 17:14:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:14:47 INFO - Running setup.py install for moznetwork 17:14:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:14:48 INFO - Running setup.py install for mozprocess 17:14:48 INFO - Running setup.py install for mozprofile 17:14:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:14:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:14:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:14:48 INFO - Running setup.py install for mozrunner 17:14:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:14:48 INFO - Running setup.py install for mozscreenshot 17:14:48 INFO - Running setup.py install for moztest 17:14:48 INFO - Running setup.py install for mozversion 17:14:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:14:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:14:49 INFO - Cleaning up... 17:14:49 INFO - Return code: 0 17:14:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14: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')]} 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14: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')]} 17:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:49 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:49 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 17:14:49 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 17:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:49 INFO - 'HOME': '/Users/cltbld', 17:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:49 INFO - 'LOGNAME': 'cltbld', 17:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:49 INFO - 'MOZ_NO_REMOTE': '1', 17:14:49 INFO - 'NO_EM_RESTART': '1', 17:14:49 INFO - 'PAGER': '/bin/cat', 17:14:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:49 INFO - 'PWD': '/builds/slave/test', 17:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:49 INFO - 'SHELL': '/bin/bash', 17:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:49 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:49 INFO - 'USER': 'cltbld', 17:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:49 INFO - 'XPC_FLAGS': '0x0', 17:14:49 INFO - 'XPC_SERVICE_NAME': '0', 17:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-u1pMmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:49 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)) 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-f0ee90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:49 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)) 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-uT0qYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:49 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)) 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-X5Nd_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:49 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)) 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-lLScNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:49 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)) 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-u9NgEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:49 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)) 17:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:49 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-BsBqrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-XUjFAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-pe_icM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-XzSWDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-oRDhJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-VxuJm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-BTexqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-dKsTvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-7PhIfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:50 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MiFMvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:50 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)) 17:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:51 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-RUbmLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:51 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)) 17:14:51 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)) 17:14:51 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)) 17:14:51 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)) 17:14:51 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)) 17:14:51 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)) 17:14:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:51 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 17:14:51 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 17:14:51 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 17:14:51 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 17:14:51 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 17:14:51 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 17:14:53 INFO - Downloading blessings-1.6.tar.gz 17:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:14:53 INFO - Installing collected packages: blessings 17:14:53 INFO - Running setup.py install for blessings 17:14:53 INFO - Successfully installed blessings 17:14:53 INFO - Cleaning up... 17:14:53 INFO - Return code: 0 17:14:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:54 INFO - Reading from file tmpfile_stdout 17:14:54 INFO - Current package versions: 17:14:54 INFO - blessings == 1.6 17:14:54 INFO - blobuploader == 1.2.4 17:14:54 INFO - docopt == 0.6.1 17:14:54 INFO - manifestparser == 1.1 17:14:54 INFO - mozInstall == 1.12 17:14:54 INFO - mozcrash == 0.17 17:14:54 INFO - mozdebug == 0.1 17:14:54 INFO - mozdevice == 0.48 17:14:54 INFO - mozfile == 1.2 17:14:54 INFO - mozhttpd == 0.7 17:14:54 INFO - mozinfo == 0.9 17:14:54 INFO - mozleak == 0.1 17:14:54 INFO - mozlog == 3.1 17:14:54 INFO - moznetwork == 0.27 17:14:54 INFO - mozprocess == 0.22 17:14:54 INFO - mozprofile == 0.28 17:14:54 INFO - mozrunner == 6.11 17:14:54 INFO - mozscreenshot == 0.1 17:14:54 INFO - mozsystemmonitor == 0.0 17:14:54 INFO - moztest == 0.7 17:14:54 INFO - mozversion == 1.4 17:14:54 INFO - psutil == 3.1.1 17:14:54 INFO - requests == 1.2.3 17:14:54 INFO - wsgiref == 0.1.2 17:14:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:54 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:54 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 17:14:54 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 17:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:54 INFO - 'HOME': '/Users/cltbld', 17:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:54 INFO - 'LOGNAME': 'cltbld', 17:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:54 INFO - 'MOZ_NO_REMOTE': '1', 17:14:54 INFO - 'NO_EM_RESTART': '1', 17:14:54 INFO - 'PAGER': '/bin/cat', 17:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:54 INFO - 'PWD': '/builds/slave/test', 17:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:54 INFO - 'SHELL': '/bin/bash', 17:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:54 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:54 INFO - 'USER': 'cltbld', 17:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:54 INFO - 'XPC_FLAGS': '0x0', 17:14:54 INFO - 'XPC_SERVICE_NAME': '0', 17:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 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)) 17:14:54 INFO - Cleaning up... 17:14:54 INFO - Return code: 0 17:14:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:54 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:54 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 17:14:54 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 17:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:54 INFO - 'HOME': '/Users/cltbld', 17:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:54 INFO - 'LOGNAME': 'cltbld', 17:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:54 INFO - 'MOZ_NO_REMOTE': '1', 17:14:54 INFO - 'NO_EM_RESTART': '1', 17:14:54 INFO - 'PAGER': '/bin/cat', 17:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:54 INFO - 'PWD': '/builds/slave/test', 17:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:54 INFO - 'SHELL': '/bin/bash', 17:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:54 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:54 INFO - 'USER': 'cltbld', 17:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:54 INFO - 'XPC_FLAGS': '0x0', 17:14:54 INFO - 'XPC_SERVICE_NAME': '0', 17:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:54 INFO - Downloading/unpacking jsonschema 17:14:54 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 17:14:54 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 17:14:54 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 17:14:54 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 17:14:54 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 17:14:54 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 17:14:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:14:57 INFO - Downloading/unpacking functools32 (from jsonschema) 17:14:57 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 17:14:57 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 17:14:57 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 17:14:57 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 17:14:57 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 17:14:57 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 17:14:57 INFO - Downloading functools32-3.2.3-2.tar.gz 17:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:14:57 INFO - warning: no files found matching '*.txt' 17:14:57 INFO - no previously-included directories found matching 'build' 17:14:57 INFO - no previously-included directories found matching 'dist' 17:14:57 INFO - no previously-included directories found matching '.git*' 17:14:57 INFO - Installing collected packages: jsonschema, functools32 17:14:57 INFO - Running setup.py install for functools32 17:14:57 INFO - warning: no files found matching '*.txt' 17:14:57 INFO - no previously-included directories found matching 'build' 17:14:57 INFO - no previously-included directories found matching 'dist' 17:14:57 INFO - no previously-included directories found matching '.git*' 17:14:57 INFO - Successfully installed jsonschema functools32 17:14:57 INFO - Cleaning up... 17:14:57 INFO - Return code: 0 17:14:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:14:57 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')]} 17:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:57 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')]} 17:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:57 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 0x102475a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102485420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530cbb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, '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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 17:14:57 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 17:14:57 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 17:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:57 INFO - 'HOME': '/Users/cltbld', 17:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:57 INFO - 'LOGNAME': 'cltbld', 17:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:57 INFO - 'MOZ_NO_REMOTE': '1', 17:14:57 INFO - 'NO_EM_RESTART': '1', 17:14:57 INFO - 'PAGER': '/bin/cat', 17:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:57 INFO - 'PWD': '/builds/slave/test', 17:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:57 INFO - 'SHELL': '/bin/bash', 17:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:14:57 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:57 INFO - 'USER': 'cltbld', 17:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:57 INFO - 'XPC_FLAGS': '0x0', 17:14:57 INFO - 'XPC_SERVICE_NAME': '0', 17:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:58 INFO - Downloading/unpacking flake8 17:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:15:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:15:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 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 17:15:00 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 17:15:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:15:01 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 17:15:01 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 17:15:01 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 17:15:01 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 17:15:01 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 17:15:01 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 17:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:15:01 INFO - no previously-included directories found matching 'docs/_build' 17:15:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:15:01 INFO - Running setup.py install for pep8 17:15:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:15:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:15:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:15:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:15:02 INFO - no previously-included directories found matching 'docs/_build' 17:15:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:15:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:15:02 INFO - Cleaning up... 17:15:02 INFO - Return code: 0 17:15:02 INFO - Running post-action listener: _resource_record_post_action 17:15:02 INFO - Running post-action listener: _start_resource_monitoring 17:15:02 INFO - Starting resource monitoring. 17:15:02 INFO - ##### 17:15:02 INFO - ##### Running install step. 17:15:02 INFO - ##### 17:15:02 INFO - Running pre-action listener: _resource_record_pre_action 17:15:02 INFO - Running main action method: install 17:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:15:02 INFO - Reading from file tmpfile_stdout 17:15:02 INFO - Detecting whether we're running mozinstall >=1.0... 17:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:15:02 INFO - Reading from file tmpfile_stdout 17:15:02 INFO - Output received: 17:15:02 INFO - Usage: mozinstall [options] installer 17:15:02 INFO - Options: 17:15:02 INFO - -h, --help show this help message and exit 17:15:02 INFO - -d DEST, --destination=DEST 17:15:02 INFO - Directory to install application into. [default: 17:15:02 INFO - "/builds/slave/test"] 17:15:02 INFO - --app=APP Application being installed. [default: firefox] 17:15:02 INFO - mkdir: /builds/slave/test/build/application 17:15:02 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'] 17:15:02 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 17:15:34 INFO - Reading from file tmpfile_stdout 17:15:34 INFO - Output received: 17:15:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:15:34 INFO - Running post-action listener: _resource_record_post_action 17:15:34 INFO - ##### 17:15:34 INFO - ##### Running run-tests step. 17:15:34 INFO - ##### 17:15:34 INFO - Running pre-action listener: _resource_record_pre_action 17:15:34 INFO - Running main action method: run_tests 17:15:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:15:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:15:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:15:34 INFO - 2016-04-28 17:15:34.692 screenresolution[1922:15555] starting screenresolution argv=screenresolution get 17:15:34 INFO - 2016-04-28 17:15:34.694 screenresolution[1922:15555] Display 0: 1600x1200x32@50 17:15:34 INFO - 2016-04-28 17:15:34.700 screenresolution[1923:15558] starting screenresolution argv=screenresolution list 17:15:34 INFO - Available Modes on Display 0 17:15:34 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 17:15:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:15:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:15:34 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 17:15:34 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 17:15:34 INFO - Graphics/Displays: 17:15:34 INFO - Intel Iris: 17:15:34 INFO - Chipset Model: Intel Iris 17:15:34 INFO - Type: GPU 17:15:34 INFO - Bus: Built-In 17:15:34 INFO - VRAM (Dynamic, Max): 1536 MB 17:15:34 INFO - Vendor: Intel (0x8086) 17:15:34 INFO - Device ID: 0x0a2e 17:15:34 INFO - Revision ID: 0x0009 17:15:34 INFO - Displays: 17:15:34 INFO - Display: 17:15:34 INFO - Resolution: 1600 x 1200 @ 50 Hz 17:15:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:15:34 INFO - Display Serial Number: 1600x1200 50 17:15:34 INFO - Main Display: Yes 17:15:34 INFO - Mirror: Off 17:15:34 INFO - Online: Yes 17:15:34 INFO - Rotation: Supported 17:15:34 INFO - Return code: 0 17:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:15:34 INFO - Python 2.7.3 17:15:34 INFO - Return code: 0 17:15:34 INFO - grabbing minidump binary from tooltool 17:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:34 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 0x102314e00>, '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 0x10217ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10230fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:15:34 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 17:15:34 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 17:15:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:15:35 INFO - Return code: 0 17:15:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:15:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:15:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:15:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:15:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:15:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:15:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:15:36 INFO - Return code: 0 17:15:36 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/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 17:15:36 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/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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 17:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ul1UWhCCWI/Render', 17:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:15:36 INFO - 'HOME': '/Users/cltbld', 17:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:15:36 INFO - 'LOGNAME': 'cltbld', 17:15:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:36 INFO - 'MOZ_NO_REMOTE': '1', 17:15:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:36 INFO - 'NO_EM_RESTART': '1', 17:15:36 INFO - 'PAGER': '/bin/cat', 17:15:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:36 INFO - 'PWD': '/builds/slave/test', 17:15:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:15:36 INFO - 'SHELL': '/bin/bash', 17:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E4zalcdnFf/Listeners', 17:15:36 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 17:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:15:36 INFO - 'USER': 'cltbld', 17:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:36 INFO - 'XPC_FLAGS': '0x0', 17:15:36 INFO - 'XPC_SERVICE_NAME': '0', 17:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:15:36 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/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 17:15:36 INFO - mozversion application_buildid: 20160428152347 17:15:36 INFO - mozversion application_changeset: 91cd25848c032866d7cca41df8d074795b594ea7 17:15:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:15:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:15:36 INFO - mozversion application_name: Firefox 17:15:36 INFO - mozversion application_remotingname: firefox-dev 17:15:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:15:36 INFO - mozversion application_vendor: Mozilla 17:15:36 INFO - mozversion application_version: 48.0a2 17:15:36 INFO - mozversion platform_buildid: 20160428152347 17:15:36 INFO - mozversion platform_changeset: 91cd25848c032866d7cca41df8d074795b594ea7 17:15:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:15:36 INFO - mozversion platform_version: 48.0a2 17:15:36 INFO - using testdate: 1461888936 17:15:36 INFO - actual date: 1461888936 17:15:36 INFO - starting webserver on 'localhost:52449' 17:15:36 INFO - SUITE-START | Running 2 tests 17:15:36 INFO - TEST-START | dromaeo_css 17:15:36 INFO - operating with platform_type : mac_ 17:15:36 INFO - Initialising browser for dromaeo_css test... 17:15:36 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmp5GWTDq/profile http://localhost:52449/getInfo.html) 17:15:39 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 17:15:39 INFO - PROCESS | 1941 | colorDepth:24 17:15:39 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 17:15:39 INFO - PROCESS | 1941 | __metrics 17:15:39 ERROR - PROCESS | 1941 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 17:15:39 INFO - TEST-INFO | 1941: exit 0 17:15:39 INFO - Browser initialized. 17:15:39 INFO - Running cycle 1/1 for dromaeo_css test... 17:15:39 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmp5GWTDq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:51 INFO - PROCESS | 1944 | RSS: Main: 221323264 17:15:51 INFO - PROCESS | 1944 | 17:16:41 INFO - PROCESS | 1944 | 1461889001372 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] 17:18:51 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52449/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52449/tests/dromaeo/cssquery-ext.html) 17:18:52 INFO - PROCESS | 1944 | RSS: Main: 231432192 17:18:52 INFO - PROCESS | 1944 | 17:21:54 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52449/tests/dromaeo/cssquery-ext.html (next: http://localhost:52449/tests/dromaeo/cssquery-jquery.html) 17:21:55 INFO - PROCESS | 1944 | RSS: Main: 243331072 17:21:55 INFO - PROCESS | 1944 | 17:24:57 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52449/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52449/tests/dromaeo/cssquery-mootools.html) 17:24:57 INFO - PROCESS | 1944 | RSS: Main: 245325824 17:24:57 INFO - PROCESS | 1944 | 17:27:59 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52449/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52449/tests/dromaeo/cssquery-prototype.html) 17:28:00 INFO - PROCESS | 1944 | RSS: Main: 247984128 17:28:00 INFO - PROCESS | 1944 | 17:31:02 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52449/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52449/tests/dromaeo/cssquery-yui.html) 17:31:03 INFO - PROCESS | 1944 | RSS: Main: 249958400 17:31:03 INFO - PROCESS | 1944 | 17:34:05 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52449/tests/dromaeo/cssquery-yui.html (next: http://localhost:52449/tests/dromaeo/cssquery-dojo.html) 17:34:06 INFO - PROCESS | 1944 | RSS: Main: 251117568 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | __start_tp_report 17:34:06 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 17:34:06 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 17:34:06 INFO - PROCESS | 1944 | |i|pagename|runs| 17:34:06 INFO - PROCESS | 1944 | |0;dojo.html;3361.764676472647;3410.8465119069633;3422.6064002639687;3461.4461686130226;3471.149568355752;5599.384067752544;5622.437756224375;5636.802711905084;5645.322561292645;5646.209530665705;5593.608447408673;5611.148321054053;5615.606907516475;5628.240187574686;5664.40523745007;4592.311153326994;4751.073540659573;5058.873528161797;5158.458361872005;5196.58427325813;5012.749362531877;5128.564072053861;5160.92258616121;5215.269862219292;5226.843194704165;4677.4620918594455;5016.297718319438;5127.717975511346;5144.8713782155555;5162.741862906859;5168.173092305232;5174.327337446127;5177.094008548489;5183.559397451221;5242.3041755562235;4439.889002774924;4896.045271172123;5147.562457191143;5159.484051594848;5174.172132458826;5348.171033489815;5398.78404863805;5442.9727851360885;5535.612507124503;5579.553635709174;6073.1497590337385;6103.450689437972;6138.894998900196;6180.196574445338;6201.193844800147;6050.515958723292;6241.594296370721;6244.687765611747;6260.123582698426;6270.122182894398;5689.317281926195;5735.655860648374;5737.971310143506;5766.308043034821;5808.796692115798;6224.844378890564;6249.78125765596;6259.593126446766;6260.593061450991;6275.184226050585;5080.288759573663;5864.38423965486;6055.7274922628585;6069.0592958091565;6081.2094427725;5446.747248132872;6012.218411606463;6208;6286.402791734778;6287.088372186007;6138.07928810673;6159.753609855556;6168.9691551541955;6182.072689096582;6194.876102478015;6016.1878146450545;6100.511959043355;6136.4578081083455;6222.62664240147;6239.75040998355;4467.419235499429;4887.242477416006;4888.26675998596;4901.460839307673;4906.754662266943;3687.94468082979;4367.956320436819;4398.912021759547;4422.469303683579;4432.202203603318;4630.652701047435;5421.539169170585;5504.4770746778995;5505.779768809202;5700.657960522382;4754.477007529169;6146.569740118149;6150.477209437248;6171.56799024073;6207.472364848948;5643.379228284885;5660.29246344207;5675.091985282335;5678.858028549319;5689.54483641316;5248.553872920845;5329.280547126166;5347.064263753828;5424.647397919122;5449.891002179934;5681.659100453986;6300.684965751694;6312.43188113072;6338.080978258219;6400.647964362005;6115.296740874856;6125.938740612715;6127.172831667846;6130.632162070112;6142.754289828536;6154.891779651248;6245.656488893154;6299.968500157654;6315.084312774714;6316.810495685137;6050.092486127116;6076.756929722762;6085.665288409003;6127.234095738033;6129.019356902874;5898.557608179318;6163.506919446523;6165;6178.165947597017;6199.318075011655;5982.46157845796;6026.246719160105;6035.155078289131;6091.604045737013;6092.116643086816;5989.191459153133;5999.820005399845;6061.72722227492;6070.666113363807;6074.240719910012;5694.515966142759;5725.826205627753;5842.912356314574;5921.437463440966;5939.970300148471;5620.775168993195;6040.577159598786;6061.515078793598;6079.604825686316;6102.084687296763;5846.386129456516;5866.120081987736;5870.970645146747;5893.2044174035955;5914.497267732118 17:34:06 INFO - PROCESS | 1944 | |1;ext.html;487.37859229482405;504.54338823364867;518.6602775182256;521.7339157029915;544.1918750655274;31288.843555782245;38716;38930;39029.80485097574;39062.41406378903;41174.17651646973;41819.372709409385;41961;42034.579654203524;42118.05173637847;9375.390599611037;9606.375585586915;9614.0385961404;9620.615175393012;9669.999999999965;9580.80838383232;9643.22854171668;9739.610415583367;9780.90219097807;9793.951030244874;9670.75823104421;9671.75820604487;9714.854277185848;9756.121949024586;9778.364406313567;8391.62237699305;9678.951605241999;9737.26970477217;9753.707388778348;9780.608775648965;9494.430334179971;9525.380850244712;9533.713988580354;9551.379160354554;9674.75813104678;38889.22221555553;38973.61026389728;39451.01372465683;39468.21063578741;39499.81500554988;3164.8734050638177;3234.530993006001;3272.132884785534;3327.8502467389026;3661.286049220403;28416;28693.426131477463;28767;28771.42457150866;28781.166314392965;2763.309172706823;2767.1975127213086;2798.9923627494086;2806.7052959439375;2833.985830070857;468833;470456;472469.637651813;473230;473281;55674.72162639242;62339;62636;62866.37133628697;62900;62839;64601;64610.353896461376;65958;66481.33518664849;17187.742183867253;17259.3959211427;17315.393961211295;17351.958882467094;17527.73708394375;13165.341732913315;13238.139520931109;13290.800637990438;13304.068715189844;13322.66693332655;19666.410007699793;19963.500912477015;19982.900940448413;20009.799902001087;20041.498962526053;8159.95920020408;8253.669853205925;8262.752117436487;8263.173682631688;8595;943.8489841625444;952.6141912525438;1053.6154303679102;1076.7738774857369;1089.0253223365203;30552.847235764122;30655.38689226203;30663.84668076645;30781.806562201222;30854.53718194184;1014.1683819268129;1113.1484414422973;1125.189863298424;1140.3044143072718;1278.9168704034207;14686.853131468763;14770.74448671875;15671;15730.213489325488;15840.207989600474;663645;665913;666695;674929;677940;61097.38902610917;61182;61568.384316157164;61707.38292617106;62131;1255.004542776989;1455.3378212913099;1482.2070192446988;1499.257867355644;1506.344740038065;29584.816607336328;29650.110496685134;29756.851215743784;29876.551851721808;30427.695723042485;43642;51474.48525514697;52337.69155771136;52443.47556524538;52611;554671;575357;578985;579685;579723;26110;26766.866165669046;27080;27501.449971000868;27508.58737118905;43466.91332716707;44399.55600443954;46771.532284676716;48037;48549.51450485591;37858.62141378661;38595.614043860325;39228.607713922494;39298.01754956035;40037.1992560153;38201.85394438171;38285.15849066059;38390.808045959595;38838.80580597079;38973.610263897;31035.913743018886;31194.22014449657;34868.82565587156;35720;37532;29603;29858.253543661584;32092.83953580217;32357.838210808794;32368.676313236567;19006.524836879187;24232.909519072036;24282.02871885105;24704.876475617508;24849.50300994006 17:34:06 INFO - PROCESS | 1944 | |2;jquery.html;403.4069917221684;403.65890822255204;405.18962075848304;407.81444442778536;411.77764007435985;19898.60202795945;19925.60148797023;20665.793342066576;20667.17331306746;21000;10653.84019239712;11752;16313.347466101342;21260.36218913435;21281;4698.08387364464;4988.551030407262;5421.701806400646;5440.809571664992;5534.833954981357;3912.62830031148;5444.237806707064;5457.781688732446;5511.448855114497;5780.3063632363965;6076.848078798043;6082.209312789331;6102.20671312731;6103.969480152593;6113.480354169901;5817.941820581782;6092.634441933476;6102.72537735803;6107.297660769002;6129.570930034899;5189.169732842727;5647.576431767634;5876.882462350772;5897.911531327033;5906;12984.480620775157;13745.725085498334;13986.93006534971;13991.510297139552;13996.93001534996;2089.937301880946;2208.381653137124;2220.944476388087;2270.1713874435877;2286.222684287334;8272.793180170544;9853.655122070693;9896;10091.69724908254;10124.190064794799;1742.0854051622873;1794.883332583391;1797.21821007862;1811.3479147506887;1818.6453641539906;111039.44480277627;117246.99999999914;119259;122171;123586.38206808998;11469.88530114705;11832.112591555495;11832.645020649394;11922;11986.34075125859;12643.367831608382;15282.388704451698;15298.235088245543;15347.232638368037;15365.769513457308;8982.86525702115;9002.774930626787;9065.546722663841;9073.45559266433;9146.451212927246;8950.552472376485;8954.059823718546;8963.372563920593;9015.684451044182;9024.142706442877;4635.072985402933;4675.18184317743;4717.150912835723;4718;4765.428148622187;1682.2177687375186;1835.9328254018737;1915.5194023739014;1962.479942815155;2040.398082565617;837.5602808525451;899.3389858454032;992.717075633441;997.3766396002499;1036.461040259061;17179.742303865452;20206.09072591737;20909.686354704692;21047.368578942656;21209.15163393478;955.8183945050419;995.8058178655093;1001.6794625719846;1023.247912784103;1024.144839059379;10779.622713205;11007;11011.889881101086;11070.501827417786;11077.612283570037;517958;525622;528476;544659;574448;134363.32818335845;137234;137601;139412;141668;997.0777031246068;1008.2236677758232;1033.0908800255727;1045.5765415006933;1048.5858086056119;17877.821221787617;18108.00405977684;18116.18477168557;18134.5466363342;18445.89324640526;52802.47197528129;53209.20186197133;53312;53680;54246.45753542572;75026;79747.00632484003;80945;82215;82310.58844705978;7788.918520284245;7827.686892524235;8048.919510804817;8099.271065603888;8256.380771442045;11088.390138542458;12766.553170638983;12791.76144174747;12881.613551593467;12961.351932403153;6199.628022318675;6528.706208220737;11245.493952771982;11468.197226194086;11521;10611;10631.734206644896;10649.8935010652;10841.125215842158;11229.606963756229;10661.09380702618;11507.597234096766;11570;11692.240004399688;11847.526098955945;8298.336133109458;8355.95822020886;8365.581720914051;8437.282830959191;8533.061363249913;8448.40861139739;10988.230823842254;11297.548098075986;11396.316221026766;11499.942500287445 17:34:06 INFO - PROCESS | 1944 | |3;mootools.html;1618.789557357543;1644.342263094762;1900.9144588493516;1954.482062253504;1974.190581861437;8042.959785201066;8433.53615551146;8664.43681160725;8971.147740964614;8995.59519821608;7103.573785572857;8322.625481853316;8443.282321002722;8445.451045682026;8488.405811593175;2427.0777104700232;2459.213051823417;2470.777630013298;2486.8010559155223;2490.4272017436;2442.0353960185716;2449.1305586516796;2476.839005464639;2477.355887469253;2517.8237523373373;2286.2455389721354;2400.4838959623676;2467.605183170693;2483.31708780086;2517.886695098716;2449.534588428202;2468.8024958003316;2490.6264060390995;2492.6634904288053;2496.6005439129835;2477.603583426643;2483.714372847128;2491.302435318114;2494.064725727852;2501.0371007162194;8786.692465763732;8847.469151850844;8851.513166775825;8851.911480885174;8879.822403551958;5324.494173053554;5507.4767897050115;5553.7778488860395;5570.637908535932;5578.386377498472;3565.714742820568;3584.1039740064984;3594.784312941206;3695.2424752925585;3698.6116457772087;936.5551363102539;946.2713710442989;1005.5022763732;1044.6082718980383;1080.616381184676;9699.90300096997;11565.710857228552;11693.766124677544;11717.707057323634;11758;4816.157172494799;4946.703197808071;5034.194528875362;5064.442911279756;5079.87300317495;10196.38821670702;10207.7448063799;10210.540525676362;10221.795564088676;10246.692599221886;5410.675359478444;5490.890182196334;5491.890162196733;5539;5587.38538760736;4125;4465.888352791141;4909.214525675875;4954.058728841508;4955.281484184845;4083.8979025524004;4111.392705817149;4185.068139449117;4205.137946720914;4221.472315960505;1215.6960759810047;1271.8664540223328;1317.5981325695564;1347.9730405391838;1399.531157062375;804.1877703519488;855.0252711908429;912.6212621762027;916.9449833010041;922.8292765838341;5553.750081246354;5716.885207384603;5750.769969201268;5766.625169363978;5798.623089499169;855.8331125430606;970.9708708738749;976.4287891583361;1001.2740762946805;1047.36634336227;3696.8336424860945;3784.6215378462484;3795.7153213509096;3797.35444974355;3853.9979605302265;10121.493925303705;10521.84217236742;10597.940205979494;10657.253992220625;10684.572617095384;9570.33007689469;10295.558621792956;10565.630202943014;10653.307535010199;10824.24230303871;6378.617282963037;8062.750273707585;8182.877256841152;8185.590720463953;8226.876596851052;5315.149576067811;5463.989162005042;5764.942350576441;5811.56413268998;5920.200772895604;9353.11145441182;9622.422654640744;9831;9924.603015879573;9973.30186886911;7872.84254314899;10108.545115469675;10114.140298074746;10127.139193168368;10199.337043092175;6849.657517124224;7264.382527485223;7350.889736653848;7565.167831538416;7581.166071732216;8180.609296419504;8247.628856701584;8255.25702686761;8317.667293308443;8351.20663536963;7198.6400679964745;7346.228645992094;7473.327400533978;7482.176960534228;7495.8500829982;7174.5695258282585;7259;7261.854762904818;7304.233055529093;7345.265473452612;6670.099536562696;6808.217055038536;6816.7614133505085;6819.89770153458;6822.727090916308;4715.764211789466;4728.172569800203;4743.549362810528;4818.915743957722;4836.95163048365;4787.664863459524;4809.519048095162;4831.830885918976;4858.78135483897;5048.798048078036 17:34:06 INFO - PROCESS | 1944 | |4;prototype.html;264.87428429176003;308.50484971620557;311.37569173806514;312.78730463284955;318.0331791354283;4630.62954963603;5459.352214176121;5465.934142842148;5529.253550770644;5538.252335934648;4321.87034388968;4696.755359829639;4700.623950083994;5333.413324534298;5412.56699464043;3368.8147151906637;4012.9799351003276;4174.332106862903;4195.895102622444;4208.747475151501;4126.628603425691;4186.246475634384;4195.370694395834;4216.135692183095;4217.40956266123;3647.9206852094317;4158.8336466541305;4188.246115699173;4192.727472714279;4197.286461301571;4032.7177097603035;4160.9597600599855;4177.227212965611;4192.077742896573;4206.8737937861915;3600.567931848169;3625.474306225609;3633.6184700606586;3650.7261955353188;3654.14127679995;4682.484926658065;4718.867471806741;4785.090832741768;4862.32249873793;5240.3187585614005;4587.449506059261;4838.491958344393;4847.878803029917;4851.878703032417;4876.804927802884;5748.655080695172;5771.134329850514;5778.59549831512;5814.883702325929;5869.148973398876;3489.930201395983;3634.545681789776;3674.7243956703355;4053.7636021013577;4066.6340029397497;5349.3045904032615;5398.514133727944;5767.250257466544;5877.38287479817;6213.2233470816145;3444.8794292199655;3950.27431072559;3962.6829853611644;3965.5637879833193;3991.022199561126;3479.521203488558;3856.1130939883983;3894.980525097413;4049.6962727795535;4053.43251944728;4085.2637629007554;4462.6530102423185;4658.394408726912;4728.1252968201;4750.881227969328;4598.436211935709;5149.562287205629;5251.317328747238;5888.146218798251;5895.145203945404;1440.5246268731503;1467.5744034230167;1471.8454562271022;1471.8528147185195;1480.4744315767962;894.6287290774255;934.1182452135104;936.8336421155568;950.7575568230058;969.5007071358256;5342.011727830364;5669.518090962314;5744.51171650406;5797.739101740347;5819.330776960704;5549.750261238254;6042.425969532887;6511.869762604816;6576.44100251484;6585.637789921505;5143.639945203875;5528.668279903219;5545.306836645419;5706.258186435737;5795.1886735857015;2784.7493725564796;2949.9822561216347;3163;3361.8487168077495;3424.4692072728767;6476.676166191766;6785.423239024738;6786.864262714718;6793.626350550669;6803.863922721518;6093.451589356979;6666.500012499082;6702.09521714572;6711.395974362331;6718.529702920847;5152.639315247971;5744.965906136936;5856.824295271149;6339.841503962438;6809.114815074008;5031.949680503295;5574.386817449996;6015.0676645120075;6131.938680613315;6169.383061693795;5053.913408617018;5998.640081595118;6001.159837622824;6051.4553690168095;6059.8488785832005;5905.557083218861;6154.261488621394;6164.229471316085;6279.717412716347;6373.521985850987;3893.3770596705526;3910.983144382424;3938.369860822255;3940.4877365942243;3986.3023970805575;4992.350994370709;5035;5976.177265933932;6024.638521688713;6043.2748070231855;4838.637102217277;5082.4409314976065;5561.833145005657;5857.970710146422;5877.177195192591;5121.206213036985;5124.74376281177;5129.640925135354;5170.250313704567;5843.590948633555;4948.505149485023;5493.862653433696;5557.5831812613405;5710.343310519177;5746.569007324551;5827.192566133042;5839.941600583939;5844.977129002493;5857.707114644166;5881.529477641693;4789.808407663654;5035.496450354935;5750.25246717924;5763.971180144073;5790.739416726342 17:34:06 INFO - PROCESS | 1944 | |5;yui.html;672.5930811858825;690.3614156904862;733.5525329548977;744.3301029073837;773.2036002916994;14808.259587020646;14849.77725334118;14886.702265954687;14988.700225995488;14995.25023748811;13713.108647937877;13752.931235343809;14858.33137508812;15578.221088945564;15631.296591653374;723.3453724379445;921.8110287391069;924.3344791749929;935.3405848876525;935.6538080910038;901.2204443156688;932.5383934952208;934.3272843552666;935.3779736475236;938.7230766923774;970.0832713086137;980.4509474694158;984.4880662055726;989.4755779436874;991.5538007896439;941.9199368053723;942.2791564453199;975.5951280218701;980.9215262778961;984.2765567308059;698.9196242432134;710.5559025608994;720.2293545905883;724.8586525627504;725.0429433148247;15136.697266054727;15392.538223853302;15395.61510962235;15422.614434639112;15648.4523041693;10494.317869338545;12355;12460.314682692448;12486.12597118202;12800.295983720891;8416.915830841674;9816.214702823829;9873.506324683736;10235;10337.966203379601;3434.7252219822353;3476.096214984097;3493.423585108454;3539.7699149555197;3562.8040457774814;77468;79072;79726;80683.57949261532;80989.59505202436;7967.721129760431;9173;9310.767230819283;9474.57364418589;9506.431438812657;20754.066067026717;20804.751714897153;20863.79136208649;20873.478163046046;20904.686429703568;7209.387202087882;7230.9638451807405;7353.338199562065;7544.698212071566;7600.733974310872;4040.393940908887;4076.085608709622;4082.3435159705923;4090.5295890972325;4120.567340429272;3448.4654878493875;3527.276908233806;3542.8405721742583;3543.344481270973;3551;553.82000849724;623.5416968528128;658.9588450248596;663.9037339585733;670.5607826873405;500.69207437425723;544.080503948326;549.4725063938664;574.5977815529063;591.6627522312241;7674.462787604814;7873.842523149505;8019.679212831538;8130.227628375294;8261.215184557457;512.7871933147702;572.6535446055077;584.2229834320344;591.928609217318;595.8718875441714;3225.2904361040532;3778.112143646241;4061.1674606705556;4207.305794543866;4308.202982448257;98227;101560;102143.48928255311;104792;107198.46400768102;87396.25207495815;88416.55791721;88830;90341;91408.5429572848;80375.59812200902;80601.19398805937;80727.19272807315;80886;81198.18801812024;7759.495632784076;7933.722319718782;7956.045274567089;8004.399670024655;8168.142345053684;45136.097278053596;45997.08005839931;46667.766661166475;46904.29643555418;47164.52835471739;56956.86086278334;63750;67636.6618166906;68164.6591767038;68543.65728171327;10762.677119686421;10976.725581860197;11004.449777510932;11362.03422709079;11608.071354291806;11667.533298668297;11972;12079.4810134777;12130.939345303217;12830.16603920742;9139.817203656023;10991.39547324873;11251.987321141136;11254.21220514556;11429.028532574825;11160.441977901235;11416.31502109876;11460.739318674772;11464.551961745477;11821.113416493625;10559.260851740304;10979.890201097887;11046.889531104585;11252.66242012741;11678.6496405108;7776.922230777846;9445;9692.951535242279;9916.107550320507;10035.849462257926;9174;9384.29617778683;9457.574409151743;9526.71389362455;9631.422114673142 17:34:06 INFO - PROCESS | 1944 | __end_tp_report 17:34:06 INFO - PROCESS | 1944 | __start_cc_report 17:34:06 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,415 17:34:06 INFO - PROCESS | 1944 | __end_cc_report 17:34:06 INFO - PROCESS | 1944 | __startTimestamp1461890046107__endTimestamp 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | ------- Summary: start ------- 17:34:06 INFO - PROCESS | 1944 | Number of tests: 6 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5632.95 Median:5842.91 stddev:659.87 (11.3%) stddev-sans-first:637.38 17:34:06 INFO - PROCESS | 1944 | Values: 3361.8 3410.8 3422.6 3461.4 3471.1 5599.4 5622.4 5636.8 5645.3 5646.2 5593.6 5611.1 5615.6 5628.2 5664.4 4592.3 4751.1 5058.9 5158.5 5196.6 5012.7 5128.6 5160.9 5215.3 5226.8 4677.5 5016.3 5127.7 5144.9 5162.7 5168.2 5174.3 5177.1 5183.6 5242.3 4439.9 4896.0 5147.6 5159.5 5174.2 5348.2 5398.8 5443.0 5535.6 5579.6 6073.1 6103.5 6138.9 6180.2 6201.2 6050.5 6241.6 6244.7 6260.1 6270.1 5689.3 5735.7 5738.0 5766.3 5808.8 6224.8 6249.8 6259.6 6260.6 6275.2 5080.3 5864.4 6055.7 6069.1 6081.2 5446.7 6012.2 6208.0 6286.4 6287.1 6138.1 6159.8 6169.0 6182.1 6194.9 6016.2 6100.5 6136.5 6222.6 6239.8 4467.4 4887.2 4888.3 4901.5 4906.8 3687.9 4368.0 4398.9 4422.5 4432.2 4630.7 5421.5 5504.5 5505.8 5700.7 4754.5 6146.6 6150.5 6171.6 6207.5 5643.4 5660.3 5675.1 5678.9 5689.5 5248.6 5329.3 5347.1 5424.6 5449.9 5681.7 6300.7 6312.4 6338.1 6400.6 6115.3 6125.9 6127.2 6130.6 6142.8 6154.9 6245.7 6300.0 6315.1 6316.8 6050.1 6076.8 6085.7 6127.2 6129.0 5898.6 6163.5 6165.0 6178.2 6199.3 5982.5 6026.2 6035.2 6091.6 6092.1 5989.2 5999.8 6061.7 6070.7 6074.2 5694.5 5725.8 5842.9 5921.4 5940.0 5620.8 6040.6 6061.5 6079.6 6102.1 5846.4 5866.1 5871.0 5893.2 5914.5 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:70363.13 Median:39046.11 stddev:154454.45 (395.6%) stddev-sans-first:154798.63 17:34:06 INFO - PROCESS | 1944 | Values: 487.4 504.5 518.7 521.7 544.2 31288.8 38716.0 38930.0 39029.8 39062.4 41174.2 41819.4 41961.0 42034.6 42118.1 9375.4 9606.4 9614.0 9620.6 9670.0 9580.8 9643.2 9739.6 9780.9 9794.0 9670.8 9671.8 9714.9 9756.1 9778.4 8391.6 9679.0 9737.3 9753.7 9780.6 9494.4 9525.4 9533.7 9551.4 9674.8 38889.2 38973.6 39451.0 39468.2 39499.8 3164.9 3234.5 3272.1 3327.9 3661.3 28416.0 28693.4 28767.0 28771.4 28781.2 2763.3 2767.2 2799.0 2806.7 2834.0 468833.0 470456.0 472469.6 473230.0 473281.0 55674.7 62339.0 62636.0 62866.4 62900.0 62839.0 64601.0 64610.4 65958.0 66481.3 17187.7 17259.4 17315.4 17352.0 17527.7 13165.3 13238.1 13290.8 13304.1 13322.7 19666.4 19963.5 19982.9 20009.8 20041.5 8160.0 8253.7 8262.8 8263.2 8595.0 943.8 952.6 1053.6 1076.8 1089.0 30552.8 30655.4 30663.8 30781.8 30854.5 1014.2 1113.1 1125.2 1140.3 1278.9 14686.9 14770.7 15671.0 15730.2 15840.2 663645.0 665913.0 666695.0 674929.0 677940.0 61097.4 61182.0 61568.4 61707.4 62131.0 1255.0 1455.3 1482.2 1499.3 1506.3 29584.8 29650.1 29756.9 29876.6 30427.7 43642.0 51474.5 52337.7 52443.5 52611.0 554671.0 575357.0 578985.0 579685.0 579723.0 26110.0 26766.9 27080.0 27501.4 27508.6 43466.9 44399.6 46771.5 48037.0 48549.5 37858.6 38595.6 39228.6 39298.0 40037.2 38201.9 38285.2 38390.8 38838.8 38973.6 31035.9 31194.2 34868.8 35720.0 37532.0 29603.0 29858.3 32092.8 32357.8 32368.7 19006.5 24232.9 24282.0 24704.9 24849.5 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:33220.16 Median:21234.76 stddev:90947.47 (428.3%) stddev-sans-first:91169.22 17:34:06 INFO - PROCESS | 1944 | Values: 403.4 403.7 405.2 407.8 411.8 19898.6 19925.6 20665.8 20667.2 21000.0 10653.8 11752.0 16313.3 21260.4 21281.0 4698.1 4988.6 5421.7 5440.8 5534.8 3912.6 5444.2 5457.8 5511.4 5780.3 6076.8 6082.2 6102.2 6104.0 6113.5 5817.9 6092.6 6102.7 6107.3 6129.6 5189.2 5647.6 5876.9 5897.9 5906.0 12984.5 13745.7 13986.9 13991.5 13996.9 2089.9 2208.4 2220.9 2270.2 2286.2 8272.8 9853.7 9896.0 10091.7 10124.2 1742.1 1794.9 1797.2 1811.3 1818.6 111039.4 117247.0 119259.0 122171.0 123586.4 11469.9 11832.1 11832.6 11922.0 11986.3 12643.4 15282.4 15298.2 15347.2 15365.8 8982.9 9002.8 9065.5 9073.5 9146.5 8950.6 8954.1 8963.4 9015.7 9024.1 4635.1 4675.2 4717.2 4718.0 4765.4 1682.2 1835.9 1915.5 1962.5 2040.4 837.6 899.3 992.7 997.4 1036.5 17179.7 20206.1 20909.7 21047.4 21209.2 955.8 995.8 1001.7 1023.2 1024.1 10779.6 11007.0 11011.9 11070.5 11077.6 517958.0 525622.0 528476.0 544659.0 574448.0 134363.3 137234.0 137601.0 139412.0 141668.0 997.1 1008.2 1033.1 1045.6 1048.6 17877.8 18108.0 18116.2 18134.5 18445.9 52802.5 53209.2 53312.0 53680.0 54246.5 75026.0 79747.0 80945.0 82215.0 82310.6 7788.9 7827.7 8048.9 8099.3 8256.4 11088.4 12766.6 12791.8 12881.6 12961.4 6199.6 6528.7 11245.5 11468.2 11521.0 10611.0 10631.7 10649.9 10841.1 11229.6 10661.1 11507.6 11570.0 11692.2 11847.5 8298.3 8356.0 8365.6 8437.3 8533.1 8448.4 10988.2 11297.5 11396.3 11499.9 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5663.82 Median:4950.38 stddev:3101.23 (62.6%) stddev-sans-first:3095.03 17:34:06 INFO - PROCESS | 1944 | Values: 1618.8 1644.3 1900.9 1954.5 1974.2 8043.0 8433.5 8664.4 8971.1 8995.6 7103.6 8322.6 8443.3 8445.5 8488.4 2427.1 2459.2 2470.8 2486.8 2490.4 2442.0 2449.1 2476.8 2477.4 2517.8 2286.2 2400.5 2467.6 2483.3 2517.9 2449.5 2468.8 2490.6 2492.7 2496.6 2477.6 2483.7 2491.3 2494.1 2501.0 8786.7 8847.5 8851.5 8851.9 8879.8 5324.5 5507.5 5553.8 5570.6 5578.4 3565.7 3584.1 3594.8 3695.2 3698.6 936.6 946.3 1005.5 1044.6 1080.6 9699.9 11565.7 11693.8 11717.7 11758.0 4816.2 4946.7 5034.2 5064.4 5079.9 10196.4 10207.7 10210.5 10221.8 10246.7 5410.7 5490.9 5491.9 5539.0 5587.4 4125.0 4465.9 4909.2 4954.1 4955.3 4083.9 4111.4 4185.1 4205.1 4221.5 1215.7 1271.9 1317.6 1348.0 1399.5 804.2 855.0 912.6 916.9 922.8 5553.8 5716.9 5750.8 5766.6 5798.6 855.8 971.0 976.4 1001.3 1047.4 3696.8 3784.6 3795.7 3797.4 3854.0 10121.5 10521.8 10597.9 10657.3 10684.6 9570.3 10295.6 10565.6 10653.3 10824.2 6378.6 8062.8 8182.9 8185.6 8226.9 5315.1 5464.0 5764.9 5811.6 5920.2 9353.1 9622.4 9831.0 9924.6 9973.3 7872.8 10108.5 10114.1 10127.1 10199.3 6849.7 7264.4 7350.9 7565.2 7581.2 8180.6 8247.6 8255.3 8317.7 8351.2 7198.6 7346.2 7473.3 7482.2 7495.9 7174.6 7259.0 7261.9 7304.2 7345.3 6670.1 6808.2 6816.8 6819.9 6822.7 4715.8 4728.2 4743.5 4818.9 4837.0 4787.7 4809.5 4831.8 4858.8 5048.8 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4729.98 Median:5136.64 stddev:1494.84 (29.1%) stddev-sans-first:1460.98 17:34:06 INFO - PROCESS | 1944 | Values: 264.9 308.5 311.4 312.8 318.0 4630.6 5459.4 5465.9 5529.3 5538.3 4321.9 4696.8 4700.6 5333.4 5412.6 3368.8 4013.0 4174.3 4195.9 4208.7 4126.6 4186.2 4195.4 4216.1 4217.4 3647.9 4158.8 4188.2 4192.7 4197.3 4032.7 4161.0 4177.2 4192.1 4206.9 3600.6 3625.5 3633.6 3650.7 3654.1 4682.5 4718.9 4785.1 4862.3 5240.3 4587.4 4838.5 4847.9 4851.9 4876.8 5748.7 5771.1 5778.6 5814.9 5869.1 3489.9 3634.5 3674.7 4053.8 4066.6 5349.3 5398.5 5767.3 5877.4 6213.2 3444.9 3950.3 3962.7 3965.6 3991.0 3479.5 3856.1 3895.0 4049.7 4053.4 4085.3 4462.7 4658.4 4728.1 4750.9 4598.4 5149.6 5251.3 5888.1 5895.1 1440.5 1467.6 1471.8 1471.9 1480.5 894.6 934.1 936.8 950.8 969.5 5342.0 5669.5 5744.5 5797.7 5819.3 5549.8 6042.4 6511.9 6576.4 6585.6 5143.6 5528.7 5545.3 5706.3 5795.2 2784.7 2950.0 3163.0 3361.8 3424.5 6476.7 6785.4 6786.9 6793.6 6803.9 6093.5 6666.5 6702.1 6711.4 6718.5 5152.6 5745.0 5856.8 6339.8 6809.1 5031.9 5574.4 6015.1 6131.9 6169.4 5053.9 5998.6 6001.2 6051.5 6059.8 5905.6 6154.3 6164.2 6279.7 6373.5 3893.4 3911.0 3938.4 3940.5 3986.3 4992.4 5035.0 5976.2 6024.6 6043.3 4838.6 5082.4 5561.8 5858.0 5877.2 5121.2 5124.7 5129.6 5170.3 5843.6 4948.5 5493.9 5557.6 5710.3 5746.6 5827.2 5839.9 5845.0 5857.7 5881.5 4789.8 5035.5 5750.3 5764.0 5790.7 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:18927.27 Median:591.80 stddev:27668.57 (4675.4%) stddev-sans-first:27712.23 17:34:06 INFO - PROCESS | 1944 | Values: 672.6 690.4 733.6 744.3 773.2 14808.3 14849.8 14886.7 14988.7 14995.3 13713.1 13752.9 14858.3 15578.2 15631.3 723.3 921.8 924.3 935.3 935.7 901.2 932.5 934.3 935.4 938.7 970.1 980.5 984.5 989.5 991.6 941.9 942.3 975.6 980.9 984.3 698.9 710.6 720.2 724.9 725.0 15136.7 15392.5 15395.6 15422.6 15648.5 10494.3 12355.0 12460.3 12486.1 12800.3 8416.9 9816.2 9873.5 10235.0 10338.0 3434.7 3476.1 3493.4 3539.8 3562.8 77468.0 79072.0 79726.0 80683.6 80989.6 7967.7 9173.0 9310.8 9474.6 9506.4 20754.1 20804.8 20863.8 20873.5 20904.7 7209.4 7231.0 7353.3 7544.7 7600.7 4040.4 4076.1 4082.3 4090.5 4120.6 3448.5 3527.3 3542.8 3543.3 3551.0 553.8 623.5 659.0 663.9 670.6 500.7 544.1 549.5 574.6 591.7 7674.5 7873.8 8019.7 8130.2 8261.2 512.8 572.7 584.2 591.9 595.9 3225.3 3778.1 4061.2 4207.3 4308.2 98227.0 101560.0 102143.5 104792.0 107198.5 87396.3 88416.6 88830.0 90341.0 91408.5 80375.6 80601.2 80727.2 80886.0 81198.2 7759.5 7933.7 7956.0 8004.4 8168.1 45136.1 45997.1 46667.8 46904.3 47164.5 56956.9 63750.0 67636.7 68164.7 68543.7 10762.7 10976.7 11004.4 11362.0 11608.1 11667.5 11972.0 12079.5 12130.9 12830.2 9139.8 10991.4 11252.0 11254.2 11429.0 11160.4 11416.3 11460.7 11464.6 11821.1 10559.3 10979.9 11046.9 11252.7 11678.6 7776.9 9445.0 9693.0 9916.1 10035.8 9174.0 9384.3 9457.6 9526.7 9631.4 17:34:06 INFO - PROCESS | 1944 | -------- Summary: end -------- 17:34:06 INFO - PROCESS | 1944 | 17:34:06 INFO - TEST-INFO | 1944: exit 0 17:34:06 INFO - TEST-OK | dromaeo_css | took 1110336ms 17:34:06 INFO - TEST-START | kraken 17:34:06 INFO - operating with platform_type : mac_ 17:34:06 INFO - Initialising browser for kraken test... 17:34:06 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmptSnrJB/profile http://localhost:52449/getInfo.html) 17:34:09 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 17:34:09 INFO - PROCESS | 1950 | colorDepth:24 17:34:09 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 17:34:09 INFO - PROCESS | 1950 | __metrics 17:34:09 ERROR - PROCESS | 1950 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 17:34:10 INFO - TEST-INFO | 1950: exit 0 17:34:10 INFO - Browser initialized. 17:34:10 INFO - Running cycle 1/1 for kraken test... 17:34:10 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmptSnrJB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:34:22 INFO - PROCESS | 1953 | RSS: Main: 221683712 17:34:22 INFO - PROCESS | 1953 | 17:34:24 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52449/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:25 INFO - PROCESS | 1953 | RSS: Main: 233529344 17:34:25 INFO - PROCESS | 1953 | 17:34:28 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52449/tests/kraken/driver.html?testName=audio-dft) 17:34:29 INFO - PROCESS | 1953 | RSS: Main: 242728960 17:34:29 INFO - PROCESS | 1953 | 17:34:32 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52449/tests/kraken/driver.html?testName=audio-fft) 17:34:32 INFO - PROCESS | 1953 | RSS: Main: 246001664 17:34:32 INFO - PROCESS | 1953 | 17:34:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52449/tests/kraken/driver.html?testName=audio-oscillator) 17:34:35 INFO - PROCESS | 1953 | RSS: Main: 246714368 17:34:35 INFO - PROCESS | 1953 | 17:34:37 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52449/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:34:38 INFO - PROCESS | 1953 | RSS: Main: 247037952 17:34:38 INFO - PROCESS | 1953 | 17:34:40 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52449/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:41 INFO - PROCESS | 1953 | RSS: Main: 247230464 17:34:41 INFO - PROCESS | 1953 | 17:34:44 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52449/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:45 INFO - PROCESS | 1953 | RSS: Main: 248225792 17:34:45 INFO - PROCESS | 1953 | 17:34:48 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52449/tests/kraken/driver.html?testName=json-parse-financial) 17:34:48 INFO - PROCESS | 1953 | RSS: Main: 248365056 17:34:48 INFO - PROCESS | 1953 | 17:34:50 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52449/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:50 INFO - PROCESS | 1953 | RSS: Main: 248049664 17:34:50 INFO - PROCESS | 1953 | 17:34:52 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:53 INFO - PROCESS | 1953 | RSS: Main: 248160256 17:34:53 INFO - PROCESS | 1953 | 17:34:55 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:56 INFO - PROCESS | 1953 | RSS: Main: 248328192 17:34:56 INFO - PROCESS | 1953 | 17:34:59 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:59 INFO - PROCESS | 1953 | RSS: Main: 248446976 17:34:59 INFO - PROCESS | 1953 | 17:35:02 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:35:03 INFO - PROCESS | 1953 | RSS: Main: 248438784 17:35:03 INFO - PROCESS | 1953 | 17:35:04 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52449/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52449/tests/kraken/driver.html?testName=ai-astar) 17:35:05 INFO - PROCESS | 1953 | RSS: Main: 250064896 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | __start_tp_report 17:35:05 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 17:35:05 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 17:35:05 INFO - PROCESS | 1953 | |i|pagename|runs| 17:35:05 INFO - PROCESS | 1953 | |0;ai-astar;120;115;118;119;118;135;118;118;117;116 17:35:05 INFO - PROCESS | 1953 | |1;audio-beat-detection;160;152;153;195;153;146;121;163;143;151 17:35:05 INFO - PROCESS | 1953 | |2;audio-dft;195;199;196;199;197;195;200;200;197;196 17:35:05 INFO - PROCESS | 1953 | |3;audio-fft;62;71;81;141;56;55;57;63;129;55 17:35:05 INFO - PROCESS | 1953 | |4;audio-oscillator;80;84;130;78;78;81;118;80;83;115 17:35:05 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;90;89;89;87;91;87;89;87;90;86 17:35:05 INFO - PROCESS | 1953 | |6;imaging-darkroom;96;98;96;94;96;115;120;95;96;95 17:35:05 INFO - PROCESS | 1953 | |7;imaging-desaturate;150;115;146;143;92;89;93;90;88;124 17:35:05 INFO - PROCESS | 1953 | |8;json-parse-financial;60;67;66;66;67;64;68;81;63;60 17:35:05 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;62;66;73;66;76;77;68;68;66;88 17:35:05 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;83;84;84;82;82;83;81;83;88;83 17:35:05 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;141;141;151;131;131;149;146;143;185;137 17:35:05 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;171;176;170;175;176;166;172;168;151;226 17:35:05 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;70;70;69;69;70;69;66;66;70;66 17:35:05 INFO - PROCESS | 1953 | __end_tp_report 17:35:05 INFO - PROCESS | 1953 | __start_cc_report 17:35:05 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,1102 17:35:05 INFO - PROCESS | 1953 | __end_cc_report 17:35:05 INFO - PROCESS | 1953 | __startTimestamp1461890105237__endTimestamp 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | ------- Summary: start ------- 17:35:05 INFO - PROCESS | 1953 | Number of tests: 14 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:119.40 Median:118.00 stddev:5.66 (4.8%) stddev-sans-first:6.00 17:35:05 INFO - PROCESS | 1953 | Values: 120.0 115.0 118.0 119.0 118.0 135.0 118.0 118.0 117.0 116.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:153.70 Median:153.00 stddev:18.52 (12.1%) stddev-sans-first:19.50 17:35:05 INFO - PROCESS | 1953 | Values: 160.0 152.0 153.0 195.0 153.0 146.0 121.0 163.0 143.0 151.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:197.40 Median:198.00 stddev:1.96 (1.0%) stddev-sans-first:1.87 17:35:05 INFO - PROCESS | 1953 | Values: 195.0 199.0 196.0 199.0 197.0 195.0 200.0 200.0 197.0 196.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:77.00 Median:59.50 stddev:31.77 (53.4%) stddev-sans-first:33.23 17:35:05 INFO - PROCESS | 1953 | Values: 62.0 71.0 81.0 141.0 56.0 55.0 57.0 63.0 129.0 55.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:80.00 stddev:19.97 (25.0%) stddev-sans-first:20.65 17:35:05 INFO - PROCESS | 1953 | Values: 80.0 84.0 130.0 78.0 78.0 81.0 118.0 80.0 83.0 115.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.00 stddev:1.65 (1.9%) stddev-sans-first:1.66 17:35:05 INFO - PROCESS | 1953 | Values: 90.0 89.0 89.0 87.0 91.0 87.0 89.0 87.0 90.0 86.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:96.00 stddev:9.30 (9.7%) stddev-sans-first:9.75 17:35:05 INFO - PROCESS | 1953 | Values: 96.0 98.0 96.0 94.0 96.0 115.0 120.0 95.0 96.0 95.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:113.00 Median:88.50 stddev:25.94 (29.3%) stddev-sans-first:23.80 17:35:05 INFO - PROCESS | 1953 | Values: 150.0 115.0 146.0 143.0 92.0 89.0 93.0 90.0 88.0 124.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.50 stddev:5.92 (8.9%) stddev-sans-first:5.84 17:35:05 INFO - PROCESS | 1953 | Values: 60.0 67.0 66.0 66.0 67.0 64.0 68.0 81.0 63.0 60.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:7.66 (10.9%) stddev-sans-first:7.40 17:35:05 INFO - PROCESS | 1953 | Values: 62.0 66.0 73.0 66.0 76.0 77.0 68.0 68.0 66.0 88.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.00 stddev:1.89 (2.3%) stddev-sans-first:2.00 17:35:05 INFO - PROCESS | 1953 | Values: 83.0 84.0 84.0 82.0 82.0 83.0 81.0 83.0 88.0 83.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:144.50 stddev:15.43 (10.7%) stddev-sans-first:16.28 17:35:05 INFO - PROCESS | 1953 | Values: 141.0 141.0 151.0 131.0 131.0 149.0 146.0 143.0 185.0 137.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:173.50 stddev:19.32 (11.1%) stddev-sans-first:20.43 17:35:05 INFO - PROCESS | 1953 | Values: 171.0 176.0 170.0 175.0 176.0 166.0 172.0 168.0 151.0 226.0 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.50 stddev:1.78 (2.6%) stddev-sans-first:1.80 17:35:05 INFO - PROCESS | 1953 | Values: 70.0 70.0 69.0 69.0 70.0 69.0 66.0 66.0 70.0 66.0 17:35:05 INFO - PROCESS | 1953 | -------- Summary: end -------- 17:35:05 INFO - PROCESS | 1953 | 17:35:05 INFO - TEST-INFO | 1953: exit 0 17:35:06 INFO - TEST-OK | kraken | took 59360ms 17:35:06 INFO - SUITE-END | took 1169s 17:35:06 INFO - Completed test suite (00:19:30) 17:35:06 INFO - javascript benchmark 17:35:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3361.764676472647, 3410.8465119069633, 3422.6064002639687, 3461.4461686130226, 3471.149568355752, 5599.384067752544, 5622.437756224375, 5636.802711905084, 5645.322561292645, 5646.209530665705, 5593.608447408673, 5611.148321054053, 5615.606907516475, 5628.240187574686, 5664.40523745007, 4592.311153326994, 4751.073540659573, 5058.873528161797, 5158.458361872005, 5196.58427325813, 5012.749362531877, 5128.564072053861, 5160.92258616121, 5215.269862219292, 5226.843194704165, 4677.4620918594455, 5016.297718319438, 5127.717975511346, 5144.8713782155555, 5162.741862906859, 5168.173092305232, 5174.327337446127, 5177.094008548489, 5183.559397451221, 5242.3041755562235, 4439.889002774924, 4896.045271172123, 5147.562457191143, 5159.484051594848, 5174.172132458826, 5348.171033489815, 5398.78404863805, 5442.9727851360885, 5535.612507124503, 5579.553635709174, 6073.1497590337385, 6103.450689437972, 6138.894998900196, 6180.196574445338, 6201.193844800147, 6050.515958723292, 6241.594296370721, 6244.687765611747, 6260.123582698426, 6270.122182894398, 5689.317281926195, 5735.655860648374, 5737.971310143506, 5766.308043034821, 5808.796692115798, 6224.844378890564, 6249.78125765596, 6259.593126446766, 6260.593061450991, 6275.184226050585, 5080.288759573663, 5864.38423965486, 6055.7274922628585, 6069.0592958091565, 6081.2094427725, 5446.747248132872, 6012.218411606463, 6208.0, 6286.402791734778, 6287.088372186007, 6138.07928810673, 6159.753609855556, 6168.9691551541955, 6182.072689096582, 6194.876102478015, 6016.1878146450545, 6100.511959043355, 6136.4578081083455, 6222.62664240147, 6239.75040998355, 4467.419235499429, 4887.242477416006, 4888.26675998596, 4901.460839307673, 4906.754662266943, 3687.94468082979, 4367.956320436819, 4398.912021759547, 4422.469303683579, 4432.202203603318, 4630.652701047435, 5421.539169170585, 5504.4770746778995, 5505.779768809202, 5700.657960522382, 4754.477007529169, 6146.569740118149, 6150.477209437248, 6171.56799024073, 6207.472364848948, 5643.379228284885, 5660.29246344207, 5675.091985282335, 5678.858028549319, 5689.54483641316, 5248.553872920845, 5329.280547126166, 5347.064263753828, 5424.647397919122, 5449.891002179934, 5681.659100453986, 6300.684965751694, 6312.43188113072, 6338.080978258219, 6400.647964362005, 6115.296740874856, 6125.938740612715, 6127.172831667846, 6130.632162070112, 6142.754289828536, 6154.891779651248, 6245.656488893154, 6299.968500157654, 6315.084312774714, 6316.810495685137, 6050.092486127116, 6076.756929722762, 6085.665288409003, 6127.234095738033, 6129.019356902874, 5898.557608179318, 6163.506919446523, 6165.0, 6178.165947597017, 6199.318075011655, 5982.46157845796, 6026.246719160105, 6035.155078289131, 6091.604045737013, 6092.116643086816, 5989.191459153133, 5999.820005399845, 6061.72722227492, 6070.666113363807, 6074.240719910012, 5694.515966142759, 5725.826205627753, 5842.912356314574, 5921.437463440966, 5939.970300148471, 5620.775168993195, 6040.577159598786, 6061.515078793598, 6079.604825686316, 6102.084687296763, 5846.386129456516, 5866.120081987736, 5870.970645146747, 5893.2044174035955, 5914.497267732118], "value": 5591.909571571438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.37859229482405, 504.54338823364867, 518.6602775182256, 521.7339157029915, 544.1918750655274, 31288.843555782245, 38716.0, 38930.0, 39029.80485097574, 39062.41406378903, 41174.17651646973, 41819.372709409385, 41961.0, 42034.579654203524, 42118.05173637847, 9375.390599611037, 9606.375585586915, 9614.0385961404, 9620.615175393012, 9669.999999999965, 9580.80838383232, 9643.22854171668, 9739.610415583367, 9780.90219097807, 9793.951030244874, 9670.75823104421, 9671.75820604487, 9714.854277185848, 9756.121949024586, 9778.364406313567, 8391.62237699305, 9678.951605241999, 9737.26970477217, 9753.707388778348, 9780.608775648965, 9494.430334179971, 9525.380850244712, 9533.713988580354, 9551.379160354554, 9674.75813104678, 38889.22221555553, 38973.61026389728, 39451.01372465683, 39468.21063578741, 39499.81500554988, 3164.8734050638177, 3234.530993006001, 3272.132884785534, 3327.8502467389026, 3661.286049220403, 28416.0, 28693.426131477463, 28767.0, 28771.42457150866, 28781.166314392965, 2763.309172706823, 2767.1975127213086, 2798.9923627494086, 2806.7052959439375, 2833.985830070857, 468833.0, 470456.0, 472469.637651813, 473230.0, 473281.0, 55674.72162639242, 62339.0, 62636.0, 62866.37133628697, 62900.0, 62839.0, 64601.0, 64610.353896461376, 65958.0, 66481.33518664849, 17187.742183867253, 17259.3959211427, 17315.393961211295, 17351.958882467094, 17527.73708394375, 13165.341732913315, 13238.139520931109, 13290.800637990438, 13304.068715189844, 13322.66693332655, 19666.410007699793, 19963.500912477015, 19982.900940448413, 20009.799902001087, 20041.498962526053, 8159.95920020408, 8253.669853205925, 8262.752117436487, 8263.173682631688, 8595.0, 943.8489841625444, 952.6141912525438, 1053.6154303679102, 1076.7738774857369, 1089.0253223365203, 30552.847235764122, 30655.38689226203, 30663.84668076645, 30781.806562201222, 30854.53718194184, 1014.1683819268129, 1113.1484414422973, 1125.189863298424, 1140.3044143072718, 1278.9168704034207, 14686.853131468763, 14770.74448671875, 15671.0, 15730.213489325488, 15840.207989600474, 663645.0, 665913.0, 666695.0, 674929.0, 677940.0, 61097.38902610917, 61182.0, 61568.384316157164, 61707.38292617106, 62131.0, 1255.004542776989, 1455.3378212913099, 1482.2070192446988, 1499.257867355644, 1506.344740038065, 29584.816607336328, 29650.110496685134, 29756.851215743784, 29876.551851721808, 30427.695723042485, 43642.0, 51474.48525514697, 52337.69155771136, 52443.47556524538, 52611.0, 554671.0, 575357.0, 578985.0, 579685.0, 579723.0, 26110.0, 26766.866165669046, 27080.0, 27501.449971000868, 27508.58737118905, 43466.91332716707, 44399.55600443954, 46771.532284676716, 48037.0, 48549.51450485591, 37858.62141378661, 38595.614043860325, 39228.607713922494, 39298.01754956035, 40037.1992560153, 38201.85394438171, 38285.15849066059, 38390.808045959595, 38838.80580597079, 38973.610263897, 31035.913743018886, 31194.22014449657, 34868.82565587156, 35720.0, 37532.0, 29603.0, 29858.253543661584, 32092.83953580217, 32357.838210808794, 32368.676313236567, 19006.524836879187, 24232.909519072036, 24282.02871885105, 24704.876475617508, 24849.50300994006], "value": 19969.634474665443, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.4069917221684, 403.65890822255204, 405.18962075848304, 407.81444442778536, 411.77764007435985, 19898.60202795945, 19925.60148797023, 20665.793342066576, 20667.17331306746, 21000.0, 10653.84019239712, 11752.0, 16313.347466101342, 21260.36218913435, 21281.0, 4698.08387364464, 4988.551030407262, 5421.701806400646, 5440.809571664992, 5534.833954981357, 3912.62830031148, 5444.237806707064, 5457.781688732446, 5511.448855114497, 5780.3063632363965, 6076.848078798043, 6082.209312789331, 6102.20671312731, 6103.969480152593, 6113.480354169901, 5817.941820581782, 6092.634441933476, 6102.72537735803, 6107.297660769002, 6129.570930034899, 5189.169732842727, 5647.576431767634, 5876.882462350772, 5897.911531327033, 5906.0, 12984.480620775157, 13745.725085498334, 13986.93006534971, 13991.510297139552, 13996.93001534996, 2089.937301880946, 2208.381653137124, 2220.944476388087, 2270.1713874435877, 2286.222684287334, 8272.793180170544, 9853.655122070693, 9896.0, 10091.69724908254, 10124.190064794799, 1742.0854051622873, 1794.883332583391, 1797.21821007862, 1811.3479147506887, 1818.6453641539906, 111039.44480277627, 117246.99999999914, 119259.0, 122171.0, 123586.38206808998, 11469.88530114705, 11832.112591555495, 11832.645020649394, 11922.0, 11986.34075125859, 12643.367831608382, 15282.388704451698, 15298.235088245543, 15347.232638368037, 15365.769513457308, 8982.86525702115, 9002.774930626787, 9065.546722663841, 9073.45559266433, 9146.451212927246, 8950.552472376485, 8954.059823718546, 8963.372563920593, 9015.684451044182, 9024.142706442877, 4635.072985402933, 4675.18184317743, 4717.150912835723, 4718.0, 4765.428148622187, 1682.2177687375186, 1835.9328254018737, 1915.5194023739014, 1962.479942815155, 2040.398082565617, 837.5602808525451, 899.3389858454032, 992.717075633441, 997.3766396002499, 1036.461040259061, 17179.742303865452, 20206.09072591737, 20909.686354704692, 21047.368578942656, 21209.15163393478, 955.8183945050419, 995.8058178655093, 1001.6794625719846, 1023.247912784103, 1024.144839059379, 10779.622713205, 11007.0, 11011.889881101086, 11070.501827417786, 11077.612283570037, 517958.0, 525622.0, 528476.0, 544659.0, 574448.0, 134363.32818335845, 137234.0, 137601.0, 139412.0, 141668.0, 997.0777031246068, 1008.2236677758232, 1033.0908800255727, 1045.5765415006933, 1048.5858086056119, 17877.821221787617, 18108.00405977684, 18116.18477168557, 18134.5466363342, 18445.89324640526, 52802.47197528129, 53209.20186197133, 53312.0, 53680.0, 54246.45753542572, 75026.0, 79747.00632484003, 80945.0, 82215.0, 82310.58844705978, 7788.918520284245, 7827.686892524235, 8048.919510804817, 8099.271065603888, 8256.380771442045, 11088.390138542458, 12766.553170638983, 12791.76144174747, 12881.613551593467, 12961.351932403153, 6199.628022318675, 6528.706208220737, 11245.493952771982, 11468.197226194086, 11521.0, 10611.0, 10631.734206644896, 10649.8935010652, 10841.125215842158, 11229.606963756229, 10661.09380702618, 11507.597234096766, 11570.0, 11692.240004399688, 11847.526098955945, 8298.336133109458, 8355.95822020886, 8365.581720914051, 8437.282830959191, 8533.061363249913, 8448.40861139739, 10988.230823842254, 11297.548098075986, 11396.316221026766, 11499.942500287445], "value": 9321.966641847535, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1618.789557357543, 1644.342263094762, 1900.9144588493516, 1954.482062253504, 1974.190581861437, 8042.959785201066, 8433.53615551146, 8664.43681160725, 8971.147740964614, 8995.59519821608, 7103.573785572857, 8322.625481853316, 8443.282321002722, 8445.451045682026, 8488.405811593175, 2427.0777104700232, 2459.213051823417, 2470.777630013298, 2486.8010559155223, 2490.4272017436, 2442.0353960185716, 2449.1305586516796, 2476.839005464639, 2477.355887469253, 2517.8237523373373, 2286.2455389721354, 2400.4838959623676, 2467.605183170693, 2483.31708780086, 2517.886695098716, 2449.534588428202, 2468.8024958003316, 2490.6264060390995, 2492.6634904288053, 2496.6005439129835, 2477.603583426643, 2483.714372847128, 2491.302435318114, 2494.064725727852, 2501.0371007162194, 8786.692465763732, 8847.469151850844, 8851.513166775825, 8851.911480885174, 8879.822403551958, 5324.494173053554, 5507.4767897050115, 5553.7778488860395, 5570.637908535932, 5578.386377498472, 3565.714742820568, 3584.1039740064984, 3594.784312941206, 3695.2424752925585, 3698.6116457772087, 936.5551363102539, 946.2713710442989, 1005.5022763732, 1044.6082718980383, 1080.616381184676, 9699.90300096997, 11565.710857228552, 11693.766124677544, 11717.707057323634, 11758.0, 4816.157172494799, 4946.703197808071, 5034.194528875362, 5064.442911279756, 5079.87300317495, 10196.38821670702, 10207.7448063799, 10210.540525676362, 10221.795564088676, 10246.692599221886, 5410.675359478444, 5490.890182196334, 5491.890162196733, 5539.0, 5587.38538760736, 4125.0, 4465.888352791141, 4909.214525675875, 4954.058728841508, 4955.281484184845, 4083.8979025524004, 4111.392705817149, 4185.068139449117, 4205.137946720914, 4221.472315960505, 1215.6960759810047, 1271.8664540223328, 1317.5981325695564, 1347.9730405391838, 1399.531157062375, 804.1877703519488, 855.0252711908429, 912.6212621762027, 916.9449833010041, 922.8292765838341, 5553.750081246354, 5716.885207384603, 5750.769969201268, 5766.625169363978, 5798.623089499169, 855.8331125430606, 970.9708708738749, 976.4287891583361, 1001.2740762946805, 1047.36634336227, 3696.8336424860945, 3784.6215378462484, 3795.7153213509096, 3797.35444974355, 3853.9979605302265, 10121.493925303705, 10521.84217236742, 10597.940205979494, 10657.253992220625, 10684.572617095384, 9570.33007689469, 10295.558621792956, 10565.630202943014, 10653.307535010199, 10824.24230303871, 6378.617282963037, 8062.750273707585, 8182.877256841152, 8185.590720463953, 8226.876596851052, 5315.149576067811, 5463.989162005042, 5764.942350576441, 5811.56413268998, 5920.200772895604, 9353.11145441182, 9622.422654640744, 9831.0, 9924.603015879573, 9973.30186886911, 7872.84254314899, 10108.545115469675, 10114.140298074746, 10127.139193168368, 10199.337043092175, 6849.657517124224, 7264.382527485223, 7350.889736653848, 7565.167831538416, 7581.166071732216, 8180.609296419504, 8247.628856701584, 8255.25702686761, 8317.667293308443, 8351.20663536963, 7198.6400679964745, 7346.228645992094, 7473.327400533978, 7482.176960534228, 7495.8500829982, 7174.5695258282585, 7259.0, 7261.854762904818, 7304.233055529093, 7345.265473452612, 6670.099536562696, 6808.217055038536, 6816.7614133505085, 6819.89770153458, 6822.727090916308, 4715.764211789466, 4728.172569800203, 4743.549362810528, 4818.915743957722, 4836.95163048365, 4787.664863459524, 4809.519048095162, 4831.830885918976, 4858.78135483897, 5048.798048078036], "value": 4600.716052234256, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.87428429176003, 308.50484971620557, 311.37569173806514, 312.78730463284955, 318.0331791354283, 4630.62954963603, 5459.352214176121, 5465.934142842148, 5529.253550770644, 5538.252335934648, 4321.87034388968, 4696.755359829639, 4700.623950083994, 5333.413324534298, 5412.56699464043, 3368.8147151906637, 4012.9799351003276, 4174.332106862903, 4195.895102622444, 4208.747475151501, 4126.628603425691, 4186.246475634384, 4195.370694395834, 4216.135692183095, 4217.40956266123, 3647.9206852094317, 4158.8336466541305, 4188.246115699173, 4192.727472714279, 4197.286461301571, 4032.7177097603035, 4160.9597600599855, 4177.227212965611, 4192.077742896573, 4206.8737937861915, 3600.567931848169, 3625.474306225609, 3633.6184700606586, 3650.7261955353188, 3654.14127679995, 4682.484926658065, 4718.867471806741, 4785.090832741768, 4862.32249873793, 5240.3187585614005, 4587.449506059261, 4838.491958344393, 4847.878803029917, 4851.878703032417, 4876.804927802884, 5748.655080695172, 5771.134329850514, 5778.59549831512, 5814.883702325929, 5869.148973398876, 3489.930201395983, 3634.545681789776, 3674.7243956703355, 4053.7636021013577, 4066.6340029397497, 5349.3045904032615, 5398.514133727944, 5767.250257466544, 5877.38287479817, 6213.2233470816145, 3444.8794292199655, 3950.27431072559, 3962.6829853611644, 3965.5637879833193, 3991.022199561126, 3479.521203488558, 3856.1130939883983, 3894.980525097413, 4049.6962727795535, 4053.43251944728, 4085.2637629007554, 4462.6530102423185, 4658.394408726912, 4728.1252968201, 4750.881227969328, 4598.436211935709, 5149.562287205629, 5251.317328747238, 5888.146218798251, 5895.145203945404, 1440.5246268731503, 1467.5744034230167, 1471.8454562271022, 1471.8528147185195, 1480.4744315767962, 894.6287290774255, 934.1182452135104, 936.8336421155568, 950.7575568230058, 969.5007071358256, 5342.011727830364, 5669.518090962314, 5744.51171650406, 5797.739101740347, 5819.330776960704, 5549.750261238254, 6042.425969532887, 6511.869762604816, 6576.44100251484, 6585.637789921505, 5143.639945203875, 5528.668279903219, 5545.306836645419, 5706.258186435737, 5795.1886735857015, 2784.7493725564796, 2949.9822561216347, 3163.0, 3361.8487168077495, 3424.4692072728767, 6476.676166191766, 6785.423239024738, 6786.864262714718, 6793.626350550669, 6803.863922721518, 6093.451589356979, 6666.500012499082, 6702.09521714572, 6711.395974362331, 6718.529702920847, 5152.639315247971, 5744.965906136936, 5856.824295271149, 6339.841503962438, 6809.114815074008, 5031.949680503295, 5574.386817449996, 6015.0676645120075, 6131.938680613315, 6169.383061693795, 5053.913408617018, 5998.640081595118, 6001.159837622824, 6051.4553690168095, 6059.8488785832005, 5905.557083218861, 6154.261488621394, 6164.229471316085, 6279.717412716347, 6373.521985850987, 3893.3770596705526, 3910.983144382424, 3938.369860822255, 3940.4877365942243, 3986.3023970805575, 4992.350994370709, 5035.0, 5976.177265933932, 6024.638521688713, 6043.2748070231855, 4838.637102217277, 5082.4409314976065, 5561.833145005657, 5857.970710146422, 5877.177195192591, 5121.206213036985, 5124.74376281177, 5129.640925135354, 5170.250313704567, 5843.590948633555, 4948.505149485023, 5493.862653433696, 5557.5831812613405, 5710.343310519177, 5746.569007324551, 5827.192566133042, 5839.941600583939, 5844.977129002493, 5857.707114644166, 5881.529477641693, 4789.808407663654, 5035.496450354935, 5750.25246717924, 5763.971180144073, 5790.739416726342], "value": 4257.9907909207395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.5930811858825, 690.3614156904862, 733.5525329548977, 744.3301029073837, 773.2036002916994, 14808.259587020646, 14849.77725334118, 14886.702265954687, 14988.700225995488, 14995.25023748811, 13713.108647937877, 13752.931235343809, 14858.33137508812, 15578.221088945564, 15631.296591653374, 723.3453724379445, 921.8110287391069, 924.3344791749929, 935.3405848876525, 935.6538080910038, 901.2204443156688, 932.5383934952208, 934.3272843552666, 935.3779736475236, 938.7230766923774, 970.0832713086137, 980.4509474694158, 984.4880662055726, 989.4755779436874, 991.5538007896439, 941.9199368053723, 942.2791564453199, 975.5951280218701, 980.9215262778961, 984.2765567308059, 698.9196242432134, 710.5559025608994, 720.2293545905883, 724.8586525627504, 725.0429433148247, 15136.697266054727, 15392.538223853302, 15395.61510962235, 15422.614434639112, 15648.4523041693, 10494.317869338545, 12355.0, 12460.314682692448, 12486.12597118202, 12800.295983720891, 8416.915830841674, 9816.214702823829, 9873.506324683736, 10235.0, 10337.966203379601, 3434.7252219822353, 3476.096214984097, 3493.423585108454, 3539.7699149555197, 3562.8040457774814, 77468.0, 79072.0, 79726.0, 80683.57949261532, 80989.59505202436, 7967.721129760431, 9173.0, 9310.767230819283, 9474.57364418589, 9506.431438812657, 20754.066067026717, 20804.751714897153, 20863.79136208649, 20873.478163046046, 20904.686429703568, 7209.387202087882, 7230.9638451807405, 7353.338199562065, 7544.698212071566, 7600.733974310872, 4040.393940908887, 4076.085608709622, 4082.3435159705923, 4090.5295890972325, 4120.567340429272, 3448.4654878493875, 3527.276908233806, 3542.8405721742583, 3543.344481270973, 3551.0, 553.82000849724, 623.5416968528128, 658.9588450248596, 663.9037339585733, 670.5607826873405, 500.69207437425723, 544.080503948326, 549.4725063938664, 574.5977815529063, 591.6627522312241, 7674.462787604814, 7873.842523149505, 8019.679212831538, 8130.227628375294, 8261.215184557457, 512.7871933147702, 572.6535446055077, 584.2229834320344, 591.928609217318, 595.8718875441714, 3225.2904361040532, 3778.112143646241, 4061.1674606705556, 4207.305794543866, 4308.202982448257, 98227.0, 101560.0, 102143.48928255311, 104792.0, 107198.46400768102, 87396.25207495815, 88416.55791721, 88830.0, 90341.0, 91408.5429572848, 80375.59812200902, 80601.19398805937, 80727.19272807315, 80886.0, 81198.18801812024, 7759.495632784076, 7933.722319718782, 7956.045274567089, 8004.399670024655, 8168.142345053684, 45136.097278053596, 45997.08005839931, 46667.766661166475, 46904.29643555418, 47164.52835471739, 56956.86086278334, 63750.0, 67636.6618166906, 68164.6591767038, 68543.65728171327, 10762.677119686421, 10976.725581860197, 11004.449777510932, 11362.03422709079, 11608.071354291806, 11667.533298668297, 11972.0, 12079.4810134777, 12130.939345303217, 12830.16603920742, 9139.817203656023, 10991.39547324873, 11251.987321141136, 11254.21220514556, 11429.028532574825, 11160.441977901235, 11416.31502109876, 11460.739318674772, 11464.551961745477, 11821.113416493625, 10559.260851740304, 10979.890201097887, 11046.889531104585, 11252.66242012741, 11678.6496405108, 7776.922230777846, 9445.0, 9692.951535242279, 9916.107550320507, 10035.849462257926, 9174.0, 9384.29617778683, 9457.574409151743, 9526.71389362455, 9631.422114673142], "value": 6911.142712654873, "unit": "score"}], "name": "dromaeo_css", "value": 7214.013823463513, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 115.0, 118.0, 119.0, 118.0, 135.0, 118.0, 118.0, 117.0, 116.0], "value": 119.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 153.0, 195.0, 153.0, 146.0, 121.0, 163.0, 143.0, 151.0], "value": 153.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 196.0, 199.0, 197.0, 195.0, 200.0, 200.0, 197.0, 196.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 81.0, 141.0, 56.0, 55.0, 57.0, 63.0, 129.0, 55.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 130.0, 78.0, 78.0, 81.0, 118.0, 80.0, 83.0, 115.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 87.0, 91.0, 87.0, 89.0, 87.0, 90.0, 86.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 94.0, 96.0, 115.0, 120.0, 95.0, 96.0, 95.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 146.0, 143.0, 92.0, 89.0, 93.0, 90.0, 88.0, 124.0], "value": 113.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 66.0, 66.0, 67.0, 64.0, 68.0, 81.0, 63.0, 60.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 73.0, 66.0, 76.0, 77.0, 68.0, 68.0, 66.0, 88.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 82.0, 82.0, 83.0, 81.0, 83.0, 88.0, 83.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 151.0, 131.0, 131.0, 149.0, 146.0, 143.0, 185.0, 137.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 176.0, 170.0, 175.0, 176.0, 166.0, 172.0, 168.0, 151.0, 226.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 69.0, 70.0, 69.0, 66.0, 66.0, 70.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1551.3999999999999, "alertThreshold": 2.0}]} 17:35:06 INFO - Return code: 0 17:35:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:35:06 ERROR - # TBPL SUCCESS # 17:35:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:35:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:35:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:35:06 INFO - 2016-04-28 17:35:06.538 screenresolution[1958:17978] starting screenresolution argv=screenresolution get 17:35:06 INFO - 2016-04-28 17:35:06.541 screenresolution[1958:17978] Display 0: 1600x1200x32@50 17:35:06 INFO - 2016-04-28 17:35:06.547 screenresolution[1959:17981] starting screenresolution argv=screenresolution list 17:35:06 INFO - Available Modes on Display 0 17:35:06 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 17:35:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:35:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:35:06 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 17:35:06 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 17:35:06 INFO - Graphics/Displays: 17:35:06 INFO - Intel Iris: 17:35:06 INFO - Chipset Model: Intel Iris 17:35:06 INFO - Type: GPU 17:35:06 INFO - Bus: Built-In 17:35:06 INFO - VRAM (Dynamic, Max): 1536 MB 17:35:06 INFO - Vendor: Intel (0x8086) 17:35:06 INFO - Device ID: 0x0a2e 17:35:06 INFO - Revision ID: 0x0009 17:35:06 INFO - Displays: 17:35:06 INFO - Display: 17:35:06 INFO - Resolution: 1600 x 1200 @ 50 Hz 17:35:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:35:06 INFO - Display Serial Number: 1600x1200 50 17:35:06 INFO - Main Display: Yes 17:35:06 INFO - Mirror: Off 17:35:06 INFO - Online: Yes 17:35:06 INFO - Rotation: Supported 17:35:06 INFO - Return code: 0 17:35:06 INFO - Running post-action listener: _resource_record_post_action 17:35:06 INFO - Running post-run listener: _resource_record_post_run 17:35:07 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 937984; Write bytes: 654121984; Read time: 12; Write time: 2304 17:35:07 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 348701184; Write bytes: 346744320; Read time: 26828; Write time: 701 17:35:07 INFO - run-tests - Wall time: 1172s; CPU: 54.0%; Read bytes: 937984; Write bytes: 296588800; Read time: 12; Write time: 1568 17:35:07 INFO - Running post-run listener: _upload_blobber_files 17:35:07 INFO - Blob upload gear active. 17:35:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:35:07 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 17:35:07 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'] 17:35:07 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 17:35:08 INFO - (blobuploader) - INFO - Open directory for files ... 17:35:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:35:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:35:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:08 INFO - (blobuploader) - INFO - Done attempting. 17:35:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:35:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:35:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:09 INFO - (blobuploader) - INFO - Done attempting. 17:35:09 INFO - (blobuploader) - INFO - Iteration through files over. 17:35:09 INFO - Return code: 0 17:35:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:35:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:35:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69c51450b57acf77568f869321115cd31f36d0771b50404feb2f6f65c3d76e94245e4cba1174db56535f0fdb75672f43ae552d08186e032de48a0c4103b510ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f954af40debea9c5ced891edb651225d937c32708bf5653962699c229139d319502cd26928ba834ac2a700de8651ac1613ca3848003cf89faea5ad9cbda730ff"} 17:35:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:35:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:35:09 INFO - Contents: 17:35:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69c51450b57acf77568f869321115cd31f36d0771b50404feb2f6f65c3d76e94245e4cba1174db56535f0fdb75672f43ae552d08186e032de48a0c4103b510ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f954af40debea9c5ced891edb651225d937c32708bf5653962699c229139d319502cd26928ba834ac2a700de8651ac1613ca3848003cf89faea5ad9cbda730ff"} 17:35:09 INFO - Running post-run listener: copy_logs_to_upload_dir 17:35:09 INFO - Copying logs to upload dir... 17:35:09 INFO - mkdir: /builds/slave/test/build/upload/logs 17:35:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.649689 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-28 17:35:10.168374) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 17:35:10.172235) ========= 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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/69c51450b57acf77568f869321115cd31f36d0771b50404feb2f6f65c3d76e94245e4cba1174db56535f0fdb75672f43ae552d08186e032de48a0c4103b510ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f954af40debea9c5ced891edb651225d937c32708bf5653962699c229139d319502cd26928ba834ac2a700de8651ac1613ca3848003cf89faea5ad9cbda730ff"} build_url:https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011079 build_url: 'https://queue.taskcluster.net/v1/task/dYzZd7v5Q9a4HlTREvXRmw/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/69c51450b57acf77568f869321115cd31f36d0771b50404feb2f6f65c3d76e94245e4cba1174db56535f0fdb75672f43ae552d08186e032de48a0c4103b510ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f954af40debea9c5ced891edb651225d937c32708bf5653962699c229139d319502cd26928ba834ac2a700de8651ac1613ca3848003cf89faea5ad9cbda730ff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 17:35:10.208062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-28 17:35:10.208412) ========= 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.Ul1UWhCCWI/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.E4zalcdnFf/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005205 ========= master_lag: 4.65 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-28 17:35:14.859370) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 17:35:14.859721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 17:35:14.868880) ========= ========= Total master_lag: 5.01 =========