builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0211 starttime: 1464708367.93 results: success (0) buildid: 20160531072841 builduid: af81619562df45009ddadfc41de0f686 revision: 34b32f5cb229db6eafbdaa653d079be1a6ca007e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:07.931147) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:07.931616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:07.931940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:07.953454) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:07.953783) ========= 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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 08:26: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% 6.50M=0.002s 2016-05-31 08:26:08 (6.50 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:08.079152) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:08.079512) ========= 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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.020079 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:08.128858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 08:26:08.129258) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 34b32f5cb229db6eafbdaa653d079be1a6ca007e --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 34b32f5cb229db6eafbdaa653d079be1a6ca007e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 08:26:08,211 truncating revision to first 12 chars 2016-05-31 08:26:08,211 Setting DEBUG logging. 2016-05-31 08:26:08,211 attempt 1/10 2016-05-31 08:26:08,211 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/34b32f5cb229?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 08:26:09,201 unpacking tar archive at: mozilla-aurora-34b32f5cb229/testing/mozharness/ program finished with exit code 0 elapsedTime=1.250904 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 08:26:09.405213) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:09.406070) ========= script_repo_revision: 34b32f5cb229db6eafbdaa653d079be1a6ca007e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:09.407210) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:09.407810) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 08:26:09.424962) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-31 08:26:09.425518) ========= /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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:09 INFO - MultiFileLogger online at 20160531 08:26:09 in /builds/slave/test 08:26: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 08:26:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:26:09 INFO - {'append_to_log': False, 08:26:09 INFO - 'base_work_dir': '/builds/slave/test', 08:26:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:26:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:26:09 INFO - 'branch': 'Mozilla-Aurora', 08:26:09 INFO - 'buildbot_json_path': 'buildprops.json', 08:26:09 INFO - 'config_files': ('talos/mac_config.py',), 08:26:09 INFO - 'default_actions': ('clobber', 08:26:09 INFO - 'read-buildbot-config', 08:26:09 INFO - 'download-and-extract', 08:26:09 INFO - 'populate-webroot', 08:26:09 INFO - 'create-virtualenv', 08:26:09 INFO - 'install', 08:26:09 INFO - 'run-tests'), 08:26:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:26:09 INFO - 'download_minidump_stackwalk': True, 08:26:09 INFO - 'download_symbols': 'ondemand', 08:26:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:26:09 INFO - 'tooltool.py': '/tools/tooltool.py', 08:26:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:26:09 INFO - '/tools/misc-python/virtualenv.py')}, 08:26:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:26:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:26:09 INFO - 'installer_path': 'installer.exe', 08:26:09 INFO - 'log_level': 'info', 08:26:09 INFO - 'log_name': 'talos', 08:26:09 INFO - 'log_to_console': True, 08:26:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:26:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:26:09 INFO - 'opt_config_files': (), 08:26:09 INFO - 'pip_index': False, 08:26:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:26:09 INFO - 'cmd': ('bash', 08:26:09 INFO - '-c', 08:26:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:26:09 INFO - 'enabled': True, 08:26:09 INFO - 'halt_on_failure': False, 08:26:09 INFO - 'name': 'check_screen_resolution'},), 08:26:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:26:09 INFO - 'cmd': ('bash', 08:26:09 INFO - '-c', 08:26:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:26:09 INFO - 'enabled': True, 08:26:09 INFO - 'halt_on_failure': False, 08:26:09 INFO - 'name': 'check_screen_resolution'},), 08:26:09 INFO - 'run_cmd_checks_enabled': True, 08:26:09 INFO - 'sps_profile': False, 08:26:09 INFO - 'sps_profile_interval': 0, 08:26:09 INFO - 'suite': 'dromaeojs-e10s', 08:26:09 INFO - 'system_bits': '32', 08:26:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:26:09 INFO - 'title': 't-yosemite-r7-0211', 08:26:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:26:09 INFO - 'use_talos_json': True, 08:26:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:26:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:26:09 INFO - 'work_dir': 'build'} 08:26:09 INFO - ##### 08:26:09 INFO - ##### Running clobber step. 08:26:09 INFO - ##### 08:26:09 INFO - Running pre-action listener: _resource_record_pre_action 08:26:09 INFO - Running main action method: clobber 08:26:09 INFO - rmtree: /builds/slave/test/build 08:26:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:26:10 INFO - Running post-action listener: _resource_record_post_action 08:26:10 INFO - ##### 08:26:10 INFO - ##### Running read-buildbot-config step. 08:26:10 INFO - ##### 08:26:10 INFO - Running pre-action listener: _resource_record_pre_action 08:26:10 INFO - Running main action method: read_buildbot_config 08:26:10 INFO - Using buildbot properties: 08:26:10 INFO - { 08:26:10 INFO - "project": "", 08:26:10 INFO - "product": "firefox", 08:26:10 INFO - "script_repo_revision": "production", 08:26:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:26:10 INFO - "repository": "", 08:26:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:26:10 INFO - "buildid": "20160531072841", 08:26:10 INFO - "pgo_build": "False", 08:26:10 INFO - "basedir": "/builds/slave/test", 08:26:10 INFO - "buildnumber": 346, 08:26:10 INFO - "slavename": "t-yosemite-r7-0211", 08:26:10 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:26:10 INFO - "platform": "yosemite_r7", 08:26:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:26:10 INFO - "branch": "mozilla-aurora", 08:26:10 INFO - "repo_path": "releases/mozilla-aurora", 08:26:10 INFO - "revision": "34b32f5cb229db6eafbdaa653d079be1a6ca007e", 08:26:10 INFO - "stage_platform": "macosx64", 08:26:10 INFO - "builduid": "af81619562df45009ddadfc41de0f686", 08:26:10 INFO - "slavebuilddir": "test" 08:26:10 INFO - } 08:26:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 08:26:10 INFO - Running post-action listener: _resource_record_post_action 08:26:10 INFO - ##### 08:26:10 INFO - ##### Running download-and-extract step. 08:26:10 INFO - ##### 08:26:10 INFO - Running pre-action listener: _resource_record_pre_action 08:26:10 INFO - Running main action method: download_and_extract 08:26:10 INFO - mkdir: /builds/slave/test/build/tests 08:26: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')]} 08:26:10 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:26:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:26:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:26:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:12 INFO - Downloaded 1199 bytes. 08:26:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 08:26:12 INFO - Using the following test package requirements: 08:26:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:26:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 08:26:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 08:26:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'jsshell-mac.zip'], 08:26:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 08:26:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:26:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 08:26:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 08:26:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 08:26:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:26:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 08:26:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:26: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')]} 08:26:12 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:26:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:26:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:26:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:13 INFO - Downloaded 18463813 bytes. 08:26: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'] 08:26: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 08:26:16 INFO - Return code: 0 08:26: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 08:26: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')]} 08:26:16 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:26:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:26:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:26:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:17 INFO - Downloaded 18463813 bytes. 08:26:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:26:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:26:21 INFO - Return code: 0 08:26: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')]} 08:26:21 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:26:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:26:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:26:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:22 INFO - Downloaded 10840145 bytes. 08:26: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'] 08:26: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 08:26:22 INFO - Return code: 0 08:26: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')]} 08:26:22 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 08:26:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 08:26:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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 08:26:25 INFO - Downloaded 114156846 bytes. 08:26:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:25 INFO - mkdir: /builds/slave/test/properties 08:26:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:26:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:26:25 INFO - Contents: 08:26:25 INFO - build_url:https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:26 INFO - Running post-action listener: _resource_record_post_action 08:26:26 INFO - Running post-action listener: set_extra_try_arguments 08:26:26 INFO - ##### 08:26:26 INFO - ##### Running populate-webroot step. 08:26:26 INFO - ##### 08:26:26 INFO - Running pre-action listener: _resource_record_pre_action 08:26:26 INFO - Running main action method: populate_webroot 08:26:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:26:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:26:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:26:26 INFO - '--tppagecycles', 08:26:26 INFO - '7'], 08:26:26 INFO - 'tests': ['tsvgm']}}, 08:26:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:26:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:26:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:26:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:26:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['damp', 'tps']}, 08:26:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'tests': ['damp', 'tps']}, 08:26:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['dromaeo_dom']}, 08:26:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:26:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['a11yr', 08:26:26 INFO - 'ts_paint', 08:26:26 INFO - 'tpaint', 08:26:26 INFO - 'sessionrestore', 08:26:26 INFO - 'sessionrestore_no_auto_restore', 08:26:26 INFO - 'tabpaint']}, 08:26:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:26:26 INFO - 'ts_paint', 08:26:26 INFO - 'tpaint', 08:26:26 INFO - 'sessionrestore', 08:26:26 INFO - 'sessionrestore_no_auto_restore', 08:26:26 INFO - 'tabpaint']}, 08:26:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:26:26 INFO - 'ts_paint', 08:26:26 INFO - 'tpaint', 08:26:26 INFO - 'sessionrestore', 08:26:26 INFO - 'sessionrestore_no_auto_restore', 08:26:26 INFO - 'tabpaint']}, 08:26:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:26:26 INFO - 'ts_paint', 08:26:26 INFO - 'tpaint', 08:26:26 INFO - 'sessionrestore', 08:26:26 INFO - 'sessionrestore_no_auto_restore', 08:26:26 INFO - 'tabpaint']}, 08:26:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['a11yr', 08:26:26 INFO - 'ts_paint', 08:26:26 INFO - 'tpaint', 08:26:26 INFO - 'sessionrestore', 08:26:26 INFO - 'sessionrestore_no_auto_restore', 08:26:26 INFO - 'tabpaint']}, 08:26:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['a11yr', 08:26:26 INFO - 'ts_paint', 08:26:26 INFO - 'tpaint', 08:26:26 INFO - 'sessionrestore', 08:26:26 INFO - 'sessionrestore_no_auto_restore', 08:26:26 INFO - 'tabpaint']}, 08:26:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['tsvgx', 08:26:26 INFO - 'tsvgr_opacity', 08:26:26 INFO - 'tart', 08:26:26 INFO - 'tscrollx', 08:26:26 INFO - 'cart']}, 08:26:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:26:26 INFO - 'tsvgr_opacity', 08:26:26 INFO - 'tart', 08:26:26 INFO - 'tscrollx', 08:26:26 INFO - 'cart']}, 08:26:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'talos_options': ['--disable-e10s'], 08:26:26 INFO - 'tests': ['tp5o']}, 08:26:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'tests': ['tp5o']}, 08:26:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'talos_options': ['--disable-e10s', 08:26:26 INFO - '--xperf_path', 08:26:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:26 INFO - 'tests': ['tp5n']}, 08:26:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:26 INFO - 'talos_options': ['--xperf_path', 08:26:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:26 INFO - 'tests': ['tp5n']}}, 08:26:26 INFO - 'talos.zip': {'path': '', 08:26:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:26:26 INFO - Running post-action listener: _resource_record_post_action 08:26:26 INFO - ##### 08:26:26 INFO - ##### Running create-virtualenv step. 08:26:26 INFO - ##### 08:26:26 INFO - Running pre-action listener: _resource_record_pre_action 08:26:26 INFO - Running main action method: create_virtualenv 08:26:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:26:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:26:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:26:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:26:26 INFO - Using real prefix '/tools/python27' 08:26:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:26:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:26:30 INFO - Installing pip.................done. 08:26:30 INFO - Return code: 0 08:26:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:30 INFO - 'HOME': '/Users/cltbld', 08:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:30 INFO - 'LOGNAME': 'cltbld', 08:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:30 INFO - 'MOZ_NO_REMOTE': '1', 08:26:30 INFO - 'NO_EM_RESTART': '1', 08:26:30 INFO - 'PAGER': '/bin/cat', 08:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:30 INFO - 'PWD': '/builds/slave/test', 08:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:30 INFO - 'SHELL': '/bin/bash', 08:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:30 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:30 INFO - 'USER': 'cltbld', 08:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:30 INFO - 'XPC_FLAGS': '0x0', 08:26:30 INFO - 'XPC_SERVICE_NAME': '0', 08:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:26:30 INFO - Cleaning up... 08:26:30 INFO - Return code: 0 08:26:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:30 INFO - 'HOME': '/Users/cltbld', 08:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:30 INFO - 'LOGNAME': 'cltbld', 08:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:30 INFO - 'MOZ_NO_REMOTE': '1', 08:26:30 INFO - 'NO_EM_RESTART': '1', 08:26:30 INFO - 'PAGER': '/bin/cat', 08:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:30 INFO - 'PWD': '/builds/slave/test', 08:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:30 INFO - 'SHELL': '/bin/bash', 08:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:30 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:30 INFO - 'USER': 'cltbld', 08:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:30 INFO - 'XPC_FLAGS': '0x0', 08:26:30 INFO - 'XPC_SERVICE_NAME': '0', 08:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:30 INFO - Downloading/unpacking psutil>=0.7.1 08:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:26:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:33 INFO - Installing collected packages: psutil 08:26:33 INFO - Running setup.py install for psutil 08:26:33 INFO - building 'psutil._psutil_osx' extension 08:26:33 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 08:26:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:26:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:26:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:26:34 INFO - building 'psutil._psutil_posix' extension 08:26:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:26:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:26:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:26:34 INFO - ^ 08:26:34 INFO - 1 warning generated. 08:26:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:26:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:34 INFO - Successfully installed psutil 08:26:34 INFO - Cleaning up... 08:26:34 INFO - Return code: 0 08:26:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:26: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')]} 08:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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')]} 08:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:26:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:34 INFO - 'HOME': '/Users/cltbld', 08:26:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:34 INFO - 'LOGNAME': 'cltbld', 08:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:34 INFO - 'MOZ_NO_REMOTE': '1', 08:26:34 INFO - 'NO_EM_RESTART': '1', 08:26:34 INFO - 'PAGER': '/bin/cat', 08:26:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:34 INFO - 'PWD': '/builds/slave/test', 08:26:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:34 INFO - 'SHELL': '/bin/bash', 08:26:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:34 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:34 INFO - 'USER': 'cltbld', 08:26:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:34 INFO - 'XPC_FLAGS': '0x0', 08:26:34 INFO - 'XPC_SERVICE_NAME': '0', 08:26:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:26:37 INFO - Installing collected packages: mozsystemmonitor 08:26:37 INFO - Running setup.py install for mozsystemmonitor 08:26:37 INFO - Successfully installed mozsystemmonitor 08:26:37 INFO - Cleaning up... 08:26:37 INFO - Return code: 0 08:26:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:37 INFO - 'HOME': '/Users/cltbld', 08:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:37 INFO - 'LOGNAME': 'cltbld', 08:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:37 INFO - 'MOZ_NO_REMOTE': '1', 08:26:37 INFO - 'NO_EM_RESTART': '1', 08:26:37 INFO - 'PAGER': '/bin/cat', 08:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:37 INFO - 'PWD': '/builds/slave/test', 08:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:37 INFO - 'SHELL': '/bin/bash', 08:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:37 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:37 INFO - 'USER': 'cltbld', 08:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:37 INFO - 'XPC_FLAGS': '0x0', 08:26:37 INFO - 'XPC_SERVICE_NAME': '0', 08:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:40 INFO - Downloading blobuploader-1.2.4.tar.gz 08:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:26:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:26:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:41 INFO - Downloading docopt-0.6.1.tar.gz 08:26:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:26:41 INFO - Installing collected packages: blobuploader, requests, docopt 08:26:41 INFO - Running setup.py install for blobuploader 08:26:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:26:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:26:41 INFO - Running setup.py install for requests 08:26:42 INFO - Running setup.py install for docopt 08:26:42 INFO - Successfully installed blobuploader requests docopt 08:26:42 INFO - Cleaning up... 08:26:42 INFO - Return code: 0 08:26:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:42 INFO - 'HOME': '/Users/cltbld', 08:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:42 INFO - 'LOGNAME': 'cltbld', 08:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:42 INFO - 'MOZ_NO_REMOTE': '1', 08:26:42 INFO - 'NO_EM_RESTART': '1', 08:26:42 INFO - 'PAGER': '/bin/cat', 08:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:42 INFO - 'PWD': '/builds/slave/test', 08:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:42 INFO - 'SHELL': '/bin/bash', 08:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:42 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:42 INFO - 'USER': 'cltbld', 08:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:42 INFO - 'XPC_FLAGS': '0x0', 08:26:42 INFO - 'XPC_SERVICE_NAME': '0', 08:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:42 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-xTfY0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:42 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-eIGQ8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:42 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-kiLLOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-IiGO7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-N8OYTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-NVPChH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-MeC3L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-arvLYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-trpSVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-EPLTAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-lg44GV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:43 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-eZW2az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-l8ENzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-F1LIjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-5QJa6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-OAuhk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:44 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-NyyPt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:26:44 INFO - Running setup.py install for manifestparser 08:26:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:26:44 INFO - Running setup.py install for mozcrash 08:26:44 INFO - Running setup.py install for mozdebug 08:26:44 INFO - Running setup.py install for mozdevice 08:26:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Running setup.py install for mozfile 08:26:45 INFO - Running setup.py install for mozhttpd 08:26:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Running setup.py install for mozinfo 08:26:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Running setup.py install for mozInstall 08:26:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:26:45 INFO - Running setup.py install for mozleak 08:26:45 INFO - Running setup.py install for mozlog 08:26:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:26:46 INFO - Running setup.py install for moznetwork 08:26:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:26:46 INFO - Running setup.py install for mozprocess 08:26:46 INFO - Running setup.py install for mozprofile 08:26:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:26:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:26:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:26:46 INFO - Running setup.py install for mozrunner 08:26:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:26:46 INFO - Running setup.py install for mozscreenshot 08:26:46 INFO - Running setup.py install for moztest 08:26:46 INFO - Running setup.py install for mozversion 08:26:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:26:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:26:47 INFO - Cleaning up... 08:26:47 INFO - Return code: 0 08:26:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:47 INFO - 'HOME': '/Users/cltbld', 08:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:47 INFO - 'LOGNAME': 'cltbld', 08:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:47 INFO - 'MOZ_NO_REMOTE': '1', 08:26:47 INFO - 'NO_EM_RESTART': '1', 08:26:47 INFO - 'PAGER': '/bin/cat', 08:26:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:47 INFO - 'PWD': '/builds/slave/test', 08:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:47 INFO - 'SHELL': '/bin/bash', 08:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:47 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:47 INFO - 'USER': 'cltbld', 08:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:47 INFO - 'XPC_FLAGS': '0x0', 08:26:47 INFO - 'XPC_SERVICE_NAME': '0', 08:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-D8WotF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-LHvb6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-kEo59M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:47 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)) 08:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-_YPbyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:47 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)) 08:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-5hOrOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:47 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)) 08:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:47 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-4MwGaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-JdOizh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-evCKka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-C_9ORe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-6zvq_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-PkXBV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-jiXqd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-qe7VQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:48 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)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-ePkDoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:48 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)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-piIgnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:48 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)) 08:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:48 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-FtHXOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:49 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-V5qAy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:26:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:51 INFO - Downloading blessings-1.6.tar.gz 08:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:26:51 INFO - Installing collected packages: blessings 08:26:51 INFO - Running setup.py install for blessings 08:26:51 INFO - Successfully installed blessings 08:26:51 INFO - Cleaning up... 08:26:51 INFO - Return code: 0 08:26:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:26:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:52 INFO - Reading from file tmpfile_stdout 08:26:52 INFO - Current package versions: 08:26:52 INFO - blessings == 1.6 08:26:52 INFO - blobuploader == 1.2.4 08:26:52 INFO - docopt == 0.6.1 08:26:52 INFO - manifestparser == 1.1 08:26:52 INFO - mozInstall == 1.12 08:26:52 INFO - mozcrash == 0.17 08:26:52 INFO - mozdebug == 0.1 08:26:52 INFO - mozdevice == 0.48 08:26:52 INFO - mozfile == 1.2 08:26:52 INFO - mozhttpd == 0.7 08:26:52 INFO - mozinfo == 0.9 08:26:52 INFO - mozleak == 0.1 08:26:52 INFO - mozlog == 3.1 08:26:52 INFO - moznetwork == 0.27 08:26:52 INFO - mozprocess == 0.22 08:26:52 INFO - mozprofile == 0.28 08:26:52 INFO - mozrunner == 6.11 08:26:52 INFO - mozscreenshot == 0.1 08:26:52 INFO - mozsystemmonitor == 0.0 08:26:52 INFO - moztest == 0.7 08:26:52 INFO - mozversion == 1.4 08:26:52 INFO - psutil == 3.1.1 08:26:52 INFO - requests == 1.2.3 08:26:52 INFO - wsgiref == 0.1.2 08:26:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:26:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:52 INFO - 'HOME': '/Users/cltbld', 08:26:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:52 INFO - 'LOGNAME': 'cltbld', 08:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:52 INFO - 'MOZ_NO_REMOTE': '1', 08:26:52 INFO - 'NO_EM_RESTART': '1', 08:26:52 INFO - 'PAGER': '/bin/cat', 08:26:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:52 INFO - 'PWD': '/builds/slave/test', 08:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:52 INFO - 'SHELL': '/bin/bash', 08:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:52 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:52 INFO - 'USER': 'cltbld', 08:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:52 INFO - 'XPC_FLAGS': '0x0', 08:26:52 INFO - 'XPC_SERVICE_NAME': '0', 08:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:26:52 INFO - Cleaning up... 08:26:52 INFO - Return code: 0 08:26:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:26:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:52 INFO - 'HOME': '/Users/cltbld', 08:26:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:52 INFO - 'LOGNAME': 'cltbld', 08:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:52 INFO - 'MOZ_NO_REMOTE': '1', 08:26:52 INFO - 'NO_EM_RESTART': '1', 08:26:52 INFO - 'PAGER': '/bin/cat', 08:26:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:52 INFO - 'PWD': '/builds/slave/test', 08:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:52 INFO - 'SHELL': '/bin/bash', 08:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:52 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:52 INFO - 'USER': 'cltbld', 08:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:52 INFO - 'XPC_FLAGS': '0x0', 08:26:52 INFO - 'XPC_SERVICE_NAME': '0', 08:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:52 INFO - Downloading/unpacking jsonschema 08:26:52 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 08:26:52 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 08:26:52 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 08:26:52 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 08:26:52 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 08:26:52 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 08:26:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:26:55 INFO - Downloading/unpacking functools32 (from jsonschema) 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:55 INFO - Downloading functools32-3.2.3-2.tar.gz 08:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:26:55 INFO - warning: no files found matching '*.txt' 08:26:55 INFO - no previously-included directories found matching 'build' 08:26:55 INFO - no previously-included directories found matching 'dist' 08:26:55 INFO - no previously-included directories found matching '.git*' 08:26:55 INFO - Installing collected packages: jsonschema, functools32 08:26:55 INFO - Running setup.py install for functools32 08:26:55 INFO - warning: no files found matching '*.txt' 08:26:55 INFO - no previously-included directories found matching 'build' 08:26:55 INFO - no previously-included directories found matching 'dist' 08:26:55 INFO - no previously-included directories found matching '.git*' 08:26:55 INFO - Successfully installed jsonschema functools32 08:26:55 INFO - Cleaning up... 08:26:55 INFO - Return code: 0 08:26:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:26:55 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')]} 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:55 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')]} 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:55 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 0x10773da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10774d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff542d73780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, '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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 08:26:55 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 08:26:55 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 08:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:55 INFO - 'HOME': '/Users/cltbld', 08:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:55 INFO - 'LOGNAME': 'cltbld', 08:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:55 INFO - 'MOZ_NO_REMOTE': '1', 08:26:55 INFO - 'NO_EM_RESTART': '1', 08:26:55 INFO - 'PAGER': '/bin/cat', 08:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:55 INFO - 'PWD': '/builds/slave/test', 08:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:55 INFO - 'SHELL': '/bin/bash', 08:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:26:55 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:55 INFO - 'USER': 'cltbld', 08:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:55 INFO - 'XPC_FLAGS': '0x0', 08:26:55 INFO - 'XPC_SERVICE_NAME': '0', 08:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:56 INFO - Downloading/unpacking flake8 08:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:26:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:26:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:26:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:26:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:26:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:26:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:26:59 INFO - no previously-included directories found matching 'docs/_build' 08:26:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:26:59 INFO - Running setup.py install for pep8 08:27:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:27:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:27:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:27:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:27:00 INFO - no previously-included directories found matching 'docs/_build' 08:27:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:27:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:27:00 INFO - Cleaning up... 08:27:00 INFO - Return code: 0 08:27:00 INFO - Running post-action listener: _resource_record_post_action 08:27:00 INFO - Running post-action listener: _start_resource_monitoring 08:27:00 INFO - Starting resource monitoring. 08:27:00 INFO - ##### 08:27:00 INFO - ##### Running install step. 08:27:00 INFO - ##### 08:27:00 INFO - Running pre-action listener: _resource_record_pre_action 08:27:00 INFO - Running main action method: install 08:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:27:00 INFO - Reading from file tmpfile_stdout 08:27:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:27:00 INFO - Reading from file tmpfile_stdout 08:27:00 INFO - Output received: 08:27:00 INFO - Usage: mozinstall [options] installer 08:27:00 INFO - Options: 08:27:00 INFO - -h, --help show this help message and exit 08:27:00 INFO - -d DEST, --destination=DEST 08:27:00 INFO - Directory to install application into. [default: 08:27:00 INFO - "/builds/slave/test"] 08:27:00 INFO - --app=APP Application being installed. [default: firefox] 08:27:00 INFO - mkdir: /builds/slave/test/build/application 08:27:00 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'] 08:27:00 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 08:27:32 INFO - Reading from file tmpfile_stdout 08:27:32 INFO - Output received: 08:27:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:27:32 INFO - Running post-action listener: _resource_record_post_action 08:27:32 INFO - ##### 08:27:32 INFO - ##### Running run-tests step. 08:27:32 INFO - ##### 08:27:32 INFO - Running pre-action listener: _resource_record_pre_action 08:27:32 INFO - Running main action method: run_tests 08:27:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:32 INFO - 2016-05-31 08:27:32.695 screenresolution[1950:11173] starting screenresolution argv=screenresolution get 08:27:32 INFO - 2016-05-31 08:27:32.698 screenresolution[1950:11173] Display 0: 1600x1200x32@60 08:27:32 INFO - 2016-05-31 08:27:32.703 screenresolution[1951:11175] starting screenresolution argv=screenresolution list 08:27:32 INFO - Available Modes on Display 0 08:27:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:32 INFO - Intel Iris: 08:27:32 INFO - Chipset Model: Intel Iris 08:27:32 INFO - Type: GPU 08:27:32 INFO - Bus: Built-In 08:27:32 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:32 INFO - Vendor: Intel (0x8086) 08:27:32 INFO - Device ID: 0x0a2e 08:27:32 INFO - Revision ID: 0x0009 08:27:32 INFO - Displays: 08:27:32 INFO - Display: 08:27:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:32 INFO - Display Serial Number: 1600x1200 60 08:27:32 INFO - Main Display: Yes 08:27:32 INFO - Mirror: Off 08:27:32 INFO - Online: Yes 08:27:32 INFO - Rotation: Supported 08:27:32 INFO - Return code: 0 08:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:27:32 INFO - Python 2.7.3 08:27:32 INFO - Return code: 0 08:27:32 INFO - grabbing minidump binary from tooltool 08:27: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')]} 08:27:32 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 0x1075dce00>, '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 0x107445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:27:32 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 08:27:32 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 08:27:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:27:33 INFO - Return code: 0 08:27:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:27:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:27:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:27:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:27:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:27:34 INFO - Return code: 0 08:27:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0211', '--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 08:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0211 --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 08:27:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9s3uSDsjGr/Render', 08:27:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:34 INFO - 'HOME': '/Users/cltbld', 08:27:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:34 INFO - 'LOGNAME': 'cltbld', 08:27:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:27:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:34 INFO - 'MOZ_NO_REMOTE': '1', 08:27:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:27:34 INFO - 'NO_EM_RESTART': '1', 08:27:34 INFO - 'PAGER': '/bin/cat', 08:27:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:34 INFO - 'PWD': '/builds/slave/test', 08:27:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:27:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:34 INFO - 'SHELL': '/bin/bash', 08:27:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CUnFfWP7gJ/Listeners', 08:27:34 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 08:27:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:34 INFO - 'USER': 'cltbld', 08:27:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:34 INFO - 'XPC_FLAGS': '0x0', 08:27:34 INFO - 'XPC_SERVICE_NAME': '0', 08:27:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0211', '--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 08:27:34 INFO - mozversion application_buildid: 20160531072841 08:27:34 INFO - mozversion application_changeset: 34b32f5cb229db6eafbdaa653d079be1a6ca007e 08:27:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:27:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:27:34 INFO - mozversion application_name: Firefox 08:27:34 INFO - mozversion application_remotingname: firefox-dev 08:27:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:27:34 INFO - mozversion application_vendor: Mozilla 08:27:34 INFO - mozversion application_version: 48.0a2 08:27:34 INFO - mozversion platform_buildid: 20160531072841 08:27:34 INFO - mozversion platform_changeset: 34b32f5cb229db6eafbdaa653d079be1a6ca007e 08:27:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:27:34 INFO - mozversion platform_version: 48.0a2 08:27:34 INFO - using testdate: 1464708454 08:27:34 INFO - actual date: 1464708454 08:27:34 INFO - starting webserver on 'localhost:49435' 08:27:34 INFO - SUITE-START | Running 2 tests 08:27:34 INFO - TEST-START | dromaeo_css 08:27:34 INFO - operating with platform_type : mac_ 08:27:34 INFO - Initialising browser for dromaeo_css test... 08:27:34 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpaUVQPA/profile http://localhost:49435/getInfo.html) 08:27:36 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 08:27:36 INFO - PROCESS | 1969 | colorDepth:24 08:27:36 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 08:27:36 INFO - PROCESS | 1969 | __metrics 08:27:37 ERROR - PROCESS | 1969 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 08:27:37 INFO - TEST-INFO | 1969: exit 0 08:27:37 INFO - Browser initialized. 08:27:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:27:37 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpaUVQPA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:49 INFO - PROCESS | 1972 | RSS: Main: 220897280 08:27:49 INFO - PROCESS | 1972 | 08:28:39 INFO - PROCESS | 1972 | 1464708519289 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] 08:30:50 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49435/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49435/tests/dromaeo/cssquery-ext.html) 08:30:52 INFO - PROCESS | 1972 | RSS: Main: 236290048 08:30:52 INFO - PROCESS | 1972 | 08:33:54 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49435/tests/dromaeo/cssquery-ext.html (next: http://localhost:49435/tests/dromaeo/cssquery-jquery.html) 08:33:55 INFO - PROCESS | 1972 | RSS: Main: 248242176 08:33:55 INFO - PROCESS | 1972 | 08:36:56 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49435/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49435/tests/dromaeo/cssquery-mootools.html) 08:36:57 INFO - PROCESS | 1972 | RSS: Main: 250114048 08:36:57 INFO - PROCESS | 1972 | 08:39:59 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49435/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49435/tests/dromaeo/cssquery-prototype.html) 08:39:59 INFO - PROCESS | 1972 | RSS: Main: 251809792 08:39:59 INFO - PROCESS | 1972 | 08:43:02 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49435/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49435/tests/dromaeo/cssquery-yui.html) 08:43:03 INFO - PROCESS | 1972 | RSS: Main: 254631936 08:43:03 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49435/tests/dromaeo/cssquery-yui.html (next: http://localhost:49435/tests/dromaeo/cssquery-dojo.html) 08:46:05 INFO - PROCESS | 1972 | RSS: Main: 255528960 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | __start_tp_report 08:46:05 INFO - PROCESS | 1972 | _x_x_mozilla_page_load 08:46:05 INFO - PROCESS | 1972 | _x_x_mozilla_page_load_details 08:46:05 INFO - PROCESS | 1972 | |i|pagename|runs| 08:46:05 INFO - PROCESS | 1972 | |0;dojo.html;3236.3850868335016;3498.930021399572;3547.846949741335;3553.6268691787363;3559.3237284915863;5774.595778295521;5799.072148456248;5827;5831.737571809268;5843.269591301082;5806.767729290834;5813.854653633662;5835.241418615585;5870.853228669285;5882.088276317178;5280.947190528093;5314.202869569576;5382.111951527993;5386.272853164832;5408.810691625794;4100.343944968806;5175.120229560965;5179.896402071975;5184.1705327147665;5205.7136857473015;5306.965141797351;5334.493223143795;5337.279467271907;5341.385740639818;5355.732213389334;4362.432883725111;5302.443243459458;5306.6550674206055;5313.9734301328435;5408.756605952742;5247.606429517801;5264.131418315973;5314.548263397616;5360.812371567015;5372.355317361902;5603.271574695264;5644.915326270109;5674.886502269931;5679.2333035040565;5741.712914354265;6123.265208175003;6216.222972128484;6295.433410992987;6325.778597749103;6326.398992095744;6301.68491575424;6309.779157729503;6318.178636777236;6319.210098737658;6334.461570766491;5958.791442299499;5963.314218864843;5971.940280597225;5978;5997.0404735242155;6256.843578910472;6338.366163383717;6385.297617262097;6394.3925327094785;6414.262359828679;5707.514861236758;5810.767569297265;6258.468030217391;6270.655113968684;6283.96858015707;5236.607254455855;6264.903641862656;6350.237971443456;6352.36476352361;6357.809265722036;4928.926066109011;5439.48324909133;5532.2531458253425;6304.054391841169;6338.904916426257;6211.596246243979;6269.529785266123;6310.4005119514495;6310.968445157837;6328.303886572473;4256.2764330063965;4924.064427758714;4956.231784073474;5004.174311238605;5027.371578552681;4114.691398145151;4447.755373454426;4465.352523884018;4502.099580083996;4505.234110201208;5657.811859509549;5828.213191219216;5833.854153646108;5856.677882716406;5864.706764661835;5781.392953739881;6161.537884658668;6201.317855035943;6269.216347956505;6341.143945567289;5791.044477661138;5838.211841401357;5843.035899076604;5863.2670916135485;5874.32445268791;5469.753861076261;5475.972620136874;5484.149956756709;5486.396496385394;5494.1758736189095;6267.749290028531;6394.904076438764;6444.581102228341;6445;6448.516361272923;6287.18266625354;6301.558890877594;6304.369563043659;6306.432421082125;6312.810615681537;6426.485881129592;6450.032495125581;6452.483801295979;6457.09600655918;6458.321876203119;6115.879294474606;6200.7519699211525;6256.499480041494;6263.74945002213;6299.905501417391;6255.718492667932;6281.968590157203;6290.370962903673;6307.590006649553;6322.715477803418;6088.939110608837;6190.566660333733;6235.127082208585;6255.24937007562;6271.905921411274;6069.696515174312;6106.938930610637;6175.320714721287;6213.596116252429;6246.562740608114;5746.16680581305;5875.353711091691;5986.910196347146;6072.817815465543;6115.3578874217565;6053.424924632153;6208.9689551551955;6212.161358216764;6222.937770622236;6244.063390491463;5960.612560183574;5979.701014949323;6064.3935606439;6076.240469941257;6128.686460748315 08:46:05 INFO - PROCESS | 1972 | |1;ext.html;466.2051202699398;469.92246279363894;500.68707058088694;546.5709418106786;548.1476304346744;35891;39259.80370098152;39400.80299598502;39402.40896386553;39593;32788.67211327886;39999.60000399995;40726.7963660182;40918.97702557438;41034.79482602583;9526.523673816317;9656.130948214659;9682.903170968271;9707.271954603397;9726.513674316291;9532.475713835736;9661.71014869555;9692.66075687351;9787.559559819789;9804.725385699849;9647.662331818388;9757;9781.217502599775;9806.068423499755;9841.311108222428;9689.418634881895;9772.21822254218;9784.21726261901;9792.069753373418;9796.118349348593;9605.598600349913;9608.51957402134;9753.414795112316;9753.853692194623;9767.365121267167;39245.215095698215;39375;39682.40476392856;39854.80435586937;39864.60135398638;3297.4229509835923;3468.2196505785996;3546.627604101558;3665.376885929372;3900.8049597520294;27793.305167370774;28196;28692.426151476855;28985.56521652177;29707.960221392146;2747.5466548019554;2774.2370848016753;2777.874995625202;2815.5354366529705;2836.3618185908213;481552;485984;487540;488696;490014;35625.46561801575;59605;59919.400805992256;60122.69938650279;60133.69933150306;35484;35829.28341433157;35957.64042359595;36119.09702257464;36136;13120.737585248242;15421.306041228172;15538;15806.446774362843;15843;13150.013748968866;13241.867581324064;13249.867501325056;13283.535076272477;13292.268925209013;8108.675652973933;8217.465864718773;8217.876731849026;8270.710525131592;8315.875261871077;2988.5666578346454;3350.8104622859023;3356.3958487472;3365.528825964367;3426.7429942754393;1019.2202964731986;1021.8569400283966;1027.9074883260541;1040.6736574515328;1093.786711591232;25079.746012699292;29069.273268168465;30244.54633180504;30385.392292154476;30449.543256851164;1109.2955972957068;1113.9108871290277;1135.971600709989;1214.872438393956;1236.975260494803;14043.438262469592;15300.540983770505;15364.462243821412;15539.844601554067;15564.533064008097;629472;636445;640964;641054;642443;58872;59503;59721.70139149363;59849;59901.700491497264;1159.3217967488945;1423.9715205695827;1446.6889618732023;1451.3468938977292;1453.1862157191795;26307.68461576875;27556.586651199847;28993.56509652399;29663.703362966957;29836.850815745784;57877.71061144667;58351.41648583629;58713.706431467566;59056.70471647786;59305.406945929986;604809;607687.9615601894;611043;611577;611856;41461.37807932823;42427.575724242364;42476;44570.10859782722;44722.77638611786;42297.365539516315;42896.571034289256;42984.35523467213;47545;47979.5202047989;35126;35680;35934.64065359418;36074.63925360713;36305.27389452144;35167.29665406626;35213.823930881204;35459.64540354667;35481;35622;32194.517082243317;35727.82136089303;35820.462693060144;37461;38138.42792358172;30318.09045728632;31574.210644733146;31770.04689859308;31855.84072079625;31959.840200798848;20265.594688106452;24478.020879164633;24676.506469870863;24699.259022229362;25053.12314068999 08:46:05 INFO - PROCESS | 1972 | |2;jquery.html;367.95032670589467;393.4943597477242;397.4554859842017;401.39589917174646;406.6482492643862;12599.866012058912;20068.799312006875;20149.59700805985;20860.061297241624;20870.89564552179;17447.691423143253;20575.588488230263;20692.689609655867;20740.8962955185;20936.476588085305;5795.246617939672;5869.8239052828485;6079.422454866781;6131.448169664729;6166.969165154191;5977.910331345033;6062.696865156747;6088.66512341821;6097.298810636789;6105.511559075263;6016.308124565688;6046.727897244635;7284.344409003189;7573.772786816405;7719.035120609924;7372.778816635509;7583.014208152932;7595.088589369283;7768.650410731474;7838.843223135563;7151.7854464366155;7562.054743157106;7610.0106986092005;7613.200613935513;7616.543007419573;23182.42043948898;27590.862045689842;28278;28319.584020799084;29158;1976.7726711428227;2751.7385848344375;2821.463921854841;3131.2015436063884;3162.5414314924437;15062.548123556311;15667;15728.606784830356;15957.76063359039;16392.590185245463;1609.3321271672241;1798.658254931572;1868.8972106534136;1894.943151705451;1901.8668693191482;116747.416262919;122472;135587.32206339005;135820;139721.99999999898;20532.281370151974;21636.242731504637;21715.565688686136;22174.891255437164;22479.21322753696;14283.78574321386;14854.777178342334;35314;36016.63983360133;36596.268074638356;8496.872546911678;8796.384253102222;8912.108789121035;9034.909650903539;9201.723948281562;8910.019897811235;9020.8646870297;9112.908870911338;9363.438193708263;9404.905950940403;4581.5647513486165;4727.692699974491;4743.857684269477;4749.453812811502;4757.500462451462;1992.8604997650025;2023.3120738948828;2034.7587971337473;2040.4796776821815;2052.3945436096087;862.2326129744532;906.4515967839494;959.5873774277128;961.9134277915022;1059.5496913811598;20215.898920505304;20299.391018269474;20633.89683051575;20905.268315608882;20975.26586569493;967.2358836519071;985.0600876713429;990.8464188050864;995.069609914732;1017;9063.501507417024;10980.560777568808;11008.064314533354;11120.99911008013;11122.943320384551;449853;621856;643671;644832;644970;130313;133124;133706;135986;136137;874.2699845628971;961.3510880155867;965.0638880286111;970.1898914406408;1007.3351587951917;18312.514262469394;18382.540436489195;18801.717974230673;18905;18928.621427571095;38003.61996380112;55033;55519.722401387735;56660.71669641625;56788;68800;71995;75585.62207188929;76018.61990690012;76183.61908190424;7911.802204944923;8084.63619137152;8089.959550202447;8230.547319897463;8307.958460207661;10293;12358.382080895739;12588.622341329774;12703.809442858179;12818.038647101319;10731.463426828472;10990.725231868948;11179.60871369498;11285.605003824827;11301.208915375844;10761.73095672583;10768.0847127995;10964.780704385708;11032.669019929415;11165.832512512156;11184.88151184875;11207.775844483227;11255.831162532731;11325.093992480746;11401.462896614734;8186.099529051919;8238.588070596566;8318.667253309799;8429.662813487392;8586.441881277697;10214.62102616167;10531.684049478528;10757.892421076001;11129.721756956142;11344.432778361213 08:46:05 INFO - PROCESS | 1972 | |3;mootools.html;1342.610642913555;1579.8815088868337;1751.413276552355;1843.9907800460996;1870.6913359295718;7901.249381308781;7977.282044615983;8637.697680581181;9006.099390061;9032;7409.555426674403;8777.2100510954;8809.471431714102;8902.465852048865;8918.598663060162;2418.1053010386136;2422.624493203556;2451.0563433078214;2453.386653336666;2457.9016839326405;2441.914532991346;2461.889214985321;2499.6375525548783;2505.9373515662164;2514.924552263426;2407.5425669122897;2446.657467954488;2446.877656117196;2474.6164344526537;2486.403263216824;2452.926412207628;2464.7658472445178;2487.4776296977657;2488.203774792067;2489.3776555861036;2438.244144315268;2441.584930561809;2460.9507809843703;2468.9753102468926;2490.63885736569;9010.549472526347;9032.593533290952;9036.909630903672;9049.954750226272;9088.77278068047;5469.371022332403;5571.331440227158;5595.608307418483;5626.156076588503;5636.2954630671165;3366.73066154707;3370.410178218802;3418.4017796885446;3483.651634836496;3504.702100321476;874.5627186406797;1045.7542477517777;1073.3023534702427;1625.5854757036998;1673.4979506148106;11822.822657660055;11855.110866684947;11934.40327983606;11939.522419103225;11953.701157471045;4464.794505483501;4591.931121033187;4597.482830665872;4665.253559430543;4752.5485078917445;9352.485613291334;9396.530173491436;9417.434953902788;9420.764480887896;9456.810863782686;5378.408375078739;5418.620696551204;5456.454354564512;5463.989162005042;5464.508194262536;4408.713433626804;4872.171730805771;4932.309476673268;4950.257461380822;5027.321311622957;3232.515122731609;3884.8834534964;3916.7845768483007;3977.54258260298;4015.15681706839;1040.4069680282166;1214.8359971403925;1231.6612931443744;1242.9067819913544;1247.3451437995125;912.8721978922956;918.0910898210857;930.4696323095906;936.5691781780457;956.1681337236648;5963.522918166623;6028.216331876828;6039;6095.725692343855;6125.729253482617;464.21315869601375;470.51066890433566;470.82814772608435;473.6471328860021;477.10066524601376;3806.864920108671;3927.253821773854;3960.663343615767;3996.7002474814503;4031.1534577739003;10672.519736611872;10870.456477176109;11046.558137674403;11192.272502287326;11216;10849.67450976472;10882.40146791934;10931.18016148776;10938.890611093946;10971.506282217319;8127.796805079802;8394.45436046655;8404.537750423786;8411.242988131098;8444.535550544779;5772.422757724194;5853;5965.70171491415;5987.820365389045;6064.818055458343;9577.808443831223;9621.326507144433;9651.71044868655;9680.515974201402;9707.514624268899;9648.855267171139;9844.556994935389;9908.25688073383;9910.008999100033;10316.329438586468;6869.896951545831;7307.196208417176;7542.660580273791;7637.923620763721;7765.378769698297;8280.627371768165;8312;8355.331573473986;8368.916310836814;8414.284785793276;6478;7369.37360324357;7616.200298968529;7642.4650274480255;7729.652165652672;7412.1846596875375;7419.851602967802;7462.403007759258;7493.363064139609;7555.13115991668;7071.398931090915;7096.042034325506;7144.178419481618;7157.856842863218;7164.785056448315;4781.14713565738;4811.49479304668;4858.27125931099;4902.558769710743;4933.235348520985;4133.565975572522;4288.871333859989;4915.139850526072;4955.677880937727;5030.3963524377305 08:46:05 INFO - PROCESS | 1972 | |4;prototype.html;321.6381570732925;324.2202502980332;332.3668411675758;335.74315648528864;335.9160209947513;5781.075027995522;6579.342065793418;6683.665816709164;6736.282248026752;6779.4576433885295;5606.439356064391;6072.240969878765;6703.430208432289;6742.393184613384;6808.693608787604;4508.797104130305;5016.3227964224725;5021.995600879821;5025.648204625687;5032.9748351258195;4119.5880411958715;4258.638015768648;4486.887827804315;4831.541003604652;5049.823256186034;4941.703497790144;4946.604271658277;4962.925556116661;4978.726170060645;4998.175301075318;4669.906601867943;4964.131277026542;4964.354633897588;4970.478099799523;4987.351644286256;4105.220008198463;4130.876073717794;4134.441850350195;4134.607212314825;4152.875413737593;4956.157453232959;6409.230892292955;6477.384648458389;6493.740250389978;6502.90245646311;5697.715114244313;5790.681512516852;5823.9708801456145;5830.30036395631;5852.941470585282;7260.96369518149;7262.491625586158;7268.709251629928;7270.746068718714;7283.7086516539275;4509.7970591323465;4669.813207471697;4673.696209746356;4674.906501869978;4681.438227412698;7489.28851759082;7579.962100189464;7583.469157158946;7601.923980760177;7619.657115429819;3835.1562656215588;4687.554682305244;4704.1767690654;4705.4588722296685;4737.957649317144;4807.062622788592;4808.855734327975;4821.180399332122;4822.493638168012;4823.638227132979;5719.256496655408;5757.453041961007;5776.075827867605;5781.739821708034;5788.739506722123;7007.754728584475;7189.604571748604;7298.416126709851;7304.48868579205;7379.778606641809;1633.5997680568337;1652.2564845819188;1660.4852495726122;1664.8002239731068;1670.0647637323138;1045.0594464981425;1045.46158728255;1062.2617280989793;1065.8720953485476;1065.8827528971806;4758.976205118952;5839.532837373;6798.150231221098;6811.318868113248;6817.693203805741;5785.797497087582;7624.466287359832;7676.731314404081;7679.155292917774;7688.654010569538;6778.559393639398;6786.287439818847;6798.66006699663;6822.976553517012;6823.249442561314;3963.286608410514;3988.940165897514;4011.7793521356607;4024.75851448914;4045.9480535060507;7658.387329013665;7969.800754981172;7978.042634883851;7983.20167983197;7985.441019128722;7875.763727088197;7894.486858354188;7895.447318687751;7907.960460197777;7918.208179182151;7936.523808571389;7980.4812687175145;8001.959745233083;8004.359651227888;8009.078955920144;7314.780556583311;7612.619369031637;7618.047744031996;7627.046619172604;7638;7462.104547454123;7466.104067511933;7519.022527071445;7525.397968162424;7538.886916696144;7879.330256928225;7959.801004974922;7990.640421181177;7990.760277191694;8017.278444939983;4719.10337035962;4755.286706993978;4756.3352826510445;4761.262004389325;4767.427908650984;7267.963660181666;7556.962215188889;7572;7594.050743657043;7619.847603048019;7446.664900079401;7480.326770590673;7521.097468303619;7525.88711169322;7549.622518874145;5987.371326010706;7295.927040729524;7320.780376588711;7371.041764570571;7383.520071195355;7118.893216601651;7143.178534468602;7217.891731624135;7304.561726296439;7331.706731730671;7136;7273.781786546412;7336.266373362621;7361.889571656537;7400.666969986256;6838.487113466411;7014.964925175342;7132.322429369202;7189.964050179716;7207.387372073433 08:46:05 INFO - PROCESS | 1972 | |5;yui.html;679.3953381490472;691.8616276744652;734.4528326396835;750.0736590310966;758.260695821574;14274.643133921658;14891.851081489196;15004.474843380483;15279.159646219481;15282.465113721022;11149.609763658274;14112.364943577539;14116.858831411682;14122.43510259591;14175.007749457542;731.0715391452852;899.5502248875562;901.324006994754;907.2877790934118;915.693242763675;875.2341701011615;878.8462019146624;914.4650379527984;917.8760867282931;922.0502882031518;835.7534527314457;966.2897770138942;966.4104896013426;967.816114938163;969.961201551937;921.9907800922039;948.2650945517211;949.3829011142743;954.3033585482602;957.9329446938717;690.3890057299276;705.8517711280638;714.2857142857143;718.4755128756037;719.9560637076261;14491.492797752027;14556.854431455657;14746.852531474655;14919.403223871032;15002.69994600102;12590.24458532482;12666.430010649543;12684.55604053863;12732.172408793398;12826;9995.600175993024;10127.240456965757;10212;10407.5836966522;10457.372557646566;3473.1838018065732;3525.735569832247;3534.2754735279204;3552;3616.5479315085613;59933.7003314985;61043.999999999556;78309.60845195738;78450.6077469609;78663.60668196679;9249.861252081224;9555.952220238854;9620.37467564606;9704.660336888175;9754.609815607439;20911.954402279825;21527.78472215289;21650.458738531663;21689.34931952044;21759.347219583437;7131.82170445743;7401.703931842666;7407.518511296748;7472.215417381205;7482.438817088631;3647.85408583659;4031.5968403160036;4085.7957102144774;4172.332426811757;4173.4157217989505;3222.258880457461;3541.4333706607335;3614.566252049718;3617;3625.1118475973553;660.4584240922397;662.8276648071536;664.3323459922747;669.1802541886151;672.085963090197;498.70077953227775;601.9608725432832;605.7274226598048;617.9165812615329;625.0936142593258;8264.586770661563;8296.377771667148;8315.376346774015;8355.791105222297;8390.538520381439;544.3413469701626;565;611.8562137897679;619.5074915442234;624.6283461340495;3086.4907290297297;3808.1812410331913;3861.6717579005535;3992.201559688074;4091.3862920562156;81129.18870811335;83192;83216;83234.16765832386;91138.63292050625;58955.410445894995;69985;70781.23046923868;71449;72978;69035;69986.65006674934;70670;71146.288537115;72009.63995179991;8327.167283271625;8533.487990720576;8534.573271336532;8626.137386261324;8790.120987901159;46371.84070398267;48715;49212;49405.25892111549;51147;62989.11032669027;65703.35741606499;67817;67902.66048669725;68925.65537172282;10583;10620;10799.082078023142;10872.782544348911;10888;11812.76374472523;12090.214136081479;12112.394380281126;12123.393830308625;12580.433880475584;11173.273737207055;11226.4386780659;11477.024452921596;11491.023263022737;11804.350760708243;11205.943970280096;11598.768024639292;11753.177277590486;11771.587994420153;11779.88220117788;11100.888991110309;11420.972112509913;11627.88372116268;11630.953214210762;11634.069274458192;9656.95171524138;9734.172595329477;9769.853452198366;9771.071748183911;10227.539760710637;9656.27577931672;9774.902250977684;9782.9021709782;9801.656942006995;9804.509774511389 08:46:05 INFO - PROCESS | 1972 | __end_tp_report 08:46:05 INFO - PROCESS | 1972 | __start_cc_report 08:46:05 INFO - PROCESS | 1972 | _x_x_mozilla_cycle_collect,415 08:46:05 INFO - PROCESS | 1972 | __end_cc_report 08:46:05 INFO - PROCESS | 1972 | __startTimestamp1464709565666__endTimestamp 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | ------- Summary: start ------- 08:46:05 INFO - PROCESS | 1972 | Number of tests: 6 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | [#0] dojo.html Cycles:165 Average:5780.25 Median:5971.94 stddev:667.55 (11.2%) stddev-sans-first:639.08 08:46:05 INFO - PROCESS | 1972 | Values: 3236.4 3498.9 3547.8 3553.6 3559.3 5774.6 5799.1 5827.0 5831.7 5843.3 5806.8 5813.9 5835.2 5870.9 5882.1 5280.9 5314.2 5382.1 5386.3 5408.8 4100.3 5175.1 5179.9 5184.2 5205.7 5307.0 5334.5 5337.3 5341.4 5355.7 4362.4 5302.4 5306.7 5314.0 5408.8 5247.6 5264.1 5314.5 5360.8 5372.4 5603.3 5644.9 5674.9 5679.2 5741.7 6123.3 6216.2 6295.4 6325.8 6326.4 6301.7 6309.8 6318.2 6319.2 6334.5 5958.8 5963.3 5971.9 5978.0 5997.0 6256.8 6338.4 6385.3 6394.4 6414.3 5707.5 5810.8 6258.5 6270.7 6284.0 5236.6 6264.9 6350.2 6352.4 6357.8 4928.9 5439.5 5532.3 6304.1 6338.9 6211.6 6269.5 6310.4 6311.0 6328.3 4256.3 4924.1 4956.2 5004.2 5027.4 4114.7 4447.8 4465.4 4502.1 4505.2 5657.8 5828.2 5833.9 5856.7 5864.7 5781.4 6161.5 6201.3 6269.2 6341.1 5791.0 5838.2 5843.0 5863.3 5874.3 5469.8 5476.0 5484.1 5486.4 5494.2 6267.7 6394.9 6444.6 6445.0 6448.5 6287.2 6301.6 6304.4 6306.4 6312.8 6426.5 6450.0 6452.5 6457.1 6458.3 6115.9 6200.8 6256.5 6263.7 6299.9 6255.7 6282.0 6290.4 6307.6 6322.7 6088.9 6190.6 6235.1 6255.2 6271.9 6069.7 6106.9 6175.3 6213.6 6246.6 5746.2 5875.4 5986.9 6072.8 6115.4 6053.4 6209.0 6212.2 6222.9 6244.1 5960.6 5979.7 6064.4 6076.2 6128.7 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | [#1] ext.html Cycles:180 Average:69762.57 Median:37799.71 stddev:155821.72 (412.2%) stddev-sans-first:156171.97 08:46:05 INFO - PROCESS | 1972 | Values: 466.2 469.9 500.7 546.6 548.1 35891.0 39259.8 39400.8 39402.4 39593.0 32788.7 39999.6 40726.8 40919.0 41034.8 9526.5 9656.1 9682.9 9707.3 9726.5 9532.5 9661.7 9692.7 9787.6 9804.7 9647.7 9757.0 9781.2 9806.1 9841.3 9689.4 9772.2 9784.2 9792.1 9796.1 9605.6 9608.5 9753.4 9753.9 9767.4 39245.2 39375.0 39682.4 39854.8 39864.6 3297.4 3468.2 3546.6 3665.4 3900.8 27793.3 28196.0 28692.4 28985.6 29708.0 2747.5 2774.2 2777.9 2815.5 2836.4 481552.0 485984.0 487540.0 488696.0 490014.0 35625.5 59605.0 59919.4 60122.7 60133.7 35484.0 35829.3 35957.6 36119.1 36136.0 13120.7 15421.3 15538.0 15806.4 15843.0 13150.0 13241.9 13249.9 13283.5 13292.3 8108.7 8217.5 8217.9 8270.7 8315.9 2988.6 3350.8 3356.4 3365.5 3426.7 1019.2 1021.9 1027.9 1040.7 1093.8 25079.7 29069.3 30244.5 30385.4 30449.5 1109.3 1113.9 1136.0 1214.9 1237.0 14043.4 15300.5 15364.5 15539.8 15564.5 629472.0 636445.0 640964.0 641054.0 642443.0 58872.0 59503.0 59721.7 59849.0 59901.7 1159.3 1424.0 1446.7 1451.3 1453.2 26307.7 27556.6 28993.6 29663.7 29836.9 57877.7 58351.4 58713.7 59056.7 59305.4 604809.0 607688.0 611043.0 611577.0 611856.0 41461.4 42427.6 42476.0 44570.1 44722.8 42297.4 42896.6 42984.4 47545.0 47979.5 35126.0 35680.0 35934.6 36074.6 36305.3 35167.3 35213.8 35459.6 35481.0 35622.0 32194.5 35727.8 35820.5 37461.0 38138.4 30318.1 31574.2 31770.0 31855.8 31959.8 20265.6 24478.0 24676.5 24699.3 25053.1 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | [#2] jquery.html Cycles:180 Average:36436.99 Median:28738.79 stddev:101332.13 (352.6%) stddev-sans-first:101580.21 08:46:05 INFO - PROCESS | 1972 | Values: 368.0 393.5 397.5 401.4 406.6 12599.9 20068.8 20149.6 20860.1 20870.9 17447.7 20575.6 20692.7 20740.9 20936.5 5795.2 5869.8 6079.4 6131.4 6167.0 5977.9 6062.7 6088.7 6097.3 6105.5 6016.3 6046.7 7284.3 7573.8 7719.0 7372.8 7583.0 7595.1 7768.7 7838.8 7151.8 7562.1 7610.0 7613.2 7616.5 23182.4 27590.9 28278.0 28319.6 29158.0 1976.8 2751.7 2821.5 3131.2 3162.5 15062.5 15667.0 15728.6 15957.8 16392.6 1609.3 1798.7 1868.9 1894.9 1901.9 116747.4 122472.0 135587.3 135820.0 139722.0 20532.3 21636.2 21715.6 22174.9 22479.2 14283.8 14854.8 35314.0 36016.6 36596.3 8496.9 8796.4 8912.1 9034.9 9201.7 8910.0 9020.9 9112.9 9363.4 9404.9 4581.6 4727.7 4743.9 4749.5 4757.5 1992.9 2023.3 2034.8 2040.5 2052.4 862.2 906.5 959.6 961.9 1059.5 20215.9 20299.4 20633.9 20905.3 20975.3 967.2 985.1 990.8 995.1 1017.0 9063.5 10980.6 11008.1 11121.0 11122.9 449853.0 621856.0 643671.0 644832.0 644970.0 130313.0 133124.0 133706.0 135986.0 136137.0 874.3 961.4 965.1 970.2 1007.3 18312.5 18382.5 18801.7 18905.0 18928.6 38003.6 55033.0 55519.7 56660.7 56788.0 68800.0 71995.0 75585.6 76018.6 76183.6 7911.8 8084.6 8090.0 8230.5 8308.0 10293.0 12358.4 12588.6 12703.8 12818.0 10731.5 10990.7 11179.6 11285.6 11301.2 10761.7 10768.1 10964.8 11032.7 11165.8 11184.9 11207.8 11255.8 11325.1 11401.5 8186.1 8238.6 8318.7 8429.7 8586.4 10214.6 10531.7 10757.9 11129.7 11344.4 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | [#3] mootools.html Cycles:180 Average:5722.29 Median:4941.75 stddev:3210.96 (65.0%) stddev-sans-first:3203.09 08:46:05 INFO - PROCESS | 1972 | Values: 1342.6 1579.9 1751.4 1844.0 1870.7 7901.2 7977.3 8637.7 9006.1 9032.0 7409.6 8777.2 8809.5 8902.5 8918.6 2418.1 2422.6 2451.1 2453.4 2457.9 2441.9 2461.9 2499.6 2505.9 2514.9 2407.5 2446.7 2446.9 2474.6 2486.4 2452.9 2464.8 2487.5 2488.2 2489.4 2438.2 2441.6 2461.0 2469.0 2490.6 9010.5 9032.6 9036.9 9050.0 9088.8 5469.4 5571.3 5595.6 5626.2 5636.3 3366.7 3370.4 3418.4 3483.7 3504.7 874.6 1045.8 1073.3 1625.6 1673.5 11822.8 11855.1 11934.4 11939.5 11953.7 4464.8 4591.9 4597.5 4665.3 4752.5 9352.5 9396.5 9417.4 9420.8 9456.8 5378.4 5418.6 5456.5 5464.0 5464.5 4408.7 4872.2 4932.3 4950.3 5027.3 3232.5 3884.9 3916.8 3977.5 4015.2 1040.4 1214.8 1231.7 1242.9 1247.3 912.9 918.1 930.5 936.6 956.2 5963.5 6028.2 6039.0 6095.7 6125.7 464.2 470.5 470.8 473.6 477.1 3806.9 3927.3 3960.7 3996.7 4031.2 10672.5 10870.5 11046.6 11192.3 11216.0 10849.7 10882.4 10931.2 10938.9 10971.5 8127.8 8394.5 8404.5 8411.2 8444.5 5772.4 5853.0 5965.7 5987.8 6064.8 9577.8 9621.3 9651.7 9680.5 9707.5 9648.9 9844.6 9908.3 9910.0 10316.3 6869.9 7307.2 7542.7 7637.9 7765.4 8280.6 8312.0 8355.3 8368.9 8414.3 6478.0 7369.4 7616.2 7642.5 7729.7 7412.2 7419.9 7462.4 7493.4 7555.1 7071.4 7096.0 7144.2 7157.9 7164.8 4781.1 4811.5 4858.3 4902.6 4933.2 4133.6 4288.9 4915.1 4955.7 5030.4 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | [#4] prototype.html Cycles:180 Average:5911.50 Median:6739.34 stddev:1950.16 (28.9%) stddev-sans-first:1909.96 08:46:05 INFO - PROCESS | 1972 | Values: 321.6 324.2 332.4 335.7 335.9 5781.1 6579.3 6683.7 6736.3 6779.5 5606.4 6072.2 6703.4 6742.4 6808.7 4508.8 5016.3 5022.0 5025.6 5033.0 4119.6 4258.6 4486.9 4831.5 5049.8 4941.7 4946.6 4962.9 4978.7 4998.2 4669.9 4964.1 4964.4 4970.5 4987.4 4105.2 4130.9 4134.4 4134.6 4152.9 4956.2 6409.2 6477.4 6493.7 6502.9 5697.7 5790.7 5824.0 5830.3 5852.9 7261.0 7262.5 7268.7 7270.7 7283.7 4509.8 4669.8 4673.7 4674.9 4681.4 7489.3 7580.0 7583.5 7601.9 7619.7 3835.2 4687.6 4704.2 4705.5 4738.0 4807.1 4808.9 4821.2 4822.5 4823.6 5719.3 5757.5 5776.1 5781.7 5788.7 7007.8 7189.6 7298.4 7304.5 7379.8 1633.6 1652.3 1660.5 1664.8 1670.1 1045.1 1045.5 1062.3 1065.9 1065.9 4759.0 5839.5 6798.2 6811.3 6817.7 5785.8 7624.5 7676.7 7679.2 7688.7 6778.6 6786.3 6798.7 6823.0 6823.2 3963.3 3988.9 4011.8 4024.8 4045.9 7658.4 7969.8 7978.0 7983.2 7985.4 7875.8 7894.5 7895.4 7908.0 7918.2 7936.5 7980.5 8002.0 8004.4 8009.1 7314.8 7612.6 7618.0 7627.0 7638.0 7462.1 7466.1 7519.0 7525.4 7538.9 7879.3 7959.8 7990.6 7990.8 8017.3 4719.1 4755.3 4756.3 4761.3 4767.4 7268.0 7557.0 7572.0 7594.1 7619.8 7446.7 7480.3 7521.1 7525.9 7549.6 5987.4 7295.9 7320.8 7371.0 7383.5 7118.9 7143.2 7217.9 7304.6 7331.7 7136.0 7273.8 7336.3 7361.9 7400.7 6838.5 7015.0 7132.3 7190.0 7207.4 08:46:05 INFO - PROCESS | 1972 | 08:46:05 INFO - PROCESS | 1972 | [#5] yui.html Cycles:180 Average:17528.97 Median:622.07 stddev:23859.64 (3835.5%) stddev-sans-first:23893.03 08:46:05 INFO - PROCESS | 1972 | Values: 679.4 691.9 734.5 750.1 758.3 14274.6 14891.9 15004.5 15279.2 15282.5 11149.6 14112.4 14116.9 14122.4 14175.0 731.1 899.6 901.3 907.3 915.7 875.2 878.8 914.5 917.9 922.1 835.8 966.3 966.4 967.8 970.0 922.0 948.3 949.4 954.3 957.9 690.4 705.9 714.3 718.5 720.0 14491.5 14556.9 14746.9 14919.4 15002.7 12590.2 12666.4 12684.6 12732.2 12826.0 9995.6 10127.2 10212.0 10407.6 10457.4 3473.2 3525.7 3534.3 3552.0 3616.5 59933.7 61044.0 78309.6 78450.6 78663.6 9249.9 9556.0 9620.4 9704.7 9754.6 20912.0 21527.8 21650.5 21689.3 21759.3 7131.8 7401.7 7407.5 7472.2 7482.4 3647.9 4031.6 4085.8 4172.3 4173.4 3222.3 3541.4 3614.6 3617.0 3625.1 660.5 662.8 664.3 669.2 672.1 498.7 602.0 605.7 617.9 625.1 8264.6 8296.4 8315.4 8355.8 8390.5 544.3 565.0 611.9 619.5 624.6 3086.5 3808.2 3861.7 3992.2 4091.4 81129.2 83192.0 83216.0 83234.2 91138.6 58955.4 69985.0 70781.2 71449.0 72978.0 69035.0 69986.7 70670.0 71146.3 72009.6 8327.2 8533.5 8534.6 8626.1 8790.1 46371.8 48715.0 49212.0 49405.3 51147.0 62989.1 65703.4 67817.0 67902.7 68925.7 10583.0 10620.0 10799.1 10872.8 10888.0 11812.8 12090.2 12112.4 12123.4 12580.4 11173.3 11226.4 11477.0 11491.0 11804.4 11205.9 11598.8 11753.2 11771.6 11779.9 11100.9 11421.0 11627.9 11631.0 11634.1 9657.0 9734.2 9769.9 9771.1 10227.5 9656.3 9774.9 9782.9 9801.7 9804.5 08:46:05 INFO - PROCESS | 1972 | -------- Summary: end -------- 08:46:05 INFO - PROCESS | 1972 | 08:46:06 INFO - TEST-INFO | 1972: exit 0 08:46:06 INFO - TEST-OK | dromaeo_css | took 1111871ms 08:46:06 INFO - TEST-START | kraken 08:46:06 INFO - operating with platform_type : mac_ 08:46:06 INFO - Initialising browser for kraken test... 08:46:06 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpNFv1iv/profile http://localhost:49435/getInfo.html) 08:46:09 INFO - PROCESS | 1979 | __metrics Screen width/height:1600/1200 08:46:09 INFO - PROCESS | 1979 | colorDepth:24 08:46:09 INFO - PROCESS | 1979 | Browser inner width/height: 1024/689 08:46:09 INFO - PROCESS | 1979 | __metrics 08:46:09 ERROR - PROCESS | 1979 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 08:46:10 INFO - TEST-INFO | 1979: exit 0 08:46:10 INFO - Browser initialized. 08:46:10 INFO - Running cycle 1/1 for kraken test... 08:46:10 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpNFv1iv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:46:22 INFO - PROCESS | 1982 | RSS: Main: 221020160 08:46:22 INFO - PROCESS | 1982 | 08:46:24 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49435/tests/kraken/driver.html?testName=audio-beat-detection) 08:46:25 INFO - PROCESS | 1982 | RSS: Main: 232853504 08:46:25 INFO - PROCESS | 1982 | 08:46:28 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49435/tests/kraken/driver.html?testName=audio-dft) 08:46:28 INFO - PROCESS | 1982 | RSS: Main: 241860608 08:46:28 INFO - PROCESS | 1982 | 08:46:32 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49435/tests/kraken/driver.html?testName=audio-fft) 08:46:33 INFO - PROCESS | 1982 | RSS: Main: 244436992 08:46:33 INFO - PROCESS | 1982 | 08:46:35 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49435/tests/kraken/driver.html?testName=audio-oscillator) 08:46:35 INFO - PROCESS | 1982 | RSS: Main: 246636544 08:46:35 INFO - PROCESS | 1982 | 08:46:38 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49435/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:46:38 INFO - PROCESS | 1982 | RSS: Main: 246804480 08:46:38 INFO - PROCESS | 1982 | 08:46:41 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49435/tests/kraken/driver.html?testName=imaging-darkroom) 08:46:42 INFO - PROCESS | 1982 | RSS: Main: 246853632 08:46:42 INFO - PROCESS | 1982 | 08:46:45 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49435/tests/kraken/driver.html?testName=imaging-desaturate) 08:46:45 INFO - PROCESS | 1982 | RSS: Main: 247160832 08:46:45 INFO - PROCESS | 1982 | 08:46:48 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49435/tests/kraken/driver.html?testName=json-parse-financial) 08:46:49 INFO - PROCESS | 1982 | RSS: Main: 247328768 08:46:49 INFO - PROCESS | 1982 | 08:46:50 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49435/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:46:51 INFO - PROCESS | 1982 | RSS: Main: 247070720 08:46:51 INFO - PROCESS | 1982 | 08:46:53 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:46:54 INFO - PROCESS | 1982 | RSS: Main: 247181312 08:46:54 INFO - PROCESS | 1982 | 08:46:56 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:46:57 INFO - PROCESS | 1982 | RSS: Main: 247427072 08:46:57 INFO - PROCESS | 1982 | 08:46:59 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:47:00 INFO - PROCESS | 1982 | RSS: Main: 247517184 08:47:00 INFO - PROCESS | 1982 | 08:47:03 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:47:04 INFO - PROCESS | 1982 | RSS: Main: 247443456 08:47:04 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49435/tests/kraken/driver.html?testName=ai-astar) 08:47:05 INFO - PROCESS | 1982 | RSS: Main: 249393152 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | __start_tp_report 08:47:05 INFO - PROCESS | 1982 | _x_x_mozilla_page_load 08:47:05 INFO - PROCESS | 1982 | _x_x_mozilla_page_load_details 08:47:05 INFO - PROCESS | 1982 | |i|pagename|runs| 08:47:05 INFO - PROCESS | 1982 | |0;ai-astar;113;120;108;116;119;118;109;116;118;117 08:47:05 INFO - PROCESS | 1982 | |1;audio-beat-detection;183;155;192;158;119;138;125;153;153;120 08:47:05 INFO - PROCESS | 1982 | |2;audio-dft;276;184;261;273;209;237;275;195;193;193 08:47:05 INFO - PROCESS | 1982 | |3;audio-fft;62;67;76;147;86;84;47;59;152;89 08:47:05 INFO - PROCESS | 1982 | |4;audio-oscillator;78;91;143;80;119;117;100;119;115;157 08:47:05 INFO - PROCESS | 1982 | |5;imaging-gaussian-blur;83;111;135;88;108;86;91;87;90;87 08:47:05 INFO - PROCESS | 1982 | |6;imaging-darkroom;123;107;149;150;93;96;97;96;114;152 08:47:05 INFO - PROCESS | 1982 | |7;imaging-desaturate;131;91;89;90;93;92;91;90;89;91 08:47:05 INFO - PROCESS | 1982 | |8;json-parse-financial;61;65;64;64;67;66;66;81;64;60 08:47:05 INFO - PROCESS | 1982 | |9;json-stringify-tinderbox;65;69;86;108;77;67;70;66;68;66 08:47:05 INFO - PROCESS | 1982 | |10;stanford-crypto-aes;83;88;109;109;83;83;81;81;83;101 08:47:05 INFO - PROCESS | 1982 | |11;stanford-crypto-ccm;129;126;136;147;158;132;129;149;177;155 08:47:05 INFO - PROCESS | 1982 | |12;stanford-crypto-pbkdf2;174;196;170;167;179;159;219;220;167;168 08:47:05 INFO - PROCESS | 1982 | |13;stanford-crypto-sha256-iterative;65;68;67;71;67;69;66;69;65;71 08:47:05 INFO - PROCESS | 1982 | __end_tp_report 08:47:05 INFO - PROCESS | 1982 | __start_cc_report 08:47:05 INFO - PROCESS | 1982 | _x_x_mozilla_cycle_collect,1084 08:47:05 INFO - PROCESS | 1982 | __end_cc_report 08:47:05 INFO - PROCESS | 1982 | __startTimestamp1464709625980__endTimestamp 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | ------- Summary: start ------- 08:47:05 INFO - PROCESS | 1982 | Number of tests: 14 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#0] ai-astar Cycles:10 Average:115.40 Median:117.50 stddev:4.12 (3.5%) stddev-sans-first:4.27 08:47:05 INFO - PROCESS | 1982 | Values: 113.0 120.0 108.0 116.0 119.0 118.0 109.0 116.0 118.0 117.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#1] audio-beat-detection Cycles:10 Average:149.60 Median:154.00 stddev:24.92 (16.2%) stddev-sans-first:23.32 08:47:05 INFO - PROCESS | 1982 | Values: 183.0 155.0 192.0 158.0 119.0 138.0 125.0 153.0 153.0 120.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#2] audio-dft Cycles:10 Average:229.60 Median:249.00 stddev:38.76 (15.6%) stddev-sans-first:37.29 08:47:05 INFO - PROCESS | 1982 | Values: 276.0 184.0 261.0 273.0 209.0 237.0 275.0 195.0 193.0 193.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#3] audio-fft Cycles:10 Average:86.90 Median:71.50 stddev:35.54 (49.7%) stddev-sans-first:36.54 08:47:05 INFO - PROCESS | 1982 | Values: 62.0 67.0 76.0 147.0 86.0 84.0 47.0 59.0 152.0 89.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#4] audio-oscillator Cycles:10 Average:111.90 Median:150.00 stddev:25.57 (17.0%) stddev-sans-first:23.99 08:47:05 INFO - PROCESS | 1982 | Values: 78.0 91.0 143.0 80.0 119.0 117.0 100.0 119.0 115.0 157.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:87.00 stddev:16.47 (18.9%) stddev-sans-first:16.72 08:47:05 INFO - PROCESS | 1982 | Values: 83.0 111.0 135.0 88.0 108.0 86.0 91.0 87.0 90.0 87.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#6] imaging-darkroom Cycles:10 Average:117.70 Median:122.50 stddev:24.35 (19.9%) stddev-sans-first:25.75 08:47:05 INFO - PROCESS | 1982 | Values: 123.0 107.0 149.0 150.0 93.0 96.0 97.0 96.0 114.0 152.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:91.00 stddev:12.82 (14.1%) stddev-sans-first:1.32 08:47:05 INFO - PROCESS | 1982 | Values: 131.0 91.0 89.0 90.0 93.0 92.0 91.0 90.0 89.0 91.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:65.50 stddev:5.77 (8.8%) stddev-sans-first:5.85 08:47:05 INFO - PROCESS | 1982 | Values: 61.0 65.0 64.0 64.0 67.0 66.0 66.0 81.0 64.0 60.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#9] json-stringify-tinderbox Cycles:10 Average:74.20 Median:68.50 stddev:13.51 (19.7%) stddev-sans-first:13.92 08:47:05 INFO - PROCESS | 1982 | Values: 65.0 69.0 86.0 108.0 77.0 67.0 70.0 66.0 68.0 66.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:83.00 stddev:11.57 (13.9%) stddev-sans-first:11.98 08:47:05 INFO - PROCESS | 1982 | Values: 83.0 88.0 109.0 109.0 83.0 83.0 81.0 81.0 83.0 101.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:148.00 stddev:16.40 (11.1%) stddev-sans-first:16.50 08:47:05 INFO - PROCESS | 1982 | Values: 129.0 126.0 136.0 147.0 158.0 132.0 129.0 149.0 177.0 155.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:176.50 stddev:22.11 (12.5%) stddev-sans-first:23.27 08:47:05 INFO - PROCESS | 1982 | Values: 174.0 196.0 170.0 167.0 179.0 159.0 219.0 220.0 167.0 168.0 08:47:05 INFO - PROCESS | 1982 | 08:47:05 INFO - PROCESS | 1982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.50 stddev:2.20 (3.2%) stddev-sans-first:2.09 08:47:05 INFO - PROCESS | 1982 | Values: 65.0 68.0 67.0 71.0 67.0 69.0 66.0 69.0 65.0 71.0 08:47:05 INFO - PROCESS | 1982 | -------- Summary: end -------- 08:47:05 INFO - PROCESS | 1982 | 08:47:06 INFO - TEST-INFO | 1982: exit 0 08:47:06 INFO - TEST-OK | kraken | took 60542ms 08:47:06 INFO - SUITE-END | took 1172s 08:47:07 INFO - Completed test suite (00:19:32) 08:47:07 INFO - javascript benchmark 08:47:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3236.3850868335016, 3498.930021399572, 3547.846949741335, 3553.6268691787363, 3559.3237284915863, 5774.595778295521, 5799.072148456248, 5827.0, 5831.737571809268, 5843.269591301082, 5806.767729290834, 5813.854653633662, 5835.241418615585, 5870.853228669285, 5882.088276317178, 5280.947190528093, 5314.202869569576, 5382.111951527993, 5386.272853164832, 5408.810691625794, 4100.343944968806, 5175.120229560965, 5179.896402071975, 5184.1705327147665, 5205.7136857473015, 5306.965141797351, 5334.493223143795, 5337.279467271907, 5341.385740639818, 5355.732213389334, 4362.432883725111, 5302.443243459458, 5306.6550674206055, 5313.9734301328435, 5408.756605952742, 5247.606429517801, 5264.131418315973, 5314.548263397616, 5360.812371567015, 5372.355317361902, 5603.271574695264, 5644.915326270109, 5674.886502269931, 5679.2333035040565, 5741.712914354265, 6123.265208175003, 6216.222972128484, 6295.433410992987, 6325.778597749103, 6326.398992095744, 6301.68491575424, 6309.779157729503, 6318.178636777236, 6319.210098737658, 6334.461570766491, 5958.791442299499, 5963.314218864843, 5971.940280597225, 5978.0, 5997.0404735242155, 6256.843578910472, 6338.366163383717, 6385.297617262097, 6394.3925327094785, 6414.262359828679, 5707.514861236758, 5810.767569297265, 6258.468030217391, 6270.655113968684, 6283.96858015707, 5236.607254455855, 6264.903641862656, 6350.237971443456, 6352.36476352361, 6357.809265722036, 4928.926066109011, 5439.48324909133, 5532.2531458253425, 6304.054391841169, 6338.904916426257, 6211.596246243979, 6269.529785266123, 6310.4005119514495, 6310.968445157837, 6328.303886572473, 4256.2764330063965, 4924.064427758714, 4956.231784073474, 5004.174311238605, 5027.371578552681, 4114.691398145151, 4447.755373454426, 4465.352523884018, 4502.099580083996, 4505.234110201208, 5657.811859509549, 5828.213191219216, 5833.854153646108, 5856.677882716406, 5864.706764661835, 5781.392953739881, 6161.537884658668, 6201.317855035943, 6269.216347956505, 6341.143945567289, 5791.044477661138, 5838.211841401357, 5843.035899076604, 5863.2670916135485, 5874.32445268791, 5469.753861076261, 5475.972620136874, 5484.149956756709, 5486.396496385394, 5494.1758736189095, 6267.749290028531, 6394.904076438764, 6444.581102228341, 6445.0, 6448.516361272923, 6287.18266625354, 6301.558890877594, 6304.369563043659, 6306.432421082125, 6312.810615681537, 6426.485881129592, 6450.032495125581, 6452.483801295979, 6457.09600655918, 6458.321876203119, 6115.879294474606, 6200.7519699211525, 6256.499480041494, 6263.74945002213, 6299.905501417391, 6255.718492667932, 6281.968590157203, 6290.370962903673, 6307.590006649553, 6322.715477803418, 6088.939110608837, 6190.566660333733, 6235.127082208585, 6255.24937007562, 6271.905921411274, 6069.696515174312, 6106.938930610637, 6175.320714721287, 6213.596116252429, 6246.562740608114, 5746.16680581305, 5875.353711091691, 5986.910196347146, 6072.817815465543, 6115.3578874217565, 6053.424924632153, 6208.9689551551955, 6212.161358216764, 6222.937770622236, 6244.063390491463, 5960.612560183574, 5979.701014949323, 6064.3935606439, 6076.240469941257, 6128.686460748315], "value": 5738.638198796296, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.2051202699398, 469.92246279363894, 500.68707058088694, 546.5709418106786, 548.1476304346744, 35891.0, 39259.80370098152, 39400.80299598502, 39402.40896386553, 39593.0, 32788.67211327886, 39999.60000399995, 40726.7963660182, 40918.97702557438, 41034.79482602583, 9526.523673816317, 9656.130948214659, 9682.903170968271, 9707.271954603397, 9726.513674316291, 9532.475713835736, 9661.71014869555, 9692.66075687351, 9787.559559819789, 9804.725385699849, 9647.662331818388, 9757.0, 9781.217502599775, 9806.068423499755, 9841.311108222428, 9689.418634881895, 9772.21822254218, 9784.21726261901, 9792.069753373418, 9796.118349348593, 9605.598600349913, 9608.51957402134, 9753.414795112316, 9753.853692194623, 9767.365121267167, 39245.215095698215, 39375.0, 39682.40476392856, 39854.80435586937, 39864.60135398638, 3297.4229509835923, 3468.2196505785996, 3546.627604101558, 3665.376885929372, 3900.8049597520294, 27793.305167370774, 28196.0, 28692.426151476855, 28985.56521652177, 29707.960221392146, 2747.5466548019554, 2774.2370848016753, 2777.874995625202, 2815.5354366529705, 2836.3618185908213, 481552.0, 485984.0, 487540.0, 488696.0, 490014.0, 35625.46561801575, 59605.0, 59919.400805992256, 60122.69938650279, 60133.69933150306, 35484.0, 35829.28341433157, 35957.64042359595, 36119.09702257464, 36136.0, 13120.737585248242, 15421.306041228172, 15538.0, 15806.446774362843, 15843.0, 13150.013748968866, 13241.867581324064, 13249.867501325056, 13283.535076272477, 13292.268925209013, 8108.675652973933, 8217.465864718773, 8217.876731849026, 8270.710525131592, 8315.875261871077, 2988.5666578346454, 3350.8104622859023, 3356.3958487472, 3365.528825964367, 3426.7429942754393, 1019.2202964731986, 1021.8569400283966, 1027.9074883260541, 1040.6736574515328, 1093.786711591232, 25079.746012699292, 29069.273268168465, 30244.54633180504, 30385.392292154476, 30449.543256851164, 1109.2955972957068, 1113.9108871290277, 1135.971600709989, 1214.872438393956, 1236.975260494803, 14043.438262469592, 15300.540983770505, 15364.462243821412, 15539.844601554067, 15564.533064008097, 629472.0, 636445.0, 640964.0, 641054.0, 642443.0, 58872.0, 59503.0, 59721.70139149363, 59849.0, 59901.700491497264, 1159.3217967488945, 1423.9715205695827, 1446.6889618732023, 1451.3468938977292, 1453.1862157191795, 26307.68461576875, 27556.586651199847, 28993.56509652399, 29663.703362966957, 29836.850815745784, 57877.71061144667, 58351.41648583629, 58713.706431467566, 59056.70471647786, 59305.406945929986, 604809.0, 607687.9615601894, 611043.0, 611577.0, 611856.0, 41461.37807932823, 42427.575724242364, 42476.0, 44570.10859782722, 44722.77638611786, 42297.365539516315, 42896.571034289256, 42984.35523467213, 47545.0, 47979.5202047989, 35126.0, 35680.0, 35934.64065359418, 36074.63925360713, 36305.27389452144, 35167.29665406626, 35213.823930881204, 35459.64540354667, 35481.0, 35622.0, 32194.517082243317, 35727.82136089303, 35820.462693060144, 37461.0, 38138.42792358172, 30318.09045728632, 31574.210644733146, 31770.04689859308, 31855.84072079625, 31959.840200798848, 20265.594688106452, 24478.020879164633, 24676.506469870863, 24699.259022229362, 25053.12314068999], "value": 18803.225451976257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.95032670589467, 393.4943597477242, 397.4554859842017, 401.39589917174646, 406.6482492643862, 12599.866012058912, 20068.799312006875, 20149.59700805985, 20860.061297241624, 20870.89564552179, 17447.691423143253, 20575.588488230263, 20692.689609655867, 20740.8962955185, 20936.476588085305, 5795.246617939672, 5869.8239052828485, 6079.422454866781, 6131.448169664729, 6166.969165154191, 5977.910331345033, 6062.696865156747, 6088.66512341821, 6097.298810636789, 6105.511559075263, 6016.308124565688, 6046.727897244635, 7284.344409003189, 7573.772786816405, 7719.035120609924, 7372.778816635509, 7583.014208152932, 7595.088589369283, 7768.650410731474, 7838.843223135563, 7151.7854464366155, 7562.054743157106, 7610.0106986092005, 7613.200613935513, 7616.543007419573, 23182.42043948898, 27590.862045689842, 28278.0, 28319.584020799084, 29158.0, 1976.7726711428227, 2751.7385848344375, 2821.463921854841, 3131.2015436063884, 3162.5414314924437, 15062.548123556311, 15667.0, 15728.606784830356, 15957.76063359039, 16392.590185245463, 1609.3321271672241, 1798.658254931572, 1868.8972106534136, 1894.943151705451, 1901.8668693191482, 116747.416262919, 122472.0, 135587.32206339005, 135820.0, 139721.99999999898, 20532.281370151974, 21636.242731504637, 21715.565688686136, 22174.891255437164, 22479.21322753696, 14283.78574321386, 14854.777178342334, 35314.0, 36016.63983360133, 36596.268074638356, 8496.872546911678, 8796.384253102222, 8912.108789121035, 9034.909650903539, 9201.723948281562, 8910.019897811235, 9020.8646870297, 9112.908870911338, 9363.438193708263, 9404.905950940403, 4581.5647513486165, 4727.692699974491, 4743.857684269477, 4749.453812811502, 4757.500462451462, 1992.8604997650025, 2023.3120738948828, 2034.7587971337473, 2040.4796776821815, 2052.3945436096087, 862.2326129744532, 906.4515967839494, 959.5873774277128, 961.9134277915022, 1059.5496913811598, 20215.898920505304, 20299.391018269474, 20633.89683051575, 20905.268315608882, 20975.26586569493, 967.2358836519071, 985.0600876713429, 990.8464188050864, 995.069609914732, 1017.0, 9063.501507417024, 10980.560777568808, 11008.064314533354, 11120.99911008013, 11122.943320384551, 449853.0, 621856.0, 643671.0, 644832.0, 644970.0, 130313.0, 133124.0, 133706.0, 135986.0, 136137.0, 874.2699845628971, 961.3510880155867, 965.0638880286111, 970.1898914406408, 1007.3351587951917, 18312.514262469394, 18382.540436489195, 18801.717974230673, 18905.0, 18928.621427571095, 38003.61996380112, 55033.0, 55519.722401387735, 56660.71669641625, 56788.0, 68800.0, 71995.0, 75585.62207188929, 76018.61990690012, 76183.61908190424, 7911.802204944923, 8084.63619137152, 8089.959550202447, 8230.547319897463, 8307.958460207661, 10293.0, 12358.382080895739, 12588.622341329774, 12703.809442858179, 12818.038647101319, 10731.463426828472, 10990.725231868948, 11179.60871369498, 11285.605003824827, 11301.208915375844, 10761.73095672583, 10768.0847127995, 10964.780704385708, 11032.669019929415, 11165.832512512156, 11184.88151184875, 11207.775844483227, 11255.831162532731, 11325.093992480746, 11401.462896614734, 8186.099529051919, 8238.588070596566, 8318.667253309799, 8429.662813487392, 8586.441881277697, 10214.62102616167, 10531.684049478528, 10757.892421076001, 11129.721756956142, 11344.432778361213], "value": 10389.59676488542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.610642913555, 1579.8815088868337, 1751.413276552355, 1843.9907800460996, 1870.6913359295718, 7901.249381308781, 7977.282044615983, 8637.697680581181, 9006.099390061, 9032.0, 7409.555426674403, 8777.2100510954, 8809.471431714102, 8902.465852048865, 8918.598663060162, 2418.1053010386136, 2422.624493203556, 2451.0563433078214, 2453.386653336666, 2457.9016839326405, 2441.914532991346, 2461.889214985321, 2499.6375525548783, 2505.9373515662164, 2514.924552263426, 2407.5425669122897, 2446.657467954488, 2446.877656117196, 2474.6164344526537, 2486.403263216824, 2452.926412207628, 2464.7658472445178, 2487.4776296977657, 2488.203774792067, 2489.3776555861036, 2438.244144315268, 2441.584930561809, 2460.9507809843703, 2468.9753102468926, 2490.63885736569, 9010.549472526347, 9032.593533290952, 9036.909630903672, 9049.954750226272, 9088.77278068047, 5469.371022332403, 5571.331440227158, 5595.608307418483, 5626.156076588503, 5636.2954630671165, 3366.73066154707, 3370.410178218802, 3418.4017796885446, 3483.651634836496, 3504.702100321476, 874.5627186406797, 1045.7542477517777, 1073.3023534702427, 1625.5854757036998, 1673.4979506148106, 11822.822657660055, 11855.110866684947, 11934.40327983606, 11939.522419103225, 11953.701157471045, 4464.794505483501, 4591.931121033187, 4597.482830665872, 4665.253559430543, 4752.5485078917445, 9352.485613291334, 9396.530173491436, 9417.434953902788, 9420.764480887896, 9456.810863782686, 5378.408375078739, 5418.620696551204, 5456.454354564512, 5463.989162005042, 5464.508194262536, 4408.713433626804, 4872.171730805771, 4932.309476673268, 4950.257461380822, 5027.321311622957, 3232.515122731609, 3884.8834534964, 3916.7845768483007, 3977.54258260298, 4015.15681706839, 1040.4069680282166, 1214.8359971403925, 1231.6612931443744, 1242.9067819913544, 1247.3451437995125, 912.8721978922956, 918.0910898210857, 930.4696323095906, 936.5691781780457, 956.1681337236648, 5963.522918166623, 6028.216331876828, 6039.0, 6095.725692343855, 6125.729253482617, 464.21315869601375, 470.51066890433566, 470.82814772608435, 473.6471328860021, 477.10066524601376, 3806.864920108671, 3927.253821773854, 3960.663343615767, 3996.7002474814503, 4031.1534577739003, 10672.519736611872, 10870.456477176109, 11046.558137674403, 11192.272502287326, 11216.0, 10849.67450976472, 10882.40146791934, 10931.18016148776, 10938.890611093946, 10971.506282217319, 8127.796805079802, 8394.45436046655, 8404.537750423786, 8411.242988131098, 8444.535550544779, 5772.422757724194, 5853.0, 5965.70171491415, 5987.820365389045, 6064.818055458343, 9577.808443831223, 9621.326507144433, 9651.71044868655, 9680.515974201402, 9707.514624268899, 9648.855267171139, 9844.556994935389, 9908.25688073383, 9910.008999100033, 10316.329438586468, 6869.896951545831, 7307.196208417176, 7542.660580273791, 7637.923620763721, 7765.378769698297, 8280.627371768165, 8312.0, 8355.331573473986, 8368.916310836814, 8414.284785793276, 6478.0, 7369.37360324357, 7616.200298968529, 7642.4650274480255, 7729.652165652672, 7412.1846596875375, 7419.851602967802, 7462.403007759258, 7493.363064139609, 7555.13115991668, 7071.398931090915, 7096.042034325506, 7144.178419481618, 7157.856842863218, 7164.785056448315, 4781.14713565738, 4811.49479304668, 4858.27125931099, 4902.558769710743, 4933.235348520985, 4133.565975572522, 4288.871333859989, 4915.139850526072, 4955.677880937727, 5030.3963524377305], "value": 4550.903917756175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.6381570732925, 324.2202502980332, 332.3668411675758, 335.74315648528864, 335.9160209947513, 5781.075027995522, 6579.342065793418, 6683.665816709164, 6736.282248026752, 6779.4576433885295, 5606.439356064391, 6072.240969878765, 6703.430208432289, 6742.393184613384, 6808.693608787604, 4508.797104130305, 5016.3227964224725, 5021.995600879821, 5025.648204625687, 5032.9748351258195, 4119.5880411958715, 4258.638015768648, 4486.887827804315, 4831.541003604652, 5049.823256186034, 4941.703497790144, 4946.604271658277, 4962.925556116661, 4978.726170060645, 4998.175301075318, 4669.906601867943, 4964.131277026542, 4964.354633897588, 4970.478099799523, 4987.351644286256, 4105.220008198463, 4130.876073717794, 4134.441850350195, 4134.607212314825, 4152.875413737593, 4956.157453232959, 6409.230892292955, 6477.384648458389, 6493.740250389978, 6502.90245646311, 5697.715114244313, 5790.681512516852, 5823.9708801456145, 5830.30036395631, 5852.941470585282, 7260.96369518149, 7262.491625586158, 7268.709251629928, 7270.746068718714, 7283.7086516539275, 4509.7970591323465, 4669.813207471697, 4673.696209746356, 4674.906501869978, 4681.438227412698, 7489.28851759082, 7579.962100189464, 7583.469157158946, 7601.923980760177, 7619.657115429819, 3835.1562656215588, 4687.554682305244, 4704.1767690654, 4705.4588722296685, 4737.957649317144, 4807.062622788592, 4808.855734327975, 4821.180399332122, 4822.493638168012, 4823.638227132979, 5719.256496655408, 5757.453041961007, 5776.075827867605, 5781.739821708034, 5788.739506722123, 7007.754728584475, 7189.604571748604, 7298.416126709851, 7304.48868579205, 7379.778606641809, 1633.5997680568337, 1652.2564845819188, 1660.4852495726122, 1664.8002239731068, 1670.0647637323138, 1045.0594464981425, 1045.46158728255, 1062.2617280989793, 1065.8720953485476, 1065.8827528971806, 4758.976205118952, 5839.532837373, 6798.150231221098, 6811.318868113248, 6817.693203805741, 5785.797497087582, 7624.466287359832, 7676.731314404081, 7679.155292917774, 7688.654010569538, 6778.559393639398, 6786.287439818847, 6798.66006699663, 6822.976553517012, 6823.249442561314, 3963.286608410514, 3988.940165897514, 4011.7793521356607, 4024.75851448914, 4045.9480535060507, 7658.387329013665, 7969.800754981172, 7978.042634883851, 7983.20167983197, 7985.441019128722, 7875.763727088197, 7894.486858354188, 7895.447318687751, 7907.960460197777, 7918.208179182151, 7936.523808571389, 7980.4812687175145, 8001.959745233083, 8004.359651227888, 8009.078955920144, 7314.780556583311, 7612.619369031637, 7618.047744031996, 7627.046619172604, 7638.0, 7462.104547454123, 7466.104067511933, 7519.022527071445, 7525.397968162424, 7538.886916696144, 7879.330256928225, 7959.801004974922, 7990.640421181177, 7990.760277191694, 8017.278444939983, 4719.10337035962, 4755.286706993978, 4756.3352826510445, 4761.262004389325, 4767.427908650984, 7267.963660181666, 7556.962215188889, 7572.0, 7594.050743657043, 7619.847603048019, 7446.664900079401, 7480.326770590673, 7521.097468303619, 7525.88711169322, 7549.622518874145, 5987.371326010706, 7295.927040729524, 7320.780376588711, 7371.041764570571, 7383.520071195355, 7118.893216601651, 7143.178534468602, 7217.891731624135, 7304.561726296439, 7331.706731730671, 7136.0, 7273.781786546412, 7336.266373362621, 7361.889571656537, 7400.666969986256, 6838.487113466411, 7014.964925175342, 7132.322429369202, 7189.964050179716, 7207.387372073433], "value": 5258.2918883594375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.3953381490472, 691.8616276744652, 734.4528326396835, 750.0736590310966, 758.260695821574, 14274.643133921658, 14891.851081489196, 15004.474843380483, 15279.159646219481, 15282.465113721022, 11149.609763658274, 14112.364943577539, 14116.858831411682, 14122.43510259591, 14175.007749457542, 731.0715391452852, 899.5502248875562, 901.324006994754, 907.2877790934118, 915.693242763675, 875.2341701011615, 878.8462019146624, 914.4650379527984, 917.8760867282931, 922.0502882031518, 835.7534527314457, 966.2897770138942, 966.4104896013426, 967.816114938163, 969.961201551937, 921.9907800922039, 948.2650945517211, 949.3829011142743, 954.3033585482602, 957.9329446938717, 690.3890057299276, 705.8517711280638, 714.2857142857143, 718.4755128756037, 719.9560637076261, 14491.492797752027, 14556.854431455657, 14746.852531474655, 14919.403223871032, 15002.69994600102, 12590.24458532482, 12666.430010649543, 12684.55604053863, 12732.172408793398, 12826.0, 9995.600175993024, 10127.240456965757, 10212.0, 10407.5836966522, 10457.372557646566, 3473.1838018065732, 3525.735569832247, 3534.2754735279204, 3552.0, 3616.5479315085613, 59933.7003314985, 61043.999999999556, 78309.60845195738, 78450.6077469609, 78663.60668196679, 9249.861252081224, 9555.952220238854, 9620.37467564606, 9704.660336888175, 9754.609815607439, 20911.954402279825, 21527.78472215289, 21650.458738531663, 21689.34931952044, 21759.347219583437, 7131.82170445743, 7401.703931842666, 7407.518511296748, 7472.215417381205, 7482.438817088631, 3647.85408583659, 4031.5968403160036, 4085.7957102144774, 4172.332426811757, 4173.4157217989505, 3222.258880457461, 3541.4333706607335, 3614.566252049718, 3617.0, 3625.1118475973553, 660.4584240922397, 662.8276648071536, 664.3323459922747, 669.1802541886151, 672.085963090197, 498.70077953227775, 601.9608725432832, 605.7274226598048, 617.9165812615329, 625.0936142593258, 8264.586770661563, 8296.377771667148, 8315.376346774015, 8355.791105222297, 8390.538520381439, 544.3413469701626, 565.0, 611.8562137897679, 619.5074915442234, 624.6283461340495, 3086.4907290297297, 3808.1812410331913, 3861.6717579005535, 3992.201559688074, 4091.3862920562156, 81129.18870811335, 83192.0, 83216.0, 83234.16765832386, 91138.63292050625, 58955.410445894995, 69985.0, 70781.23046923868, 71449.0, 72978.0, 69035.0, 69986.65006674934, 70670.0, 71146.288537115, 72009.63995179991, 8327.167283271625, 8533.487990720576, 8534.573271336532, 8626.137386261324, 8790.120987901159, 46371.84070398267, 48715.0, 49212.0, 49405.25892111549, 51147.0, 62989.11032669027, 65703.35741606499, 67817.0, 67902.66048669725, 68925.65537172282, 10583.0, 10620.0, 10799.082078023142, 10872.782544348911, 10888.0, 11812.76374472523, 12090.214136081479, 12112.394380281126, 12123.393830308625, 12580.433880475584, 11173.273737207055, 11226.4386780659, 11477.024452921596, 11491.023263022737, 11804.350760708243, 11205.943970280096, 11598.768024639292, 11753.177277590486, 11771.587994420153, 11779.88220117788, 11100.888991110309, 11420.972112509913, 11627.88372116268, 11630.953214210762, 11634.069274458192, 9656.95171524138, 9734.172595329477, 9769.853452198366, 9771.071748183911, 10227.539760710637, 9656.27577931672, 9774.902250977684, 9782.9021709782, 9801.656942006995, 9804.509774511389], "value": 6851.204143473501, "unit": "score"}], "name": "dromaeo_css", "value": 7540.45571883531, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 120.0, 108.0, 116.0, 119.0, 118.0, 109.0, 116.0, 118.0, 117.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 155.0, 192.0, 158.0, 119.0, 138.0, 125.0, 153.0, 153.0, 120.0], "value": 149.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [276.0, 184.0, 261.0, 273.0, 209.0, 237.0, 275.0, 195.0, 193.0, 193.0], "value": 229.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 76.0, 147.0, 86.0, 84.0, 47.0, 59.0, 152.0, 89.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 91.0, 143.0, 80.0, 119.0, 117.0, 100.0, 119.0, 115.0, 157.0], "value": 111.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 111.0, 135.0, 88.0, 108.0, 86.0, 91.0, 87.0, 90.0, 87.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 107.0, 149.0, 150.0, 93.0, 96.0, 97.0, 96.0, 114.0, 152.0], "value": 117.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 91.0, 89.0, 90.0, 93.0, 92.0, 91.0, 90.0, 89.0, 91.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 64.0, 67.0, 66.0, 66.0, 81.0, 64.0, 60.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 86.0, 108.0, 77.0, 67.0, 70.0, 66.0, 68.0, 66.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 109.0, 109.0, 83.0, 83.0, 81.0, 81.0, 83.0, 101.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 126.0, 136.0, 147.0, 158.0, 132.0, 129.0, 149.0, 177.0, 155.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 196.0, 170.0, 167.0, 179.0, 159.0, 219.0, 220.0, 167.0, 168.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 71.0, 67.0, 69.0, 66.0, 69.0, 65.0, 71.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1626.0, "alertThreshold": 2.0}]} 08:47:07 INFO - Return code: 0 08:47:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:47:07 ERROR - # TBPL SUCCESS # 08:47:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:07 INFO - 2016-05-31 08:47:07.282 screenresolution[1987:14001] starting screenresolution argv=screenresolution get 08:47:07 INFO - 2016-05-31 08:47:07.284 screenresolution[1987:14001] Display 0: 1600x1200x32@60 08:47:07 INFO - 2016-05-31 08:47:07.291 screenresolution[1988:14004] starting screenresolution argv=screenresolution list 08:47:07 INFO - Available Modes on Display 0 08:47:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:07 INFO - Intel Iris: 08:47:07 INFO - Chipset Model: Intel Iris 08:47:07 INFO - Type: GPU 08:47:07 INFO - Bus: Built-In 08:47:07 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:07 INFO - Vendor: Intel (0x8086) 08:47:07 INFO - Device ID: 0x0a2e 08:47:07 INFO - Revision ID: 0x0009 08:47:07 INFO - Displays: 08:47:07 INFO - Display: 08:47:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:07 INFO - Display Serial Number: 1600x1200 60 08:47:07 INFO - Main Display: Yes 08:47:07 INFO - Mirror: Off 08:47:07 INFO - Online: Yes 08:47:07 INFO - Rotation: Supported 08:47:07 INFO - Return code: 0 08:47:07 INFO - Running post-action listener: _resource_record_post_action 08:47:07 INFO - Running post-run listener: _resource_record_post_run 08:47:08 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 700416; Write bytes: 653912576; Read time: 8; Write time: 1898 08:47:08 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 351050240; Write bytes: 346052096; Read time: 27621; Write time: 738 08:47:08 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 700416; Write bytes: 297018368; Read time: 8; Write time: 1120 08:47:08 INFO - Running post-run listener: _upload_blobber_files 08:47:08 INFO - Blob upload gear active. 08:47:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:47:08 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 08:47:08 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'] 08:47:08 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 08:47:09 INFO - (blobuploader) - INFO - Open directory for files ... 08:47:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:47:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:47:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:47:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:10 INFO - (blobuploader) - INFO - Done attempting. 08:47:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:47:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:47:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:47:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:12 INFO - (blobuploader) - INFO - Done attempting. 08:47:12 INFO - (blobuploader) - INFO - Iteration through files over. 08:47:12 INFO - Return code: 0 08:47:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:47:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:47:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66a07bba1211879633dcf1e0baae015141576241f63499a2f1df586757bd508c4978d5ba7116e6a0513dd965eb1d7039993a15ed8c2e7343c28a56f8e160d705", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0677f29571a466ecca094546f390e51dc216ff549c98d76c9fb0ebf9b95fb0cb0315918e5d4d00d670ef8f15ecbbd7e98b508cf2577579e438eecf8744daca18"} 08:47:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:47:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:47:12 INFO - Contents: 08:47:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66a07bba1211879633dcf1e0baae015141576241f63499a2f1df586757bd508c4978d5ba7116e6a0513dd965eb1d7039993a15ed8c2e7343c28a56f8e160d705", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0677f29571a466ecca094546f390e51dc216ff549c98d76c9fb0ebf9b95fb0cb0315918e5d4d00d670ef8f15ecbbd7e98b508cf2577579e438eecf8744daca18"} 08:47:12 INFO - Running post-run listener: copy_logs_to_upload_dir 08:47:12 INFO - Copying logs to upload dir... 08:47:12 INFO - mkdir: /builds/slave/test/build/upload/logs 08:47:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.903776 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-31 08:47:12.387076) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 08:47:12.394160) ========= 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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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/66a07bba1211879633dcf1e0baae015141576241f63499a2f1df586757bd508c4978d5ba7116e6a0513dd965eb1d7039993a15ed8c2e7343c28a56f8e160d705", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0677f29571a466ecca094546f390e51dc216ff549c98d76c9fb0ebf9b95fb0cb0315918e5d4d00d670ef8f15ecbbd7e98b508cf2577579e438eecf8744daca18"} build_url:https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/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/66a07bba1211879633dcf1e0baae015141576241f63499a2f1df586757bd508c4978d5ba7116e6a0513dd965eb1d7039993a15ed8c2e7343c28a56f8e160d705", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0677f29571a466ecca094546f390e51dc216ff549c98d76c9fb0ebf9b95fb0cb0315918e5d4d00d670ef8f15ecbbd7e98b508cf2577579e438eecf8744daca18"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 08:47:12.427798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:47:12.428149) ========= 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.9s3uSDsjGr/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.CUnFfWP7gJ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.005243 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:47:12.476426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 08:47:12.476828) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 08:47:12.477138) ========= ========= Total master_lag: 0.20 =========