builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0222 starttime: 1467753336.41 results: success (0) buildid: 20160705121723 builduid: 15663881f04847ca8f7fddd4a660a773 revision: 52fb1d141cc9c18d7b7971d778b8976e43f360dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.406976) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.407445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.407731) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.430978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.431386) ========= 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.K6tVXuGxiF/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.Ddpo3soczX/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-07-05 14:15:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-07-05 14:15:36 (46.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113835 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.567141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.567472) ========= 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.K6tVXuGxiF/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.Ddpo3soczX/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.020346 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.616234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:36.616605) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 52fb1d141cc9c18d7b7971d778b8976e43f360dc --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-beta --rev 52fb1d141cc9c18d7b7971d778b8976e43f360dc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K6tVXuGxiF/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.Ddpo3soczX/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-07-05 14:15:36,700 truncating revision to first 12 chars 2016-07-05 14:15:36,701 Setting DEBUG logging. 2016-07-05 14:15:36,701 attempt 1/10 2016-07-05 14:15:36,701 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/52fb1d141cc9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 14:15:37,050 unpacking tar archive at: mozilla-beta-52fb1d141cc9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.611961 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:37.254639) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:37.254945) ========= script_repo_revision: 52fb1d141cc9c18d7b7971d778b8976e43f360dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:37.255369) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:37.255639) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 14:15:37.273908) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2016-07-05 14:15:37.274287) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K6tVXuGxiF/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.Ddpo3soczX/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:15:37 INFO - MultiFileLogger online at 20160705 14:15:37 in /builds/slave/test 14:15:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:15:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:15:37 INFO - {'append_to_log': False, 14:15:37 INFO - 'base_work_dir': '/builds/slave/test', 14:15:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:15:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:15:37 INFO - 'branch': 'Mozilla-Beta', 14:15:37 INFO - 'buildbot_json_path': 'buildprops.json', 14:15:37 INFO - 'config_files': ('talos/mac_config.py',), 14:15:37 INFO - 'default_actions': ('clobber', 14:15:37 INFO - 'read-buildbot-config', 14:15:37 INFO - 'download-and-extract', 14:15:37 INFO - 'populate-webroot', 14:15:37 INFO - 'create-virtualenv', 14:15:37 INFO - 'install', 14:15:37 INFO - 'run-tests'), 14:15:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:15:37 INFO - 'download_minidump_stackwalk': True, 14:15:37 INFO - 'download_symbols': 'ondemand', 14:15:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:15:37 INFO - 'tooltool.py': '/tools/tooltool.py', 14:15:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:15:37 INFO - '/tools/misc-python/virtualenv.py')}, 14:15:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:15:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:15:37 INFO - 'installer_path': 'installer.exe', 14:15:37 INFO - 'log_level': 'info', 14:15:37 INFO - 'log_name': 'talos', 14:15:37 INFO - 'log_to_console': True, 14:15:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:15:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:15:37 INFO - 'opt_config_files': (), 14:15:37 INFO - 'pip_index': False, 14:15:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:15:37 INFO - 'cmd': ('bash', 14:15:37 INFO - '-c', 14:15:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:15:37 INFO - 'enabled': True, 14:15:37 INFO - 'halt_on_failure': False, 14:15:37 INFO - 'name': 'check_screen_resolution'},), 14:15:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:15:37 INFO - 'cmd': ('bash', 14:15:37 INFO - '-c', 14:15:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:15:37 INFO - 'enabled': True, 14:15:37 INFO - 'halt_on_failure': False, 14:15:37 INFO - 'name': 'check_screen_resolution'},), 14:15:37 INFO - 'run_cmd_checks_enabled': True, 14:15:37 INFO - 'sps_profile': False, 14:15:37 INFO - 'sps_profile_interval': 0, 14:15:37 INFO - 'suite': 'dromaeojs-e10s', 14:15:37 INFO - 'system_bits': '32', 14:15:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:15:37 INFO - 'title': 't-yosemite-r7-0222', 14:15:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:15:37 INFO - 'use_talos_json': True, 14:15:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:15:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:15:37 INFO - 'work_dir': 'build'} 14:15:37 INFO - ##### 14:15:37 INFO - ##### Running clobber step. 14:15:37 INFO - ##### 14:15:37 INFO - Running pre-action listener: _resource_record_pre_action 14:15:37 INFO - Running main action method: clobber 14:15:37 INFO - rmtree: /builds/slave/test/build 14:15:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:15:37 INFO - Running post-action listener: _resource_record_post_action 14:15:37 INFO - ##### 14:15:37 INFO - ##### Running read-buildbot-config step. 14:15:37 INFO - ##### 14:15:37 INFO - Running pre-action listener: _resource_record_pre_action 14:15:37 INFO - Running main action method: read_buildbot_config 14:15:37 INFO - Using buildbot properties: 14:15:37 INFO - { 14:15:37 INFO - "project": "", 14:15:37 INFO - "product": "firefox", 14:15:37 INFO - "script_repo_revision": "production", 14:15:37 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 14:15:37 INFO - "repository": "", 14:15:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 14:15:37 INFO - "buildid": "20160705121723", 14:15:37 INFO - "pgo_build": "False", 14:15:37 INFO - "basedir": "/builds/slave/test", 14:15:37 INFO - "buildnumber": 5, 14:15:37 INFO - "slavename": "t-yosemite-r7-0222", 14:15:37 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:15:37 INFO - "platform": "yosemite_r7", 14:15:37 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 14:15:37 INFO - "branch": "mozilla-beta", 14:15:37 INFO - "repo_path": "releases/mozilla-beta", 14:15:37 INFO - "revision": "52fb1d141cc9c18d7b7971d778b8976e43f360dc", 14:15:37 INFO - "stage_platform": "macosx64", 14:15:37 INFO - "builduid": "15663881f04847ca8f7fddd4a660a773", 14:15:37 INFO - "slavebuilddir": "test" 14:15:37 INFO - } 14:15:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 14:15:37 INFO - Running post-action listener: _resource_record_post_action 14:15:37 INFO - ##### 14:15:37 INFO - ##### Running download-and-extract step. 14:15:37 INFO - ##### 14:15:37 INFO - Running pre-action listener: _resource_record_pre_action 14:15:37 INFO - Running main action method: download_and_extract 14:15:37 INFO - mkdir: /builds/slave/test/build/tests 14:15: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')]} 14:15:37 INFO - https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:15:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 14:15:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 14:15:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 14:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 14:15:41 INFO - Downloaded 1165 bytes. 14:15:41 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 14:15:41 INFO - Using the following test package requirements: 14:15:41 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 14:15:41 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 14:15:41 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 14:15:41 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:15:41 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 14:15:41 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 14:15:41 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 14:15:41 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 14:15:41 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 14:15:41 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 14:15:41 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 14:15:41 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 14:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:41 INFO - https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:15:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 14:15:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 14:15:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 14:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 14:15:43 INFO - Downloaded 18351384 bytes. 14:15:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:15:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:15:46 INFO - Return code: 0 14:15:46 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:15:46 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')]} 14:15:46 INFO - https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:15:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 14:15:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 14:15:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 14:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 14:15:48 INFO - Downloaded 18351384 bytes. 14:15:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:15:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:15:51 INFO - Return code: 0 14:15:51 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')]} 14:15:51 INFO - https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:15:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 14:15:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 14:15:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 14:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 14:15:53 INFO - Downloaded 10840145 bytes. 14:15:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:15:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:15:53 INFO - Return code: 0 14:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:53 INFO - https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 14:15:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 14:15:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 14:15:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 14:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 14:16:27 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg: The read operation timed out 14:16:27 INFO - retry: attempt #1 caught exception: The read operation timed out 14:16:27 INFO - retry: Failed, sleeping 30 seconds before retrying 14:16:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #2 14:17:05 INFO - Downloaded 86189986 bytes. 14:17:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 14:17:05 INFO - mkdir: /builds/slave/test/properties 14:17:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:17:05 INFO - Writing to file /builds/slave/test/properties/build_url 14:17:05 INFO - Contents: 14:17:05 INFO - build_url:https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 14:17:12 INFO - Running post-action listener: _resource_record_post_action 14:17:12 INFO - Running post-action listener: set_extra_try_arguments 14:17:12 INFO - ##### 14:17:12 INFO - ##### Running populate-webroot step. 14:17:12 INFO - ##### 14:17:12 INFO - Running pre-action listener: _resource_record_pre_action 14:17:12 INFO - Running main action method: populate_webroot 14:17:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:17:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:17:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:17:12 INFO - '--tppagecycles', 14:17:12 INFO - '7'], 14:17:12 INFO - 'tests': ['tsvgm']}}, 14:17:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:17:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:17:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:17:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:17:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['damp', 'tps']}, 14:17:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'tests': ['damp', 'tps']}, 14:17:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['dromaeo_dom']}, 14:17:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:17:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['a11yr', 14:17:12 INFO - 'ts_paint', 14:17:12 INFO - 'tpaint', 14:17:12 INFO - 'sessionrestore', 14:17:12 INFO - 'sessionrestore_no_auto_restore', 14:17:12 INFO - 'tabpaint']}, 14:17:12 INFO - 'other-e10s': {'tests': ['a11yr', 14:17:12 INFO - 'ts_paint', 14:17:12 INFO - 'tpaint', 14:17:12 INFO - 'sessionrestore', 14:17:12 INFO - 'sessionrestore_no_auto_restore', 14:17:12 INFO - 'tabpaint']}, 14:17:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:17:12 INFO - 'ts_paint', 14:17:12 INFO - 'tpaint', 14:17:12 INFO - 'sessionrestore', 14:17:12 INFO - 'sessionrestore_no_auto_restore', 14:17:12 INFO - 'tabpaint']}, 14:17:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:17:12 INFO - 'ts_paint', 14:17:12 INFO - 'tpaint', 14:17:12 INFO - 'sessionrestore', 14:17:12 INFO - 'sessionrestore_no_auto_restore', 14:17:12 INFO - 'tabpaint']}, 14:17:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['a11yr', 14:17:12 INFO - 'ts_paint', 14:17:12 INFO - 'tpaint', 14:17:12 INFO - 'sessionrestore', 14:17:12 INFO - 'sessionrestore_no_auto_restore', 14:17:12 INFO - 'tabpaint']}, 14:17:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['a11yr', 14:17:12 INFO - 'ts_paint', 14:17:12 INFO - 'tpaint', 14:17:12 INFO - 'sessionrestore', 14:17:12 INFO - 'sessionrestore_no_auto_restore', 14:17:12 INFO - 'tabpaint']}, 14:17:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['tsvgx', 14:17:12 INFO - 'tsvgr_opacity', 14:17:12 INFO - 'tart', 14:17:12 INFO - 'tscrollx', 14:17:12 INFO - 'cart']}, 14:17:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:17:12 INFO - 'tsvgr_opacity', 14:17:12 INFO - 'tart', 14:17:12 INFO - 'tscrollx', 14:17:12 INFO - 'cart']}, 14:17:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'talos_options': ['--disable-e10s'], 14:17:12 INFO - 'tests': ['tp5o']}, 14:17:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'tests': ['tp5o']}, 14:17:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'talos_options': ['--disable-e10s', 14:17:12 INFO - '--xperf_path', 14:17:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:12 INFO - 'tests': ['tp5n']}, 14:17:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:12 INFO - 'talos_options': ['--xperf_path', 14:17:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:12 INFO - 'tests': ['tp5n']}}, 14:17:12 INFO - 'talos.zip': {'path': '', 14:17:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:17:12 INFO - Running post-action listener: _resource_record_post_action 14:17:12 INFO - ##### 14:17:12 INFO - ##### Running create-virtualenv step. 14:17:12 INFO - ##### 14:17:12 INFO - Running pre-action listener: _resource_record_pre_action 14:17:12 INFO - Running main action method: create_virtualenv 14:17:12 INFO - Creating virtualenv /builds/slave/test/build/venv 14:17:12 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 14:17:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:17:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:17:12 INFO - Using real prefix '/tools/python27' 14:17:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:17:13 INFO - Installing distribute.............................................................................................................................................................................................done. 14:17:16 INFO - Installing pip.................done. 14:17:16 INFO - Return code: 0 14:17:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:17: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')]} 14:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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')]} 14:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:16 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:16 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 14:17:16 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 14:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:16 INFO - 'HOME': '/Users/cltbld', 14:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:16 INFO - 'LOGNAME': 'cltbld', 14:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:16 INFO - 'MOZ_NO_REMOTE': '1', 14:17:16 INFO - 'NO_EM_RESTART': '1', 14:17:16 INFO - 'PAGER': '/bin/cat', 14:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:16 INFO - 'PWD': '/builds/slave/test', 14:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:16 INFO - 'SHELL': '/bin/bash', 14:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:16 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:16 INFO - 'USER': 'cltbld', 14:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:16 INFO - 'XPC_FLAGS': '0x0', 14:17:16 INFO - 'XPC_SERVICE_NAME': '0', 14:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:16 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 14:17:16 INFO - Cleaning up... 14:17:16 INFO - Return code: 0 14:17:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:17: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')]} 14:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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')]} 14:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:16 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:16 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 14:17:16 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 14:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:16 INFO - 'HOME': '/Users/cltbld', 14:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:16 INFO - 'LOGNAME': 'cltbld', 14:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:16 INFO - 'MOZ_NO_REMOTE': '1', 14:17:16 INFO - 'NO_EM_RESTART': '1', 14:17:16 INFO - 'PAGER': '/bin/cat', 14:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:16 INFO - 'PWD': '/builds/slave/test', 14:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:16 INFO - 'SHELL': '/bin/bash', 14:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:16 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:16 INFO - 'USER': 'cltbld', 14:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:16 INFO - 'XPC_FLAGS': '0x0', 14:17:16 INFO - 'XPC_SERVICE_NAME': '0', 14:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:16 INFO - Downloading/unpacking psutil>=0.7.1 14:17:16 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 14:17:16 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 14:17:16 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 14:17:16 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 14:17:16 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 14:17:16 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 14:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:17:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:19 INFO - Installing collected packages: psutil 14:17:19 INFO - Running setup.py install for psutil 14:17:19 INFO - building 'psutil._psutil_osx' extension 14:17:19 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 14:17:20 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 14:17:20 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 14:17:20 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 14:17:20 INFO - building 'psutil._psutil_posix' extension 14:17:20 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 14:17:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:17:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:17:20 INFO - ^ 14:17:20 INFO - 1 warning generated. 14:17:20 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 14:17:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:20 INFO - Successfully installed psutil 14:17:20 INFO - Cleaning up... 14:17:20 INFO - Return code: 0 14:17:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:20 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:20 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 14:17:20 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 14:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:20 INFO - 'HOME': '/Users/cltbld', 14:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:20 INFO - 'LOGNAME': 'cltbld', 14:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:20 INFO - 'MOZ_NO_REMOTE': '1', 14:17:20 INFO - 'NO_EM_RESTART': '1', 14:17:20 INFO - 'PAGER': '/bin/cat', 14:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:20 INFO - 'PWD': '/builds/slave/test', 14:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:20 INFO - 'SHELL': '/bin/bash', 14:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:20 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:20 INFO - 'USER': 'cltbld', 14:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:20 INFO - 'XPC_FLAGS': '0x0', 14:17:20 INFO - 'XPC_SERVICE_NAME': '0', 14:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:17:20 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 14:17:20 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 14:17:20 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 14:17:20 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 14:17:20 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 14:17:20 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 14:17:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:17:23 INFO - Installing collected packages: mozsystemmonitor 14:17:23 INFO - Running setup.py install for mozsystemmonitor 14:17:23 INFO - Successfully installed mozsystemmonitor 14:17:23 INFO - Cleaning up... 14:17:23 INFO - Return code: 0 14:17:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:23 INFO - 'HOME': '/Users/cltbld', 14:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:23 INFO - 'LOGNAME': 'cltbld', 14:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:23 INFO - 'MOZ_NO_REMOTE': '1', 14:17:23 INFO - 'NO_EM_RESTART': '1', 14:17:23 INFO - 'PAGER': '/bin/cat', 14:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:23 INFO - 'PWD': '/builds/slave/test', 14:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:23 INFO - 'SHELL': '/bin/bash', 14:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:23 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:23 INFO - 'USER': 'cltbld', 14:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:23 INFO - 'XPC_FLAGS': '0x0', 14:17:23 INFO - 'XPC_SERVICE_NAME': '0', 14:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:23 INFO - Downloading/unpacking blobuploader==1.2.4 14:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:26 INFO - Downloading blobuploader-1.2.4.tar.gz 14:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:17:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:17:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:27 INFO - Downloading docopt-0.6.1.tar.gz 14:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:17:27 INFO - Installing collected packages: blobuploader, requests, docopt 14:17:27 INFO - Running setup.py install for blobuploader 14:17:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:17:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:17:27 INFO - Running setup.py install for requests 14:17:27 INFO - Running setup.py install for docopt 14:17:28 INFO - Successfully installed blobuploader requests docopt 14:17:28 INFO - Cleaning up... 14:17:28 INFO - Return code: 0 14:17:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:28 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')]} 14:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:28 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')]} 14:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:28 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:28 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 14:17:28 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 14:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:28 INFO - 'HOME': '/Users/cltbld', 14:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:28 INFO - 'LOGNAME': 'cltbld', 14:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:28 INFO - 'MOZ_NO_REMOTE': '1', 14:17:28 INFO - 'NO_EM_RESTART': '1', 14:17:28 INFO - 'PAGER': '/bin/cat', 14:17:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:28 INFO - 'PWD': '/builds/slave/test', 14:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:28 INFO - 'SHELL': '/bin/bash', 14:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:28 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:28 INFO - 'USER': 'cltbld', 14:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:28 INFO - 'XPC_FLAGS': '0x0', 14:17:28 INFO - 'XPC_SERVICE_NAME': '0', 14:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:28 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-LDSEHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:28 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Ti8RIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:28 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-AL6kFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:28 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-1074IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:28 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-vRDjcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-hd0qQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-PdPH2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-D4Fla3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-nouiYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-UWx2Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-LhNBn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-snPYsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-BduJOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-si1ZvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:29 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-8YaOu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:30 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kfld0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:30 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-26lnaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:17:30 INFO - Running setup.py install for manifestparser 14:17:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:17:30 INFO - Running setup.py install for mozcrash 14:17:30 INFO - Running setup.py install for mozdebug 14:17:30 INFO - Running setup.py install for mozdevice 14:17:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:17:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:17:30 INFO - Running setup.py install for mozfile 14:17:30 INFO - Running setup.py install for mozhttpd 14:17:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Running setup.py install for mozinfo 14:17:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Running setup.py install for mozInstall 14:17:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Running setup.py install for mozleak 14:17:31 INFO - Running setup.py install for mozlog 14:17:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Running setup.py install for moznetwork 14:17:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:17:31 INFO - Running setup.py install for mozprocess 14:17:32 INFO - Running setup.py install for mozprofile 14:17:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:17:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:17:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:17:32 INFO - Running setup.py install for mozrunner 14:17:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:17:32 INFO - Running setup.py install for mozscreenshot 14:17:32 INFO - Running setup.py install for moztest 14:17:32 INFO - Running setup.py install for mozversion 14:17:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:17:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:17:32 INFO - Cleaning up... 14:17:32 INFO - Return code: 0 14:17:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17: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')]} 14:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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')]} 14:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:32 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:32 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 14:17:32 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 14:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:32 INFO - 'HOME': '/Users/cltbld', 14:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:32 INFO - 'LOGNAME': 'cltbld', 14:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:32 INFO - 'MOZ_NO_REMOTE': '1', 14:17:32 INFO - 'NO_EM_RESTART': '1', 14:17:32 INFO - 'PAGER': '/bin/cat', 14:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:32 INFO - 'PWD': '/builds/slave/test', 14:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:32 INFO - 'SHELL': '/bin/bash', 14:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:32 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:32 INFO - 'USER': 'cltbld', 14:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:32 INFO - 'XPC_FLAGS': '0x0', 14:17:32 INFO - 'XPC_SERVICE_NAME': '0', 14:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7umoH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-tA9dhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-nrueWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-OVEFnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-yF2vpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-JfhXdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-AKlSV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-v3DWWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:33 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)) 14:17:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:33 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-hfmZ9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-2K_ZDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-C50yBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-BXycSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-BSCUz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ppgUhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-6sKCXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-c11Hqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:34 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)) 14:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-fLBaA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:34 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)) 14:17:34 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)) 14:17:34 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)) 14:17:34 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)) 14:17:34 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)) 14:17:34 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)) 14:17:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:37 INFO - Downloading blessings-1.6.tar.gz 14:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:17:37 INFO - Installing collected packages: blessings 14:17:37 INFO - Running setup.py install for blessings 14:17:37 INFO - Successfully installed blessings 14:17:37 INFO - Cleaning up... 14:17:37 INFO - Return code: 0 14:17:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:17:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:37 INFO - Reading from file tmpfile_stdout 14:17:37 INFO - Current package versions: 14:17:37 INFO - blessings == 1.6 14:17:37 INFO - blobuploader == 1.2.4 14:17:37 INFO - docopt == 0.6.1 14:17:37 INFO - manifestparser == 1.1 14:17:37 INFO - mozInstall == 1.12 14:17:37 INFO - mozcrash == 0.17 14:17:37 INFO - mozdebug == 0.1 14:17:37 INFO - mozdevice == 0.48 14:17:37 INFO - mozfile == 1.2 14:17:37 INFO - mozhttpd == 0.7 14:17:37 INFO - mozinfo == 0.9 14:17:37 INFO - mozleak == 0.1 14:17:37 INFO - mozlog == 3.1 14:17:37 INFO - moznetwork == 0.27 14:17:37 INFO - mozprocess == 0.22 14:17:37 INFO - mozprofile == 0.28 14:17:37 INFO - mozrunner == 6.11 14:17:37 INFO - mozscreenshot == 0.1 14:17:37 INFO - mozsystemmonitor == 0.0 14:17:37 INFO - moztest == 0.7 14:17:37 INFO - mozversion == 1.4 14:17:37 INFO - psutil == 3.1.1 14:17:37 INFO - requests == 1.2.3 14:17:37 INFO - wsgiref == 0.1.2 14:17:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17: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')]} 14:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17: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')]} 14:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:37 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:37 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 14:17:37 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 14:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:37 INFO - 'HOME': '/Users/cltbld', 14:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:37 INFO - 'LOGNAME': 'cltbld', 14:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:37 INFO - 'MOZ_NO_REMOTE': '1', 14:17:37 INFO - 'NO_EM_RESTART': '1', 14:17:37 INFO - 'PAGER': '/bin/cat', 14:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:37 INFO - 'PWD': '/builds/slave/test', 14:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:37 INFO - 'SHELL': '/bin/bash', 14:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:37 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:37 INFO - 'USER': 'cltbld', 14:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:37 INFO - 'XPC_FLAGS': '0x0', 14:17:37 INFO - 'XPC_SERVICE_NAME': '0', 14:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 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)) 14:17:38 INFO - Cleaning up... 14:17:38 INFO - Return code: 0 14:17:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:38 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 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:38 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 14:17:38 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 14:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:38 INFO - 'HOME': '/Users/cltbld', 14:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:38 INFO - 'LOGNAME': 'cltbld', 14:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:38 INFO - 'MOZ_NO_REMOTE': '1', 14:17:38 INFO - 'NO_EM_RESTART': '1', 14:17:38 INFO - 'PAGER': '/bin/cat', 14:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:38 INFO - 'PWD': '/builds/slave/test', 14:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:38 INFO - 'SHELL': '/bin/bash', 14:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:38 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:38 INFO - 'USER': 'cltbld', 14:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:38 INFO - 'XPC_FLAGS': '0x0', 14:17:38 INFO - 'XPC_SERVICE_NAME': '0', 14:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:38 INFO - Downloading/unpacking jsonschema 14:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:17:40 INFO - Downloading/unpacking functools32 (from jsonschema) 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:41 INFO - Downloading functools32-3.2.3-2.tar.gz 14:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:17:41 INFO - warning: no files found matching '*.txt' 14:17:41 INFO - no previously-included directories found matching 'build' 14:17:41 INFO - no previously-included directories found matching 'dist' 14:17:41 INFO - no previously-included directories found matching '.git*' 14:17:41 INFO - Installing collected packages: jsonschema, functools32 14:17:41 INFO - Running setup.py install for functools32 14:17:41 INFO - warning: no files found matching '*.txt' 14:17:41 INFO - no previously-included directories found matching 'build' 14:17:41 INFO - no previously-included directories found matching 'dist' 14:17:41 INFO - no previously-included directories found matching '.git*' 14:17:41 INFO - Successfully installed jsonschema functools32 14:17:41 INFO - Cleaning up... 14:17:41 INFO - Return code: 0 14:17:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc79a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc89420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefb8cba6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, '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.K6tVXuGxiF/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.Ddpo3soczX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 14:17:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:17:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:17:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:17:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:41 INFO - 'HOME': '/Users/cltbld', 14:17:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:41 INFO - 'LOGNAME': 'cltbld', 14:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:41 INFO - 'MOZ_NO_REMOTE': '1', 14:17:41 INFO - 'NO_EM_RESTART': '1', 14:17:41 INFO - 'PAGER': '/bin/cat', 14:17:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:41 INFO - 'PWD': '/builds/slave/test', 14:17:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:41 INFO - 'SHELL': '/bin/bash', 14:17:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:17:41 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:17:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:41 INFO - 'USER': 'cltbld', 14:17:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:41 INFO - 'XPC_FLAGS': '0x0', 14:17:41 INFO - 'XPC_SERVICE_NAME': '0', 14:17:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:41 INFO - Downloading/unpacking flake8 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:17:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:17:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:17:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:17:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:17:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:17:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:17:45 INFO - no previously-included directories found matching 'docs/_build' 14:17:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:17:45 INFO - Running setup.py install for pep8 14:17:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:17:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:17:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:17:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:17:45 INFO - no previously-included directories found matching 'docs/_build' 14:17:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:17:45 INFO - Cleaning up... 14:17:46 INFO - Return code: 0 14:17:46 INFO - Running post-action listener: _resource_record_post_action 14:17:46 INFO - Running post-action listener: _start_resource_monitoring 14:17:46 INFO - Starting resource monitoring. 14:17:46 INFO - ##### 14:17:46 INFO - ##### Running install step. 14:17:46 INFO - ##### 14:17:46 INFO - Running pre-action listener: _resource_record_pre_action 14:17:46 INFO - Running main action method: install 14:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:46 INFO - Reading from file tmpfile_stdout 14:17:46 INFO - Detecting whether we're running mozinstall >=1.0... 14:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:17:46 INFO - Reading from file tmpfile_stdout 14:17:46 INFO - Output received: 14:17:46 INFO - Usage: mozinstall [options] installer 14:17:46 INFO - Options: 14:17:46 INFO - -h, --help show this help message and exit 14:17:46 INFO - -d DEST, --destination=DEST 14:17:46 INFO - Directory to install application into. [default: 14:17:46 INFO - "/builds/slave/test"] 14:17:46 INFO - --app=APP Application being installed. [default: firefox] 14:17:46 INFO - mkdir: /builds/slave/test/build/application 14:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 14:18:08 INFO - Reading from file tmpfile_stdout 14:18:08 INFO - Output received: 14:18:08 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 14:18:08 INFO - Running post-action listener: _resource_record_post_action 14:18:08 INFO - ##### 14:18:08 INFO - ##### Running run-tests step. 14:18:08 INFO - ##### 14:18:08 INFO - Running pre-action listener: _resource_record_pre_action 14:18:08 INFO - Running main action method: run_tests 14:18:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:18:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:18:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:18:08 INFO - 2016-07-05 14:18:08.085 screenresolution[1918:9137] starting screenresolution argv=screenresolution get 14:18:08 INFO - 2016-07-05 14:18:08.088 screenresolution[1918:9137] Display 0: 1600x1200x32@60 14:18:08 INFO - 2016-07-05 14:18:08.094 screenresolution[1919:9139] starting screenresolution argv=screenresolution list 14:18:08 INFO - Available Modes on Display 0 14:18:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:18:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:18:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:18:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:18:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:18:08 INFO - Intel Iris: 14:18:08 INFO - Chipset Model: Intel Iris 14:18:08 INFO - Type: GPU 14:18:08 INFO - Bus: Built-In 14:18:08 INFO - VRAM (Dynamic, Max): 1536 MB 14:18:08 INFO - Vendor: Intel (0x8086) 14:18:08 INFO - Device ID: 0x0a2e 14:18:08 INFO - Revision ID: 0x0009 14:18:08 INFO - Displays: 14:18:08 INFO - Display: 14:18:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:18:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:18:08 INFO - Display Serial Number: 1600x1200 60 14:18:08 INFO - Main Display: Yes 14:18:08 INFO - Mirror: Off 14:18:08 INFO - Online: Yes 14:18:08 INFO - Rotation: Supported 14:18:08 INFO - Return code: 0 14:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:18:08 INFO - Python 2.7.3 14:18:08 INFO - Return code: 0 14:18:08 INFO - grabbing minidump binary from tooltool 14:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:08 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 0x10db18e00>, '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 0x10d981db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db13b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:18:08 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 14:18:08 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 14:18:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:18:08 INFO - Return code: 0 14:18:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:18:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:18:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:18:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:18:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:18:09 INFO - Return code: 0 14:18:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 14:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0222 --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 14:18:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6tVXuGxiF/Render', 14:18:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:09 INFO - 'HOME': '/Users/cltbld', 14:18:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:09 INFO - 'LOGNAME': 'cltbld', 14:18:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:18:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:09 INFO - 'MOZ_NO_REMOTE': '1', 14:18:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:18:09 INFO - 'NO_EM_RESTART': '1', 14:18:09 INFO - 'PAGER': '/bin/cat', 14:18:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:09 INFO - 'PWD': '/builds/slave/test', 14:18:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:18:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:09 INFO - 'SHELL': '/bin/bash', 14:18:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ddpo3soczX/Listeners', 14:18:09 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 14:18:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:09 INFO - 'USER': 'cltbld', 14:18:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:09 INFO - 'XPC_FLAGS': '0x0', 14:18:09 INFO - 'XPC_SERVICE_NAME': '0', 14:18:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 14:18:09 INFO - mozversion application_buildid: 20160705121723 14:18:09 INFO - mozversion application_changeset: 52fb1d141cc9c18d7b7971d778b8976e43f360dc 14:18:09 INFO - mozversion application_display_name: Firefox 14:18:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:18:09 INFO - mozversion application_name: Firefox 14:18:09 INFO - mozversion application_remotingname: firefox 14:18:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:18:09 INFO - mozversion application_vendor: Mozilla 14:18:09 INFO - mozversion application_version: 48.0 14:18:09 INFO - mozversion platform_buildid: 20160705121723 14:18:09 INFO - mozversion platform_changeset: 52fb1d141cc9c18d7b7971d778b8976e43f360dc 14:18:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:18:09 INFO - mozversion platform_version: 48.0 14:18:09 INFO - using testdate: 1467753489 14:18:09 INFO - actual date: 1467753489 14:18:09 INFO - starting webserver on 'localhost:49467' 14:18:09 INFO - SUITE-START | Running 2 tests 14:18:09 INFO - TEST-START | dromaeo_css 14:18:09 INFO - operating with platform_type : mac_ 14:18:09 INFO - Initialising browser for dromaeo_css test... 14:18:09 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpEdlaZa/profile http://localhost:49467/getInfo.html) 14:18:12 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 14:18:12 INFO - PROCESS | 1937 | colorDepth:24 14:18:12 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 14:18:12 INFO - PROCESS | 1937 | __metrics 14:18:12 ERROR - PROCESS | 1937 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 14:18:13 INFO - TEST-INFO | 1937: exit 0 14:18:13 INFO - Browser initialized. 14:18:13 INFO - Running cycle 1/1 for dromaeo_css test... 14:18:13 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpEdlaZa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:25 INFO - PROCESS | 1941 | RSS: Main: 170967040 14:18:25 INFO - PROCESS | 1941 | 14:19:14 INFO - PROCESS | 1941 | 1467753554643 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] 14:21:24 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49467/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49467/tests/dromaeo/cssquery-ext.html) 14:21:25 INFO - PROCESS | 1941 | RSS: Main: 182181888 14:21:25 INFO - PROCESS | 1941 | 14:24:27 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49467/tests/dromaeo/cssquery-ext.html (next: http://localhost:49467/tests/dromaeo/cssquery-jquery.html) 14:24:28 INFO - PROCESS | 1941 | RSS: Main: 192741376 14:24:28 INFO - PROCESS | 1941 | 14:27:30 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49467/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49467/tests/dromaeo/cssquery-mootools.html) 14:27:30 INFO - PROCESS | 1941 | RSS: Main: 194674688 14:27:30 INFO - PROCESS | 1941 | 14:30:32 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49467/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49467/tests/dromaeo/cssquery-prototype.html) 14:30:32 INFO - PROCESS | 1941 | RSS: Main: 198578176 14:30:32 INFO - PROCESS | 1941 | 14:33:35 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49467/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49467/tests/dromaeo/cssquery-yui.html) 14:33:35 INFO - PROCESS | 1941 | RSS: Main: 197554176 14:33:35 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49467/tests/dromaeo/cssquery-yui.html (next: http://localhost:49467/tests/dromaeo/cssquery-dojo.html) 14:36:38 INFO - PROCESS | 1941 | RSS: Main: 202510336 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | __start_tp_report 14:36:38 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 14:36:38 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 14:36:38 INFO - PROCESS | 1941 | |i|pagename|runs| 14:36:38 INFO - PROCESS | 1941 | |0;dojo.html;3600.7839529628222;3645.125169959209;3700.3524383232943;3704.629537046297;3705.8147092645368;5849.561282903784;5950.880982380355;5954.553408494358;5960.493358064559;5985.371535988718;5964.850878728034;5990.191324171246;5999.250093738283;6014.578979471439;6031.668258245806;5299.973500132494;5331.866703332409;5343.358796944372;5385.165299378583;5441.211024401459;5340.332458442695;5382.461753824606;5395.433479484656;5474.945250547503;5475.479829416199;5385.946140538584;5391.029614669358;5402.513773760361;5407.594430417715;5414.052540805363;5408.296921400193;5417.674939503642;5442.591805614595;5449.128139497681;5492.80775172867;5156.8968620627375;5179.870503237412;5338.759755810959;5345.812896548641;5374.677519348813;3944.9013774655577;5242.689327668083;5703.77184912603;5853.209816674737;5876.6180198286975;6241.469475094623;6276.184096067527;6279.0895320178315;6286.339934306878;6299.244090709144;5401.378841433247;6181.320054793969;6240.656763877985;6306.180196574416;6327.905081423783;6027.427394397525;6028.547858910511;6047.183630209909;6048.909266361052;6057.606255593417;6318.71565779541;6471.482281417475;6491.967540162364;6511.38141876521;6540.345965403421;6143.327652471189;6185.443310102022;6212.627242365473;6246.6876656167005;6260.906086408708;6264.624122552661;6302.590331628429;6309.400606942333;6351.5871468355335;6363.5545511813725;6144.064619644887;6191.752329906843;6261.279952805395;6272.874542509216;6293.716782744696;6141.508679305645;6201.7519299228425;6234.625922444668;6267.62394256346;6267.6866156691985;4409.801558929856;5000.549950504472;5008.84973450797;5061.215511595708;5067.5185857343495;4483.327500874895;4510.684252102321;4551.3173024046655;4583.587477127008;4596.49438561758;6037.607555508878;6094.238220222473;6129.63387754696;6138.786925362681;6187.195664563578;6263.498920086382;6305.464035557029;6312.400321969406;6326.620402775849;6361.840953976188;6031.788887388921;6136.662483563447;6177.907331390033;6179.3202747697715;6205.565610407228;5003.249512573143;5444.564434845203;5575.0522411190195;5581.079121944834;5591.832245032574;6643.269240383651;6664.566803157779;6670.199576050905;6703.765368212089;6723.957786543094;6200.441960223421;6245.875082498415;6282.780102696383;6299.401556852092;6325.335839736761;6595.4393876521035;6613.206415230011;6626.403623673892;6628.734850605922;6647.268800432045;6211.2857021443115;6213.161223234805;6247.094171345038;6251.874962500816;6271.216097987752;6209.813705588839;6279.748810047547;6287.937120628918;6307.779227727007;6331.176946997045;6101.145839582373;6193.84515387119;6193.876122477615;6212.1303017578475;6227.159333490102;6081.756729730761;6191.752329906753;6250;6253.749850005949;6268.529860260407;5452.291202143854;6124.5406594506485;6149.923763341308;6166.907496387468;6203.937960620336;6209.285932117684;6215.533834962305;6221;6221.813345599639;6248.4376406123665;6086.543509236736;6209.054122155177;6220.098085777628;6225.62646241227;6241.750329986749 14:36:38 INFO - PROCESS | 1941 | |1;ext.html;555.4972749661557;561.0910325273059;565.4493268574104;592.5022980696215;601.5097695378266;31649.36701265976;39264.41103383451;39300.80349598255;39373.80313098434;39406.8029659852;32304;40750.59249407505;40899.36402543895;40924;41353.79323103388;9537.713868583936;9669.806603867919;9733.075357840993;9735.97772233916;9747.512624368788;8491.74524764258;9636.22910167185;9844.212463002978;9844.458554779485;9845.556949937234;9779.853302200472;9858.852117218248;9873.259505537078;9885.505724713736;9912.306138570268;9828.459434731087;9868.703938881808;9882.802343953117;9889.011098890125;9915.851262231072;9708;9786.41281523111;9816.754581135458;9831.852522212173;9872.60509579616;39859;40298.99252518681;40347;40463.7976810117;40657.39013914794;2836.468307114156;3203.183188286995;3344.5986481622363;3375.2911888503445;3608.5128507651393;26459.33851653705;26484.867575662192;26643.46713065747;26683.066092686662;27832.443351132864;2706.824056436345;2716.171567671859;2722.278596172016;2733.0024541042576;2798.837284157249;374599;378580;378783;382274;383086;35453.645463545035;35809.46285805715;37288.62711372906;37948.67179648699;38221.42667859984;36515.452268215995;36621.450678239846;36659.63340366616;36833.26333473316;36860.447093293624;15740.60648483797;16153.676926461405;16285.918570407071;16300.347986080424;16327.938683985503;13814.723705525834;13886.722265554632;13905.930470347786;13938.024338296425;13983.650408739863;8299.460535065202;8364.539950302791;8402.495850249004;8466.449680770731;8585.227329540372;3567.910802229965;3580.534530511069;3608.3685355062757;3611.1513794258326;3614.2590768892314;939.5349302095507;977.8630991661173;1005.2561104782554;1048.758785479344;1090.0353187429182;24966.625500617505;28598;29214;29318.41363172767;29479.410411791643;1088.7169335972546;1125.0830573083074;1214.690253985228;1232.7164752107064;1252.3988485526997;13263.801042984363;14296.428142874376;14681.972261941775;14919.477818276308;15393.846061539465;335754;338259;338502;339748;341953.16093677987;63626;63650.36349636537;63897.680511597144;64054.67972660107;64074.35925640777;1384.78535826947;1525.4355888321393;1551.7594772810232;1561.3598424646068;1564.7496400575853;30007;30124;30554.541681874343;31071.84464077665;31667.36665266636;59002.70498647479;60992.39007609867;61022;61085.389146109745;61619.6919015402;593011;595246;601118;601457;602081;42790.786046069574;42855.714328568945;44086.55913440824;45231;45350.31974520319;48302;48357;48845;49907.75046124746;50091.24863126983;39120.413193802684;39906.60093399145;40299;40310.596894030685;41155;38861.417078743274;38962.636307729095;39471.013224668466;39654.603453965094;40294.39558406685;39626.80186599164;39934.201315974096;40197.397039043855;41287.587124129575;41378.793106034274;32565.674343256265;33804.640047793895;33811;33902.660973390935;34292;21635;24343.63484547698;25457.981680732562;25812.87093564595;25884.964601415733 14:36:38 INFO - PROCESS | 1941 | |2;jquery.html;337.25634974880376;418.4309339298554;420.0967918974203;422.22943128789956;428.94479580232627;15660.765088523653;20653.554251202422;21307;21365.252216172437;21399;20898.05958731857;21309.57380852382;21660.2418915338;21858.562828743416;22385;5925;5942.673152976585;6181.505479561647;6211.0993905883615;6233.906491402633;6004.909926351108;6111.480524155452;6115.29674087479;6125.326214116443;6135.2024236849375;5970;6080.331163572004;6129.938700613004;6135.110408990694;6140.570160088796;6005.099235114724;6042.063480160582;6068.453839154476;6124.234470691142;6134.539909506805;5264.842054738364;5272.340957380327;5289.86775330616;5295.470452954673;5412.3505179378335;26251.21246362612;26730.73269267302;26875;27083.729162708318;27304.317392065157;2276.1578216060166;2425.2966639674473;2449.8407603505716;2493.8254322197454;2551.387666959935;15182.748830327415;15969.520914372588;16006.679866402606;16205.270762815702;16214.75677864833;1794.4616615015443;1824.2338217948493;1874.5227602796751;1902.771667399907;1925.210663627906;103730.4067398321;117013;121594;123949.38025309907;125568.37215813862;11461;11670.183087183816;11963.162578619413;12014.717203731263;12020.699482512833;14835;14889.329980150918;14897.329620167118;14926.70146597062;14946.551603451913;9320.813583728288;9488.430694158373;9503.429794212369;9555.948845626976;9562.808743825224;8031.317338026333;9682.951585242028;9683;9686.951565242129;9699.903000970042;7061.682224299919;7111.542133862546;7142.64286785669;7171;7194.352508274281;1834.2112891456802;1895.8862468251948;2042.9795702042788;2065.2651772511163;2101.8965043352364;999.7553046457062;1007.9193664506822;1069.502681253221;1086.006304231635;1172.466527729898;21179.78820211809;21296.893515532323;22121.889390552944;22209.55580888373;22404.215852445086;923.7044145873256;988.2489308125876;1056.4559251985233;1064.3720205079042;1069.2034434346463;11206.271592346471;11315.225517069524;11415.315081095161;11448.3703396314;11512.712182195344;676494;685164;688583;691018;694076;116361.41819290849;128473;129436.3528182353;136859;140207;1070.4273213830563;1097.4786976186247;1104.1056744037355;1109.678193323927;1112.2103306652207;18818.52953676169;19251;19482.318118865773;19488.707669385254;19791.505212369804;51465.7426712864;51909;52101;54244.64388390322;54508.72745636247;76230.23769762453;77749.61125194338;79251.60374198091;80169.59915200387;80271.39457210706;8063.637136328762;8410;8502.91497085046;8547.615357308812;8769.605367758593;10979.121670266519;12505.43725532335;12624;12927.741445171232;13087.476500940236;9338.533073346442;10974.506147223554;11195.60815371458;11253.831192531954;11341.773164536498;10844.67465976022;10940.452977350942;10993.175511836484;11072.256453945763;11133;11262.297048708415;11264.211505194558;11412.771744565229;11467.713307167387;11487.712807179552;6731;7927.365810735012;8553.700620478254;8748.562571871254;8944.150305720947;10152.74618134523;10917.097700437052;11519.963203311741;11679.649610511698;11862.644120676394 14:36:38 INFO - PROCESS | 1941 | |3;mootools.html;1544.860962513374;1642.0640235066014;1893.5266183454137;1905.5140939060539;1967.9176452950874;7712.922870771291;8504.36217283703;9250.39872408293;9468.526573671315;9475.147236748691;9204.677836275732;9247.815043699122;9334.486603236819;9349.766255843608;9399.577019034143;2599.8440093594445;2620.5021046001293;2620.5545057340296;2633.8156329056974;2646.6559347284824;2114.3339847947846;2604.4791041791623;2609.0998605481077;2611.425486393;2629.1849526646706;2156.363872657562;2588.417606038645;2619.947601047978;2622.5672763993916;2631.802614803888;2597.5973724072705;2597.6363309136736;2614.2026681862026;2617.947641047178;2631.4868600622885;2604.2838219489604;2606.2832721001687;2640.854752988585;2643.061713091863;2645.021342103415;9307.162355388047;9436.433813971184;9510.47692376919;9511.953685094633;9524.428534287965;5752.338481074688;5847.707614619295;5912.704364781786;5952.791652292192;6008.038713805814;3696.2792255510185;3794.051487128218;3829.0044588406936;3830.6743926766258;3845.903852403684;1058.3015209961388;1077.9245452818307;1083.2038451737967;1115.983260251097;1116.865976082867;12654.493820247271;12703.301318427482;12720.300383478905;12766.489340426371;12775.29735864527;5118.25785261135;5163.199704045879;5187.714675692798;5249.475052494797;5257.76340064698;10569.101626361846;10724.356538607708;10796.974287442681;10798.190135739851;10830.296030758132;5735.942640573541;5852.736626851802;5877.52979761618;5911.940880591225;5922.1412895130825;4321.978390108092;4731.124741922756;5228.111221092423;5261.237120617489;5294.894102117936;4288.7212331199;4296.419983302277;4367.45406824147;4395.868123956287;4398.098389830077;1448.044290768088;1469.1992863889207;1469.7354476194387;1470.147314557554;1487.9032862863878;859.1709000814244;980.2990861534038;987.5605355616682;997.4165113408592;1001.8146642871092;6174.135621013062;6233.2208473940755;6238.594491358047;6281.905771413341;6324.747010119544;487.6367106505676;502.42472369137863;506.12693104394623;510.55071537047905;512.2955935588566;4098.262312783728;4131.880430275123;4168.624823765876;4183.5398106208295;4251.893702657397;10993.945030274957;11216.439178041066;11342.20604557673;11397.829032564518;11421.828672569918;11133.554657813758;11152.052075573667;11193.776124477465;11228.943855280835;11312.886871131348;8621.568921553897;8705.955285365797;8790.296776257885;8814.647414103492;8815.559222038872;5865.4134586541;5975.760969561169;5996.940030599638;6103.42017508336;6200.81397558074;10000.399976001463;10002.199824013916;10166.796664066529;10257.794844103226;10269.948650256702;10064.949675251577;10391.58433662645;10437.11284540792;10471.3193642413;10538.156947444339;7102.325279098477;7921.960390198012;7986.520808751494;8509;8677.956610216908;8649.783755406164;8697.739067827975;8710.998235203033;8732.956335218283;8785.868211976698;7487.588182649789;7556.962215188889;7703.922960770321;7746.225377462209;7973.641186146753;7116;7439.51643143194;7449;7485.88771168422;7647.770566883002;7119.2524784898915;7170.605616691132;7180.60506672138;7183.317584829433;7194.892076618959;4993.15116430215;5008.1235783736975;5119.692818430906;5186.740662966912;5239.109351410193;5111.974440127924;5177.922331165111;5178.482151784791;5240.161574148118;5255.973720131375 14:36:38 INFO - PROCESS | 1941 | |4;prototype.html;321.7136748294019;335.37848448381317;338.60214248258296;344.72795385029247;348.5033826796814;5670.71646417679;6616.569922955005;6781.762638307648;7012.649367531622;7013.368796808286;5236.8690782730355;6263.718132684029;6725.159355080615;6947.4442044636435;6994.3355381238825;5129.102407078765;5162.432132465426;5166.509181627758;5170.586353091744;5170.9741451292875;5138.820141295056;5171.301874246985;5171.974140129294;5174.767135478913;5175.378954525462;5083.66956147851;5092.312537807404;5092.388913330406;5109.846704598868;5128.512791284822;4964.726940018298;5069.188929771219;5107.182850743881;5107.872303192431;5127.2052831811125;3601.3697602919383;4325.091730736549;4361.171377438277;4370.956290437087;4374.234508960919;6612.768553100666;6640.0703901453835;6668.266490686021;6693.497987650946;6707.530472866902;4954.851354459336;5483.7806487740445;5521.696306703129;6065.818025459199;6068.908966365508;6013.248343957005;7432.182459929403;7443.8883416748795;7445.590492522909;7481.551106933602;4726.149293127236;4889.193283108283;4898;4902.142125128088;4915.361003069578;7892.842143157105;7946.920530794676;7948.960255198687;7949.841003179904;7964.402669799731;5003.324551185616;5029;5029.5221953914315;5031.295618613397;5036.597072234213;4502.684812063124;4881.608741508653;5120.206368012964;5123.308353372247;5127.333446651912;6003.279606447254;6015.0676645120075;6016.217891674054;6019.277686677539;6056.757729690851;7182.42540596751;7482;7508.849823003509;7518.661660225303;7530;1743.895366278002;1767.416752471707;1778.5020194345605;1783.8572914166834;1784.5895444047683;1071.8820929697727;1131.372088490879;1132.1678566253797;1155.5839897637013;1160.4545863444168;6945.513814032969;7181.174164971095;7202.387797037206;7211.6033618151505;7253.419726421874;7840.823876418469;7846.60766961661;7854.921450785533;7863.724769633035;7871.409644276588;7115.252898445692;7130.750423735248;7159.212486626467;7174.461915356264;7183.856322873513;4012.859549915739;4109.137081212979;4130.277201489727;4143.7306575072525;4149.2738770715005;6682.398584127452;8290.336773058141;8298.751037468886;8313.12712165214;8323.417360784686;7106.147262328554;8096.878546821802;8100.270975612223;8105.554194519358;8111.553864537389;6101.542384321194;8303.04514980773;8306.958465207636;8313.376496762767;8322.751587261959;7014.473914456335;7190.676419561237;7578.317951384402;7818.452708310591;7820.139784623573;7674.577898215429;7697.191794861683;7698.34564062061;7711.961440192763;7730.11103723079;6677.83137950847;8267.751967440987;8284.130166332448;8284.88154099189;8355.456895301786;4201.957980420279;5035.546800787946;5049.798008079636;5052.59579233653;5077.060743762416;7526.962365188139;7747.457677962714;7747.985523763749;7792.025996750406;7805.843883122192;7449.627518623939;7474.850502989801;7483.363914067365;7485.288897554724;7489.363404110493;6409.878271302597;7291.6354182289615;7480.476366654282;7495.213002634645;7501.662425190989;7129.607871566906;7270.873014682733;7334.706611735684;7336.119665640157;7340.302671246223;6089.238845144357;6133.957227271464;7451.739189128354;7519.511231769918;7536.623168841646;7050.294970503114;7144.9642751785905;7216.422686185198;7244.782656520313;7272.163701174432 14:36:38 INFO - PROCESS | 1941 | |5;yui.html;638.9712462939167;713.2332742302025;771.2172145272552;777.3198451355064;798.9201079892008;15419.614509637264;15594.142322172276;15622.687546249068;15639.765403518957;15642.687146257082;14293.78559321611;14349.713005739904;14382.712345753078;14420.062695924758;14428.13431194129;785.8899754034439;957.1624828275242;959.4147569982305;964.8745663063827;979.314479864098;817.4727300890921;961.5913236874335;969.486172328667;971.3443425687668;973.8003709239624;1019.7450637340664;1029.269218854614;1030.1655658916263;1030.8969103089669;1032.292879377625;863.2964134230582;1004.3120462483222;1009.1321463541349;1020.4999550220375;1031.6234574380371;722.1334398721502;730.7588495796374;735.2280105888796;762.2263402646319;767.8502691975067;15050.096994180385;15286;15374.9231253843;15400.075995440196;15516.534503964785;13120.409581568852;13194.53819116336;13195.20828750278;13335.533256335983;13376.264305463195;10107.949460252725;10155.746106347327;10267.486625668762;10332.690019299433;10383.221258405574;3190.489521676521;3551.3252482028333;3563.875264365735;3590.2101537661933;3686.55761308642;85964.14035859624;86307.56846215729;87497.56251218703;87567;87977.99999999936;9772;9793.216542676711;9905.306628535933;10103.595856165672;10134.493275336205;22222.66666000011;22873.19943801959;22889;22953.7704622955;22967.6554851674;7455.0681164854395;7533.397328213729;7563.168051514439;7640.694372225049;7741.845163096819;4220.894477638022;4224.387463817729;4233.111046680232;4255.574442555781;4279.516825535793;3750.343689854319;3751.8311675974646;3763.6800871926193;3814.237152569497;3815.236952609489;655.9737610495622;676.7615264066746;682.2836022176695;686.7664993901998;688.9586624802528;531.3013387395563;559.7956745787758;573.9397363276879;587.0724255676022;588.805694120939;8275.710350137717;8303.1281715419;8307.418480706414;8341;8493.235608795114;526.8987815465695;560.8569814697307;595.2321505258168;619.2259675405743;627.8493161641264;3935.0555866591812;3965.9603403966166;3991.5210174779213;3999.440078389028;4088.7751173685137;89743;89932;90954.54522727344;91332;92075;73863;73979.63010185023;74557;76277.61861190769;81509.18490815135;71238.64380678063;71352.64323678348;71538.56922861513;71885.6405717968;72974;8586;8629.87055194185;8687.695930642398;8691.130886911258;8774.956125219589;49866.00267994547;50993;51132.74433627808;51544.48455515547;52731;68447.65776171087;71119;71303.9304410424;71325.6433717828;71894;10737.033666969697;10938.398388088732;10947.397893115955;11162;11490.383178780228;10694.62568810088;10999.395033273247;11009.2843965142;11011.944940275247;11419.543218271509;11468.025217856575;11600.013998810196;11773.705657358634;11970.58102966392;12004.339761313211;11245.156613253876;11313.20807543464;11421.37182454973;11437.942810285895;12042.397880106135;9471.384360016576;11218;11510.366929818605;11623.244489108181;12213.328266945404;8533.658653653783;9536;9681.273904457335;9793.363431376938;10051.899481005097;9108.952470465982;9706.369086009387;9991.998847039013;10025.598976040877;10038.64864729731 14:36:38 INFO - PROCESS | 1941 | __end_tp_report 14:36:38 INFO - PROCESS | 1941 | __start_cc_report 14:36:38 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,398 14:36:38 INFO - PROCESS | 1941 | __end_cc_report 14:36:38 INFO - PROCESS | 1941 | __startTimestamp1467754598335__endTimestamp 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | ------- Summary: start ------- 14:36:38 INFO - PROCESS | 1941 | Number of tests: 6 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5881.63 Median:6177.91 stddev:648.70 (10.5%) stddev-sans-first:625.53 14:36:38 INFO - PROCESS | 1941 | Values: 3600.8 3645.1 3700.4 3704.6 3705.8 5849.6 5950.9 5954.6 5960.5 5985.4 5964.9 5990.2 5999.3 6014.6 6031.7 5300.0 5331.9 5343.4 5385.2 5441.2 5340.3 5382.5 5395.4 5474.9 5475.5 5385.9 5391.0 5402.5 5407.6 5414.1 5408.3 5417.7 5442.6 5449.1 5492.8 5156.9 5179.9 5338.8 5345.8 5374.7 3944.9 5242.7 5703.8 5853.2 5876.6 6241.5 6276.2 6279.1 6286.3 6299.2 5401.4 6181.3 6240.7 6306.2 6327.9 6027.4 6028.5 6047.2 6048.9 6057.6 6318.7 6471.5 6492.0 6511.4 6540.3 6143.3 6185.4 6212.6 6246.7 6260.9 6264.6 6302.6 6309.4 6351.6 6363.6 6144.1 6191.8 6261.3 6272.9 6293.7 6141.5 6201.8 6234.6 6267.6 6267.7 4409.8 5000.5 5008.8 5061.2 5067.5 4483.3 4510.7 4551.3 4583.6 4596.5 6037.6 6094.2 6129.6 6138.8 6187.2 6263.5 6305.5 6312.4 6326.6 6361.8 6031.8 6136.7 6177.9 6179.3 6205.6 5003.2 5444.6 5575.1 5581.1 5591.8 6643.3 6664.6 6670.2 6703.8 6724.0 6200.4 6245.9 6282.8 6299.4 6325.3 6595.4 6613.2 6626.4 6628.7 6647.3 6211.3 6213.2 6247.1 6251.9 6271.2 6209.8 6279.7 6287.9 6307.8 6331.2 6101.1 6193.8 6193.9 6212.1 6227.2 6081.8 6191.8 6250.0 6253.7 6268.5 5452.3 6124.5 6149.9 6166.9 6203.9 6209.3 6215.5 6221.0 6221.8 6248.4 6086.5 6209.1 6220.1 6225.6 6241.8 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:58487.27 Median:38912.03 stddev:120949.36 (310.8%) stddev-sans-first:121210.44 14:36:38 INFO - PROCESS | 1941 | Values: 555.5 561.1 565.4 592.5 601.5 31649.4 39264.4 39300.8 39373.8 39406.8 32304.0 40750.6 40899.4 40924.0 41353.8 9537.7 9669.8 9733.1 9736.0 9747.5 8491.7 9636.2 9844.2 9844.5 9845.6 9779.9 9858.9 9873.3 9885.5 9912.3 9828.5 9868.7 9882.8 9889.0 9915.9 9708.0 9786.4 9816.8 9831.9 9872.6 39859.0 40299.0 40347.0 40463.8 40657.4 2836.5 3203.2 3344.6 3375.3 3608.5 26459.3 26484.9 26643.5 26683.1 27832.4 2706.8 2716.2 2722.3 2733.0 2798.8 374599.0 378580.0 378783.0 382274.0 383086.0 35453.6 35809.5 37288.6 37948.7 38221.4 36515.5 36621.5 36659.6 36833.3 36860.4 15740.6 16153.7 16285.9 16300.3 16327.9 13814.7 13886.7 13905.9 13938.0 13983.7 8299.5 8364.5 8402.5 8466.4 8585.2 3567.9 3580.5 3608.4 3611.2 3614.3 939.5 977.9 1005.3 1048.8 1090.0 24966.6 28598.0 29214.0 29318.4 29479.4 1088.7 1125.1 1214.7 1232.7 1252.4 13263.8 14296.4 14682.0 14919.5 15393.8 335754.0 338259.0 338502.0 339748.0 341953.2 63626.0 63650.4 63897.7 64054.7 64074.4 1384.8 1525.4 1551.8 1561.4 1564.7 30007.0 30124.0 30554.5 31071.8 31667.4 59002.7 60992.4 61022.0 61085.4 61619.7 593011.0 595246.0 601118.0 601457.0 602081.0 42790.8 42855.7 44086.6 45231.0 45350.3 48302.0 48357.0 48845.0 49907.8 50091.2 39120.4 39906.6 40299.0 40310.6 41155.0 38861.4 38962.6 39471.0 39654.6 40294.4 39626.8 39934.2 40197.4 41287.6 41378.8 32565.7 33804.6 33811.0 33902.7 34292.0 21635.0 24343.6 25458.0 25812.9 25885.0 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:38057.88 Median:21759.40 stddev:113903.22 (523.5%) stddev-sans-first:114187.54 14:36:38 INFO - PROCESS | 1941 | Values: 337.3 418.4 420.1 422.2 428.9 15660.8 20653.6 21307.0 21365.3 21399.0 20898.1 21309.6 21660.2 21858.6 22385.0 5925.0 5942.7 6181.5 6211.1 6233.9 6004.9 6111.5 6115.3 6125.3 6135.2 5970.0 6080.3 6129.9 6135.1 6140.6 6005.1 6042.1 6068.5 6124.2 6134.5 5264.8 5272.3 5289.9 5295.5 5412.4 26251.2 26730.7 26875.0 27083.7 27304.3 2276.2 2425.3 2449.8 2493.8 2551.4 15182.7 15969.5 16006.7 16205.3 16214.8 1794.5 1824.2 1874.5 1902.8 1925.2 103730.4 117013.0 121594.0 123949.4 125568.4 11461.0 11670.2 11963.2 12014.7 12020.7 14835.0 14889.3 14897.3 14926.7 14946.6 9320.8 9488.4 9503.4 9555.9 9562.8 8031.3 9683.0 9683.0 9687.0 9699.9 7061.7 7111.5 7142.6 7171.0 7194.4 1834.2 1895.9 2043.0 2065.3 2101.9 999.8 1007.9 1069.5 1086.0 1172.5 21179.8 21296.9 22121.9 22209.6 22404.2 923.7 988.2 1056.5 1064.4 1069.2 11206.3 11315.2 11415.3 11448.4 11512.7 676494.0 685164.0 688583.0 691018.0 694076.0 116361.4 128473.0 129436.4 136859.0 140207.0 1070.4 1097.5 1104.1 1109.7 1112.2 18818.5 19251.0 19482.3 19488.7 19791.5 51465.7 51909.0 52101.0 54244.6 54508.7 76230.2 77749.6 79251.6 80169.6 80271.4 8063.6 8410.0 8502.9 8547.6 8769.6 10979.1 12505.4 12624.0 12927.7 13087.5 9338.5 10974.5 11195.6 11253.8 11341.8 10844.7 10940.5 10993.2 11072.3 11133.0 11262.3 11264.2 11412.8 11467.7 11487.7 6731.0 7927.4 8553.7 8748.6 8944.2 10152.7 10917.1 11520.0 11679.6 11862.6 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:6040.13 Median:2755.27 stddev:3372.86 (122.4%) stddev-sans-first:3365.41 14:36:38 INFO - PROCESS | 1941 | Values: 1544.9 1642.1 1893.5 1905.5 1967.9 7712.9 8504.4 9250.4 9468.5 9475.1 9204.7 9247.8 9334.5 9349.8 9399.6 2599.8 2620.5 2620.6 2633.8 2646.7 2114.3 2604.5 2609.1 2611.4 2629.2 2156.4 2588.4 2619.9 2622.6 2631.8 2597.6 2597.6 2614.2 2617.9 2631.5 2604.3 2606.3 2640.9 2643.1 2645.0 9307.2 9436.4 9510.5 9512.0 9524.4 5752.3 5847.7 5912.7 5952.8 6008.0 3696.3 3794.1 3829.0 3830.7 3845.9 1058.3 1077.9 1083.2 1116.0 1116.9 12654.5 12703.3 12720.3 12766.5 12775.3 5118.3 5163.2 5187.7 5249.5 5257.8 10569.1 10724.4 10797.0 10798.2 10830.3 5735.9 5852.7 5877.5 5911.9 5922.1 4322.0 4731.1 5228.1 5261.2 5294.9 4288.7 4296.4 4367.5 4395.9 4398.1 1448.0 1469.2 1469.7 1470.1 1487.9 859.2 980.3 987.6 997.4 1001.8 6174.1 6233.2 6238.6 6281.9 6324.7 487.6 502.4 506.1 510.6 512.3 4098.3 4131.9 4168.6 4183.5 4251.9 10993.9 11216.4 11342.2 11397.8 11421.8 11133.6 11152.1 11193.8 11228.9 11312.9 8621.6 8706.0 8790.3 8814.6 8815.6 5865.4 5975.8 5996.9 6103.4 6200.8 10000.4 10002.2 10166.8 10257.8 10269.9 10064.9 10391.6 10437.1 10471.3 10538.2 7102.3 7922.0 7986.5 8509.0 8678.0 8649.8 8697.7 8711.0 8733.0 8785.9 7487.6 7557.0 7703.9 7746.2 7973.6 7116.0 7439.5 7449.0 7485.9 7647.8 7119.3 7170.6 7180.6 7183.3 7194.9 4993.2 5008.1 5119.7 5186.7 5239.1 5112.0 5177.9 5178.5 5240.2 5256.0 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:6072.91 Median:6716.34 stddev:1956.91 (29.1%) stddev-sans-first:1914.19 14:36:38 INFO - PROCESS | 1941 | Values: 321.7 335.4 338.6 344.7 348.5 5670.7 6616.6 6781.8 7012.6 7013.4 5236.9 6263.7 6725.2 6947.4 6994.3 5129.1 5162.4 5166.5 5170.6 5171.0 5138.8 5171.3 5172.0 5174.8 5175.4 5083.7 5092.3 5092.4 5109.8 5128.5 4964.7 5069.2 5107.2 5107.9 5127.2 3601.4 4325.1 4361.2 4371.0 4374.2 6612.8 6640.1 6668.3 6693.5 6707.5 4954.9 5483.8 5521.7 6065.8 6068.9 6013.2 7432.2 7443.9 7445.6 7481.6 4726.1 4889.2 4898.0 4902.1 4915.4 7892.8 7946.9 7949.0 7949.8 7964.4 5003.3 5029.0 5029.5 5031.3 5036.6 4502.7 4881.6 5120.2 5123.3 5127.3 6003.3 6015.1 6016.2 6019.3 6056.8 7182.4 7482.0 7508.8 7518.7 7530.0 1743.9 1767.4 1778.5 1783.9 1784.6 1071.9 1131.4 1132.2 1155.6 1160.5 6945.5 7181.2 7202.4 7211.6 7253.4 7840.8 7846.6 7854.9 7863.7 7871.4 7115.3 7130.8 7159.2 7174.5 7183.9 4012.9 4109.1 4130.3 4143.7 4149.3 6682.4 8290.3 8298.8 8313.1 8323.4 7106.1 8096.9 8100.3 8105.6 8111.6 6101.5 8303.0 8307.0 8313.4 8322.8 7014.5 7190.7 7578.3 7818.5 7820.1 7674.6 7697.2 7698.3 7712.0 7730.1 6677.8 8267.8 8284.1 8284.9 8355.5 4202.0 5035.5 5049.8 5052.6 5077.1 7527.0 7747.5 7748.0 7792.0 7805.8 7449.6 7474.9 7483.4 7485.3 7489.4 6409.9 7291.6 7480.5 7495.2 7501.7 7129.6 7270.9 7334.7 7336.1 7340.3 6089.2 6134.0 7451.7 7519.5 7536.6 7050.3 7145.0 7216.4 7244.8 7272.2 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:18679.26 Median:51338.61 stddev:26179.14 (51.0%) stddev-sans-first:26217.53 14:36:38 INFO - PROCESS | 1941 | Values: 639.0 713.2 771.2 777.3 798.9 15419.6 15594.1 15622.7 15639.8 15642.7 14293.8 14349.7 14382.7 14420.1 14428.1 785.9 957.2 959.4 964.9 979.3 817.5 961.6 969.5 971.3 973.8 1019.7 1029.3 1030.2 1030.9 1032.3 863.3 1004.3 1009.1 1020.5 1031.6 722.1 730.8 735.2 762.2 767.9 15050.1 15286.0 15374.9 15400.1 15516.5 13120.4 13194.5 13195.2 13335.5 13376.3 10107.9 10155.7 10267.5 10332.7 10383.2 3190.5 3551.3 3563.9 3590.2 3686.6 85964.1 86307.6 87497.6 87567.0 87978.0 9772.0 9793.2 9905.3 10103.6 10134.5 22222.7 22873.2 22889.0 22953.8 22967.7 7455.1 7533.4 7563.2 7640.7 7741.8 4220.9 4224.4 4233.1 4255.6 4279.5 3750.3 3751.8 3763.7 3814.2 3815.2 656.0 676.8 682.3 686.8 689.0 531.3 559.8 573.9 587.1 588.8 8275.7 8303.1 8307.4 8341.0 8493.2 526.9 560.9 595.2 619.2 627.8 3935.1 3966.0 3991.5 3999.4 4088.8 89743.0 89932.0 90954.5 91332.0 92075.0 73863.0 73979.6 74557.0 76277.6 81509.2 71238.6 71352.6 71538.6 71885.6 72974.0 8586.0 8629.9 8687.7 8691.1 8775.0 49866.0 50993.0 51132.7 51544.5 52731.0 68447.7 71119.0 71303.9 71325.6 71894.0 10737.0 10938.4 10947.4 11162.0 11490.4 10694.6 10999.4 11009.3 11011.9 11419.5 11468.0 11600.0 11773.7 11970.6 12004.3 11245.2 11313.2 11421.4 11437.9 12042.4 9471.4 11218.0 11510.4 11623.2 12213.3 8533.7 9536.0 9681.3 9793.4 10051.9 9109.0 9706.4 9992.0 10025.6 10038.6 14:36:38 INFO - PROCESS | 1941 | -------- Summary: end -------- 14:36:38 INFO - PROCESS | 1941 | 14:36:38 INFO - TEST-INFO | 1941: exit 0 14:36:38 INFO - TEST-OK | dromaeo_css | took 1109144ms 14:36:38 INFO - TEST-START | kraken 14:36:38 INFO - operating with platform_type : mac_ 14:36:38 INFO - Initialising browser for kraken test... 14:36:38 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpcnRR_8/profile http://localhost:49467/getInfo.html) 14:36:41 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 14:36:41 INFO - PROCESS | 1952 | colorDepth:24 14:36:41 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 14:36:41 INFO - PROCESS | 1952 | __metrics 14:36:41 ERROR - PROCESS | 1952 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 14:36:42 INFO - TEST-INFO | 1952: exit 0 14:36:42 INFO - Browser initialized. 14:36:42 INFO - Running cycle 1/1 for kraken test... 14:36:42 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpcnRR_8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:36:54 INFO - PROCESS | 1956 | RSS: Main: 171433984 14:36:54 INFO - PROCESS | 1956 | 14:36:56 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49467/tests/kraken/driver.html?testName=audio-beat-detection) 14:36:57 INFO - PROCESS | 1956 | RSS: Main: 183771136 14:36:57 INFO - PROCESS | 1956 | 14:37:00 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49467/tests/kraken/driver.html?testName=audio-dft) 14:37:01 INFO - PROCESS | 1956 | RSS: Main: 192839680 14:37:01 INFO - PROCESS | 1956 | 14:37:04 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49467/tests/kraken/driver.html?testName=audio-fft) 14:37:05 INFO - PROCESS | 1956 | RSS: Main: 196612096 14:37:05 INFO - PROCESS | 1956 | 14:37:07 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49467/tests/kraken/driver.html?testName=audio-oscillator) 14:37:07 INFO - PROCESS | 1956 | RSS: Main: 196841472 14:37:07 INFO - PROCESS | 1956 | 14:37:09 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49467/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:37:10 INFO - PROCESS | 1956 | RSS: Main: 197922816 14:37:10 INFO - PROCESS | 1956 | 14:37:13 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49467/tests/kraken/driver.html?testName=imaging-darkroom) 14:37:13 INFO - PROCESS | 1956 | RSS: Main: 198586368 14:37:13 INFO - PROCESS | 1956 | 14:37:16 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49467/tests/kraken/driver.html?testName=imaging-desaturate) 14:37:17 INFO - PROCESS | 1956 | RSS: Main: 198688768 14:37:17 INFO - PROCESS | 1956 | 14:37:20 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49467/tests/kraken/driver.html?testName=json-parse-financial) 14:37:20 INFO - PROCESS | 1956 | RSS: Main: 199331840 14:37:20 INFO - PROCESS | 1956 | 14:37:22 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49467/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:37:23 INFO - PROCESS | 1956 | RSS: Main: 197844992 14:37:23 INFO - PROCESS | 1956 | 14:37:24 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:37:25 INFO - PROCESS | 1956 | RSS: Main: 198049792 14:37:25 INFO - PROCESS | 1956 | 14:37:27 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:37:28 INFO - PROCESS | 1956 | RSS: Main: 198844416 14:37:28 INFO - PROCESS | 1956 | 14:37:31 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:37:31 INFO - PROCESS | 1956 | RSS: Main: 198361088 14:37:31 INFO - PROCESS | 1956 | 14:37:34 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:37:35 INFO - PROCESS | 1956 | RSS: Main: 198189056 14:37:35 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49467/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49467/tests/kraken/driver.html?testName=ai-astar) 14:37:37 INFO - PROCESS | 1956 | RSS: Main: 199954432 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | __start_tp_report 14:37:37 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 14:37:37 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 14:37:37 INFO - PROCESS | 1956 | |i|pagename|runs| 14:37:37 INFO - PROCESS | 1956 | |0;ai-astar;118;113;116;117;115;132;115;120;168;128 14:37:37 INFO - PROCESS | 1956 | |1;audio-beat-detection;167;126;142;189;147;168;121;175;143;143 14:37:37 INFO - PROCESS | 1956 | |2;audio-dft;197;209;167;211;189;223;274;267;196;200 14:37:37 INFO - PROCESS | 1956 | |3;audio-fft;63;65;82;168;54;57;55;91;166;49 14:37:37 INFO - PROCESS | 1956 | |4;audio-oscillator;79;81;123;80;79;99;158;116;78;125 14:37:37 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;79;127;91;88;90;75;135;87;87;84 14:37:37 INFO - PROCESS | 1956 | |6;imaging-darkroom;92;120;93;93;95;93;94;94;100;94 14:37:37 INFO - PROCESS | 1956 | |7;imaging-desaturate;92;91;105;94;92;90;80;140;141;92 14:37:37 INFO - PROCESS | 1956 | |8;json-parse-financial;60;64;64;66;65;63;65;79;60;58 14:37:37 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;63;65;70;62;70;70;70;66;66;71 14:37:37 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;80;79;91;102;80;82;93;103;108;83 14:37:37 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;131;130;125;118;137;150;134;149;212;173 14:37:37 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;168;169;158;159;167;172;165;167;175;165 14:37:37 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;66;65;82;93;97;81;63;68;67;69 14:37:37 INFO - PROCESS | 1956 | __end_tp_report 14:37:37 INFO - PROCESS | 1956 | __start_cc_report 14:37:37 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1055 14:37:37 INFO - PROCESS | 1956 | __end_cc_report 14:37:37 INFO - PROCESS | 1956 | __startTimestamp1467754657325__endTimestamp 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | ------- Summary: start ------- 14:37:37 INFO - PROCESS | 1956 | Number of tests: 14 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:124.20 Median:119.00 stddev:16.54 (13.9%) stddev-sans-first:17.40 14:37:37 INFO - PROCESS | 1956 | Values: 118.0 113.0 116.0 117.0 115.0 132.0 115.0 120.0 168.0 128.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:152.10 Median:157.00 stddev:21.87 (13.9%) stddev-sans-first:22.52 14:37:37 INFO - PROCESS | 1956 | Values: 167.0 126.0 142.0 189.0 147.0 168.0 121.0 175.0 143.0 143.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:213.30 Median:210.00 stddev:33.60 (16.0%) stddev-sans-first:35.12 14:37:37 INFO - PROCESS | 1956 | Values: 197.0 209.0 167.0 211.0 189.0 223.0 274.0 267.0 196.0 200.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:85.00 Median:60.00 stddev:45.12 (75.2%) stddev-sans-first:47.15 14:37:37 INFO - PROCESS | 1956 | Values: 63.0 65.0 82.0 168.0 54.0 57.0 55.0 91.0 166.0 49.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:79.00 stddev:27.63 (35.0%) stddev-sans-first:28.04 14:37:37 INFO - PROCESS | 1956 | Values: 79.0 81.0 123.0 80.0 79.0 99.0 158.0 116.0 78.0 125.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:87.00 stddev:20.04 (23.0%) stddev-sans-first:20.48 14:37:37 INFO - PROCESS | 1956 | Values: 79.0 127.0 91.0 88.0 90.0 75.0 135.0 87.0 87.0 84.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:93.50 stddev:8.44 (9.0%) stddev-sans-first:8.77 14:37:37 INFO - PROCESS | 1956 | Values: 92.0 120.0 93.0 93.0 95.0 93.0 94.0 94.0 100.0 94.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:91.50 stddev:21.31 (23.3%) stddev-sans-first:22.31 14:37:37 INFO - PROCESS | 1956 | Values: 92.0 91.0 105.0 94.0 92.0 90.0 80.0 140.0 141.0 92.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.50 stddev:5.76 (8.9%) stddev-sans-first:5.88 14:37:37 INFO - PROCESS | 1956 | Values: 60.0 64.0 64.0 66.0 65.0 63.0 65.0 79.0 60.0 58.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:70.00 stddev:3.30 (4.7%) stddev-sans-first:3.11 14:37:37 INFO - PROCESS | 1956 | Values: 63.0 65.0 70.0 62.0 70.0 70.0 70.0 66.0 66.0 71.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:81.00 stddev:10.96 (13.5%) stddev-sans-first:11.00 14:37:37 INFO - PROCESS | 1956 | Values: 80.0 79.0 91.0 102.0 80.0 82.0 93.0 103.0 108.0 83.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:143.00 stddev:27.97 (19.6%) stddev-sans-first:29.14 14:37:37 INFO - PROCESS | 1956 | Values: 131.0 130.0 125.0 118.0 137.0 150.0 134.0 149.0 212.0 173.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.50 stddev:5.21 (3.1%) stddev-sans-first:5.50 14:37:37 INFO - PROCESS | 1956 | Values: 168.0 169.0 158.0 159.0 167.0 172.0 165.0 167.0 175.0 165.0 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:75.00 stddev:12.32 (16.4%) stddev-sans-first:12.62 14:37:37 INFO - PROCESS | 1956 | Values: 66.0 65.0 82.0 93.0 97.0 81.0 63.0 68.0 67.0 69.0 14:37:37 INFO - PROCESS | 1956 | -------- Summary: end -------- 14:37:37 INFO - PROCESS | 1956 | 14:37:37 INFO - TEST-INFO | 1956: exit 0 14:37:38 INFO - TEST-OK | kraken | took 59173ms 14:37:38 INFO - SUITE-END | took 1168s 14:37:38 INFO - Completed test suite (00:19:28) 14:37:38 INFO - javascript benchmark 14:37:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3600.7839529628222, 3645.125169959209, 3700.3524383232943, 3704.629537046297, 3705.8147092645368, 5849.561282903784, 5950.880982380355, 5954.553408494358, 5960.493358064559, 5985.371535988718, 5964.850878728034, 5990.191324171246, 5999.250093738283, 6014.578979471439, 6031.668258245806, 5299.973500132494, 5331.866703332409, 5343.358796944372, 5385.165299378583, 5441.211024401459, 5340.332458442695, 5382.461753824606, 5395.433479484656, 5474.945250547503, 5475.479829416199, 5385.946140538584, 5391.029614669358, 5402.513773760361, 5407.594430417715, 5414.052540805363, 5408.296921400193, 5417.674939503642, 5442.591805614595, 5449.128139497681, 5492.80775172867, 5156.8968620627375, 5179.870503237412, 5338.759755810959, 5345.812896548641, 5374.677519348813, 3944.9013774655577, 5242.689327668083, 5703.77184912603, 5853.209816674737, 5876.6180198286975, 6241.469475094623, 6276.184096067527, 6279.0895320178315, 6286.339934306878, 6299.244090709144, 5401.378841433247, 6181.320054793969, 6240.656763877985, 6306.180196574416, 6327.905081423783, 6027.427394397525, 6028.547858910511, 6047.183630209909, 6048.909266361052, 6057.606255593417, 6318.71565779541, 6471.482281417475, 6491.967540162364, 6511.38141876521, 6540.345965403421, 6143.327652471189, 6185.443310102022, 6212.627242365473, 6246.6876656167005, 6260.906086408708, 6264.624122552661, 6302.590331628429, 6309.400606942333, 6351.5871468355335, 6363.5545511813725, 6144.064619644887, 6191.752329906843, 6261.279952805395, 6272.874542509216, 6293.716782744696, 6141.508679305645, 6201.7519299228425, 6234.625922444668, 6267.62394256346, 6267.6866156691985, 4409.801558929856, 5000.549950504472, 5008.84973450797, 5061.215511595708, 5067.5185857343495, 4483.327500874895, 4510.684252102321, 4551.3173024046655, 4583.587477127008, 4596.49438561758, 6037.607555508878, 6094.238220222473, 6129.63387754696, 6138.786925362681, 6187.195664563578, 6263.498920086382, 6305.464035557029, 6312.400321969406, 6326.620402775849, 6361.840953976188, 6031.788887388921, 6136.662483563447, 6177.907331390033, 6179.3202747697715, 6205.565610407228, 5003.249512573143, 5444.564434845203, 5575.0522411190195, 5581.079121944834, 5591.832245032574, 6643.269240383651, 6664.566803157779, 6670.199576050905, 6703.765368212089, 6723.957786543094, 6200.441960223421, 6245.875082498415, 6282.780102696383, 6299.401556852092, 6325.335839736761, 6595.4393876521035, 6613.206415230011, 6626.403623673892, 6628.734850605922, 6647.268800432045, 6211.2857021443115, 6213.161223234805, 6247.094171345038, 6251.874962500816, 6271.216097987752, 6209.813705588839, 6279.748810047547, 6287.937120628918, 6307.779227727007, 6331.176946997045, 6101.145839582373, 6193.84515387119, 6193.876122477615, 6212.1303017578475, 6227.159333490102, 6081.756729730761, 6191.752329906753, 6250.0, 6253.749850005949, 6268.529860260407, 5452.291202143854, 6124.5406594506485, 6149.923763341308, 6166.907496387468, 6203.937960620336, 6209.285932117684, 6215.533834962305, 6221.0, 6221.813345599639, 6248.4376406123665, 6086.543509236736, 6209.054122155177, 6220.098085777628, 6225.62646241227, 6241.750329986749], "value": 5843.150907292657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.4972749661557, 561.0910325273059, 565.4493268574104, 592.5022980696215, 601.5097695378266, 31649.36701265976, 39264.41103383451, 39300.80349598255, 39373.80313098434, 39406.8029659852, 32304.0, 40750.59249407505, 40899.36402543895, 40924.0, 41353.79323103388, 9537.713868583936, 9669.806603867919, 9733.075357840993, 9735.97772233916, 9747.512624368788, 8491.74524764258, 9636.22910167185, 9844.212463002978, 9844.458554779485, 9845.556949937234, 9779.853302200472, 9858.852117218248, 9873.259505537078, 9885.505724713736, 9912.306138570268, 9828.459434731087, 9868.703938881808, 9882.802343953117, 9889.011098890125, 9915.851262231072, 9708.0, 9786.41281523111, 9816.754581135458, 9831.852522212173, 9872.60509579616, 39859.0, 40298.99252518681, 40347.0, 40463.7976810117, 40657.39013914794, 2836.468307114156, 3203.183188286995, 3344.5986481622363, 3375.2911888503445, 3608.5128507651393, 26459.33851653705, 26484.867575662192, 26643.46713065747, 26683.066092686662, 27832.443351132864, 2706.824056436345, 2716.171567671859, 2722.278596172016, 2733.0024541042576, 2798.837284157249, 374599.0, 378580.0, 378783.0, 382274.0, 383086.0, 35453.645463545035, 35809.46285805715, 37288.62711372906, 37948.67179648699, 38221.42667859984, 36515.452268215995, 36621.450678239846, 36659.63340366616, 36833.26333473316, 36860.447093293624, 15740.60648483797, 16153.676926461405, 16285.918570407071, 16300.347986080424, 16327.938683985503, 13814.723705525834, 13886.722265554632, 13905.930470347786, 13938.024338296425, 13983.650408739863, 8299.460535065202, 8364.539950302791, 8402.495850249004, 8466.449680770731, 8585.227329540372, 3567.910802229965, 3580.534530511069, 3608.3685355062757, 3611.1513794258326, 3614.2590768892314, 939.5349302095507, 977.8630991661173, 1005.2561104782554, 1048.758785479344, 1090.0353187429182, 24966.625500617505, 28598.0, 29214.0, 29318.41363172767, 29479.410411791643, 1088.7169335972546, 1125.0830573083074, 1214.690253985228, 1232.7164752107064, 1252.3988485526997, 13263.801042984363, 14296.428142874376, 14681.972261941775, 14919.477818276308, 15393.846061539465, 335754.0, 338259.0, 338502.0, 339748.0, 341953.16093677987, 63626.0, 63650.36349636537, 63897.680511597144, 64054.67972660107, 64074.35925640777, 1384.78535826947, 1525.4355888321393, 1551.7594772810232, 1561.3598424646068, 1564.7496400575853, 30007.0, 30124.0, 30554.541681874343, 31071.84464077665, 31667.36665266636, 59002.70498647479, 60992.39007609867, 61022.0, 61085.389146109745, 61619.6919015402, 593011.0, 595246.0, 601118.0, 601457.0, 602081.0, 42790.786046069574, 42855.714328568945, 44086.55913440824, 45231.0, 45350.31974520319, 48302.0, 48357.0, 48845.0, 49907.75046124746, 50091.24863126983, 39120.413193802684, 39906.60093399145, 40299.0, 40310.596894030685, 41155.0, 38861.417078743274, 38962.636307729095, 39471.013224668466, 39654.603453965094, 40294.39558406685, 39626.80186599164, 39934.201315974096, 40197.397039043855, 41287.587124129575, 41378.793106034274, 32565.674343256265, 33804.640047793895, 33811.0, 33902.660973390935, 34292.0, 21635.0, 24343.63484547698, 25457.981680732562, 25812.87093564595, 25884.964601415733], "value": 18627.641596835976, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.25634974880376, 418.4309339298554, 420.0967918974203, 422.22943128789956, 428.94479580232627, 15660.765088523653, 20653.554251202422, 21307.0, 21365.252216172437, 21399.0, 20898.05958731857, 21309.57380852382, 21660.2418915338, 21858.562828743416, 22385.0, 5925.0, 5942.673152976585, 6181.505479561647, 6211.0993905883615, 6233.906491402633, 6004.909926351108, 6111.480524155452, 6115.29674087479, 6125.326214116443, 6135.2024236849375, 5970.0, 6080.331163572004, 6129.938700613004, 6135.110408990694, 6140.570160088796, 6005.099235114724, 6042.063480160582, 6068.453839154476, 6124.234470691142, 6134.539909506805, 5264.842054738364, 5272.340957380327, 5289.86775330616, 5295.470452954673, 5412.3505179378335, 26251.21246362612, 26730.73269267302, 26875.0, 27083.729162708318, 27304.317392065157, 2276.1578216060166, 2425.2966639674473, 2449.8407603505716, 2493.8254322197454, 2551.387666959935, 15182.748830327415, 15969.520914372588, 16006.679866402606, 16205.270762815702, 16214.75677864833, 1794.4616615015443, 1824.2338217948493, 1874.5227602796751, 1902.771667399907, 1925.210663627906, 103730.4067398321, 117013.0, 121594.0, 123949.38025309907, 125568.37215813862, 11461.0, 11670.183087183816, 11963.162578619413, 12014.717203731263, 12020.699482512833, 14835.0, 14889.329980150918, 14897.329620167118, 14926.70146597062, 14946.551603451913, 9320.813583728288, 9488.430694158373, 9503.429794212369, 9555.948845626976, 9562.808743825224, 8031.317338026333, 9682.951585242028, 9683.0, 9686.951565242129, 9699.903000970042, 7061.682224299919, 7111.542133862546, 7142.64286785669, 7171.0, 7194.352508274281, 1834.2112891456802, 1895.8862468251948, 2042.9795702042788, 2065.2651772511163, 2101.8965043352364, 999.7553046457062, 1007.9193664506822, 1069.502681253221, 1086.006304231635, 1172.466527729898, 21179.78820211809, 21296.893515532323, 22121.889390552944, 22209.55580888373, 22404.215852445086, 923.7044145873256, 988.2489308125876, 1056.4559251985233, 1064.3720205079042, 1069.2034434346463, 11206.271592346471, 11315.225517069524, 11415.315081095161, 11448.3703396314, 11512.712182195344, 676494.0, 685164.0, 688583.0, 691018.0, 694076.0, 116361.41819290849, 128473.0, 129436.3528182353, 136859.0, 140207.0, 1070.4273213830563, 1097.4786976186247, 1104.1056744037355, 1109.678193323927, 1112.2103306652207, 18818.52953676169, 19251.0, 19482.318118865773, 19488.707669385254, 19791.505212369804, 51465.7426712864, 51909.0, 52101.0, 54244.64388390322, 54508.72745636247, 76230.23769762453, 77749.61125194338, 79251.60374198091, 80169.59915200387, 80271.39457210706, 8063.637136328762, 8410.0, 8502.91497085046, 8547.615357308812, 8769.605367758593, 10979.121670266519, 12505.43725532335, 12624.0, 12927.741445171232, 13087.476500940236, 9338.533073346442, 10974.506147223554, 11195.60815371458, 11253.831192531954, 11341.773164536498, 10844.67465976022, 10940.452977350942, 10993.175511836484, 11072.256453945763, 11133.0, 11262.297048708415, 11264.211505194558, 11412.771744565229, 11467.713307167387, 11487.712807179552, 6731.0, 7927.365810735012, 8553.700620478254, 8748.562571871254, 8944.150305720947, 10152.74618134523, 10917.097700437052, 11519.963203311741, 11679.649610511698, 11862.644120676394], "value": 10172.256231169147, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.860962513374, 1642.0640235066014, 1893.5266183454137, 1905.5140939060539, 1967.9176452950874, 7712.922870771291, 8504.36217283703, 9250.39872408293, 9468.526573671315, 9475.147236748691, 9204.677836275732, 9247.815043699122, 9334.486603236819, 9349.766255843608, 9399.577019034143, 2599.8440093594445, 2620.5021046001293, 2620.5545057340296, 2633.8156329056974, 2646.6559347284824, 2114.3339847947846, 2604.4791041791623, 2609.0998605481077, 2611.425486393, 2629.1849526646706, 2156.363872657562, 2588.417606038645, 2619.947601047978, 2622.5672763993916, 2631.802614803888, 2597.5973724072705, 2597.6363309136736, 2614.2026681862026, 2617.947641047178, 2631.4868600622885, 2604.2838219489604, 2606.2832721001687, 2640.854752988585, 2643.061713091863, 2645.021342103415, 9307.162355388047, 9436.433813971184, 9510.47692376919, 9511.953685094633, 9524.428534287965, 5752.338481074688, 5847.707614619295, 5912.704364781786, 5952.791652292192, 6008.038713805814, 3696.2792255510185, 3794.051487128218, 3829.0044588406936, 3830.6743926766258, 3845.903852403684, 1058.3015209961388, 1077.9245452818307, 1083.2038451737967, 1115.983260251097, 1116.865976082867, 12654.493820247271, 12703.301318427482, 12720.300383478905, 12766.489340426371, 12775.29735864527, 5118.25785261135, 5163.199704045879, 5187.714675692798, 5249.475052494797, 5257.76340064698, 10569.101626361846, 10724.356538607708, 10796.974287442681, 10798.190135739851, 10830.296030758132, 5735.942640573541, 5852.736626851802, 5877.52979761618, 5911.940880591225, 5922.1412895130825, 4321.978390108092, 4731.124741922756, 5228.111221092423, 5261.237120617489, 5294.894102117936, 4288.7212331199, 4296.419983302277, 4367.45406824147, 4395.868123956287, 4398.098389830077, 1448.044290768088, 1469.1992863889207, 1469.7354476194387, 1470.147314557554, 1487.9032862863878, 859.1709000814244, 980.2990861534038, 987.5605355616682, 997.4165113408592, 1001.8146642871092, 6174.135621013062, 6233.2208473940755, 6238.594491358047, 6281.905771413341, 6324.747010119544, 487.6367106505676, 502.42472369137863, 506.12693104394623, 510.55071537047905, 512.2955935588566, 4098.262312783728, 4131.880430275123, 4168.624823765876, 4183.5398106208295, 4251.893702657397, 10993.945030274957, 11216.439178041066, 11342.20604557673, 11397.829032564518, 11421.828672569918, 11133.554657813758, 11152.052075573667, 11193.776124477465, 11228.943855280835, 11312.886871131348, 8621.568921553897, 8705.955285365797, 8790.296776257885, 8814.647414103492, 8815.559222038872, 5865.4134586541, 5975.760969561169, 5996.940030599638, 6103.42017508336, 6200.81397558074, 10000.399976001463, 10002.199824013916, 10166.796664066529, 10257.794844103226, 10269.948650256702, 10064.949675251577, 10391.58433662645, 10437.11284540792, 10471.3193642413, 10538.156947444339, 7102.325279098477, 7921.960390198012, 7986.520808751494, 8509.0, 8677.956610216908, 8649.783755406164, 8697.739067827975, 8710.998235203033, 8732.956335218283, 8785.868211976698, 7487.588182649789, 7556.962215188889, 7703.922960770321, 7746.225377462209, 7973.641186146753, 7116.0, 7439.51643143194, 7449.0, 7485.88771168422, 7647.770566883002, 7119.2524784898915, 7170.605616691132, 7180.60506672138, 7183.317584829433, 7194.892076618959, 4993.15116430215, 5008.1235783736975, 5119.692818430906, 5186.740662966912, 5239.109351410193, 5111.974440127924, 5177.922331165111, 5178.482151784791, 5240.161574148118, 5255.973720131375], "value": 4806.107218268356, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.7136748294019, 335.37848448381317, 338.60214248258296, 344.72795385029247, 348.5033826796814, 5670.71646417679, 6616.569922955005, 6781.762638307648, 7012.649367531622, 7013.368796808286, 5236.8690782730355, 6263.718132684029, 6725.159355080615, 6947.4442044636435, 6994.3355381238825, 5129.102407078765, 5162.432132465426, 5166.509181627758, 5170.586353091744, 5170.9741451292875, 5138.820141295056, 5171.301874246985, 5171.974140129294, 5174.767135478913, 5175.378954525462, 5083.66956147851, 5092.312537807404, 5092.388913330406, 5109.846704598868, 5128.512791284822, 4964.726940018298, 5069.188929771219, 5107.182850743881, 5107.872303192431, 5127.2052831811125, 3601.3697602919383, 4325.091730736549, 4361.171377438277, 4370.956290437087, 4374.234508960919, 6612.768553100666, 6640.0703901453835, 6668.266490686021, 6693.497987650946, 6707.530472866902, 4954.851354459336, 5483.7806487740445, 5521.696306703129, 6065.818025459199, 6068.908966365508, 6013.248343957005, 7432.182459929403, 7443.8883416748795, 7445.590492522909, 7481.551106933602, 4726.149293127236, 4889.193283108283, 4898.0, 4902.142125128088, 4915.361003069578, 7892.842143157105, 7946.920530794676, 7948.960255198687, 7949.841003179904, 7964.402669799731, 5003.324551185616, 5029.0, 5029.5221953914315, 5031.295618613397, 5036.597072234213, 4502.684812063124, 4881.608741508653, 5120.206368012964, 5123.308353372247, 5127.333446651912, 6003.279606447254, 6015.0676645120075, 6016.217891674054, 6019.277686677539, 6056.757729690851, 7182.42540596751, 7482.0, 7508.849823003509, 7518.661660225303, 7530.0, 1743.895366278002, 1767.416752471707, 1778.5020194345605, 1783.8572914166834, 1784.5895444047683, 1071.8820929697727, 1131.372088490879, 1132.1678566253797, 1155.5839897637013, 1160.4545863444168, 6945.513814032969, 7181.174164971095, 7202.387797037206, 7211.6033618151505, 7253.419726421874, 7840.823876418469, 7846.60766961661, 7854.921450785533, 7863.724769633035, 7871.409644276588, 7115.252898445692, 7130.750423735248, 7159.212486626467, 7174.461915356264, 7183.856322873513, 4012.859549915739, 4109.137081212979, 4130.277201489727, 4143.7306575072525, 4149.2738770715005, 6682.398584127452, 8290.336773058141, 8298.751037468886, 8313.12712165214, 8323.417360784686, 7106.147262328554, 8096.878546821802, 8100.270975612223, 8105.554194519358, 8111.553864537389, 6101.542384321194, 8303.04514980773, 8306.958465207636, 8313.376496762767, 8322.751587261959, 7014.473914456335, 7190.676419561237, 7578.317951384402, 7818.452708310591, 7820.139784623573, 7674.577898215429, 7697.191794861683, 7698.34564062061, 7711.961440192763, 7730.11103723079, 6677.83137950847, 8267.751967440987, 8284.130166332448, 8284.88154099189, 8355.456895301786, 4201.957980420279, 5035.546800787946, 5049.798008079636, 5052.59579233653, 5077.060743762416, 7526.962365188139, 7747.457677962714, 7747.985523763749, 7792.025996750406, 7805.843883122192, 7449.627518623939, 7474.850502989801, 7483.363914067365, 7485.288897554724, 7489.363404110493, 6409.878271302597, 7291.6354182289615, 7480.476366654282, 7495.213002634645, 7501.662425190989, 7129.607871566906, 7270.873014682733, 7334.706611735684, 7336.119665640157, 7340.302671246223, 6089.238845144357, 6133.957227271464, 7451.739189128354, 7519.511231769918, 7536.623168841646, 7050.294970503114, 7144.9642751785905, 7216.422686185198, 7244.782656520313, 7272.163701174432], "value": 5423.32409123443, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.9712462939167, 713.2332742302025, 771.2172145272552, 777.3198451355064, 798.9201079892008, 15419.614509637264, 15594.142322172276, 15622.687546249068, 15639.765403518957, 15642.687146257082, 14293.78559321611, 14349.713005739904, 14382.712345753078, 14420.062695924758, 14428.13431194129, 785.8899754034439, 957.1624828275242, 959.4147569982305, 964.8745663063827, 979.314479864098, 817.4727300890921, 961.5913236874335, 969.486172328667, 971.3443425687668, 973.8003709239624, 1019.7450637340664, 1029.269218854614, 1030.1655658916263, 1030.8969103089669, 1032.292879377625, 863.2964134230582, 1004.3120462483222, 1009.1321463541349, 1020.4999550220375, 1031.6234574380371, 722.1334398721502, 730.7588495796374, 735.2280105888796, 762.2263402646319, 767.8502691975067, 15050.096994180385, 15286.0, 15374.9231253843, 15400.075995440196, 15516.534503964785, 13120.409581568852, 13194.53819116336, 13195.20828750278, 13335.533256335983, 13376.264305463195, 10107.949460252725, 10155.746106347327, 10267.486625668762, 10332.690019299433, 10383.221258405574, 3190.489521676521, 3551.3252482028333, 3563.875264365735, 3590.2101537661933, 3686.55761308642, 85964.14035859624, 86307.56846215729, 87497.56251218703, 87567.0, 87977.99999999936, 9772.0, 9793.216542676711, 9905.306628535933, 10103.595856165672, 10134.493275336205, 22222.66666000011, 22873.19943801959, 22889.0, 22953.7704622955, 22967.6554851674, 7455.0681164854395, 7533.397328213729, 7563.168051514439, 7640.694372225049, 7741.845163096819, 4220.894477638022, 4224.387463817729, 4233.111046680232, 4255.574442555781, 4279.516825535793, 3750.343689854319, 3751.8311675974646, 3763.6800871926193, 3814.237152569497, 3815.236952609489, 655.9737610495622, 676.7615264066746, 682.2836022176695, 686.7664993901998, 688.9586624802528, 531.3013387395563, 559.7956745787758, 573.9397363276879, 587.0724255676022, 588.805694120939, 8275.710350137717, 8303.1281715419, 8307.418480706414, 8341.0, 8493.235608795114, 526.8987815465695, 560.8569814697307, 595.2321505258168, 619.2259675405743, 627.8493161641264, 3935.0555866591812, 3965.9603403966166, 3991.5210174779213, 3999.440078389028, 4088.7751173685137, 89743.0, 89932.0, 90954.54522727344, 91332.0, 92075.0, 73863.0, 73979.63010185023, 74557.0, 76277.61861190769, 81509.18490815135, 71238.64380678063, 71352.64323678348, 71538.56922861513, 71885.6405717968, 72974.0, 8586.0, 8629.87055194185, 8687.695930642398, 8691.130886911258, 8774.956125219589, 49866.00267994547, 50993.0, 51132.74433627808, 51544.48455515547, 52731.0, 68447.65776171087, 71119.0, 71303.9304410424, 71325.6433717828, 71894.0, 10737.033666969697, 10938.398388088732, 10947.397893115955, 11162.0, 11490.383178780228, 10694.62568810088, 10999.395033273247, 11009.2843965142, 11011.944940275247, 11419.543218271509, 11468.025217856575, 11600.013998810196, 11773.705657358634, 11970.58102966392, 12004.339761313211, 11245.156613253876, 11313.20807543464, 11421.37182454973, 11437.942810285895, 12042.397880106135, 9471.384360016576, 11218.0, 11510.366929818605, 11623.244489108181, 12213.328266945404, 8533.658653653783, 9536.0, 9681.273904457335, 9793.363431376938, 10051.899481005097, 9108.952470465982, 9706.369086009387, 9991.998847039013, 10025.598976040877, 10038.64864729731], "value": 7075.381445983309, "unit": "score"}], "name": "dromaeo_css", "value": 7673.802546066721, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 113.0, 116.0, 117.0, 115.0, 132.0, 115.0, 120.0, 168.0, 128.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 126.0, 142.0, 189.0, 147.0, 168.0, 121.0, 175.0, 143.0, 143.0], "value": 152.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 209.0, 167.0, 211.0, 189.0, 223.0, 274.0, 267.0, 196.0, 200.0], "value": 213.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 82.0, 168.0, 54.0, 57.0, 55.0, 91.0, 166.0, 49.0], "value": 85.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 123.0, 80.0, 79.0, 99.0, 158.0, 116.0, 78.0, 125.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 127.0, 91.0, 88.0, 90.0, 75.0, 135.0, 87.0, 87.0, 84.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 120.0, 93.0, 93.0, 95.0, 93.0, 94.0, 94.0, 100.0, 94.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 105.0, 94.0, 92.0, 90.0, 80.0, 140.0, 141.0, 92.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 66.0, 65.0, 63.0, 65.0, 79.0, 60.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 70.0, 62.0, 70.0, 70.0, 70.0, 66.0, 66.0, 71.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 91.0, 102.0, 80.0, 82.0, 93.0, 103.0, 108.0, 83.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 130.0, 125.0, 118.0, 137.0, 150.0, 134.0, 149.0, 212.0, 173.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 158.0, 159.0, 167.0, 172.0, 165.0, 167.0, 175.0, 165.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 82.0, 93.0, 97.0, 81.0, 63.0, 68.0, 67.0, 69.0], "value": 75.1, "unit": "score"}], "name": "kraken", "value": 1578.4999999999998, "alertThreshold": 2.0}]} 14:37:38 INFO - Return code: 0 14:37:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:37:38 ERROR - # TBPL SUCCESS # 14:37:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:37:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:37:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:37:38 INFO - 2016-07-05 14:37:38.649 screenresolution[1961:11792] starting screenresolution argv=screenresolution get 14:37:38 INFO - 2016-07-05 14:37:38.651 screenresolution[1961:11792] Display 0: 1600x1200x32@60 14:37:38 INFO - 2016-07-05 14:37:38.657 screenresolution[1962:11794] starting screenresolution argv=screenresolution list 14:37:38 INFO - Available Modes on Display 0 14:37:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:37:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:37:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:37:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:37:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:37:38 INFO - Intel Iris: 14:37:38 INFO - Chipset Model: Intel Iris 14:37:38 INFO - Type: GPU 14:37:38 INFO - Bus: Built-In 14:37:38 INFO - VRAM (Dynamic, Max): 1536 MB 14:37:38 INFO - Vendor: Intel (0x8086) 14:37:38 INFO - Device ID: 0x0a2e 14:37:38 INFO - Revision ID: 0x0009 14:37:38 INFO - Displays: 14:37:38 INFO - Display: 14:37:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:37:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:37:38 INFO - Display Serial Number: 1600x1200 60 14:37:38 INFO - Main Display: Yes 14:37:38 INFO - Mirror: Off 14:37:38 INFO - Online: Yes 14:37:38 INFO - Rotation: Supported 14:37:38 INFO - Return code: 0 14:37:38 INFO - Running post-action listener: _resource_record_post_action 14:37:38 INFO - Running post-run listener: _resource_record_post_run 14:37:39 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 2134016; Write bytes: 498182656; Read time: 24; Write time: 1581 14:37:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 0; Write bytes: 197965824; Read time: 0; Write time: 452 14:37:39 INFO - run-tests - Wall time: 1171s; CPU: 54.0%; Read bytes: 1273856; Write bytes: 299368960; Read time: 21; Write time: 1126 14:37:39 INFO - Running post-run listener: _upload_blobber_files 14:37:39 INFO - Blob upload gear active. 14:37:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:37:39 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 14:37:39 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:37:39 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:37:40 INFO - (blobuploader) - INFO - Open directory for files ... 14:37:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:37:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:37:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:42 INFO - (blobuploader) - INFO - Done attempting. 14:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:43 INFO - (blobuploader) - INFO - Done attempting. 14:37:43 INFO - (blobuploader) - INFO - Iteration through files over. 14:37:43 INFO - Return code: 0 14:37:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:37:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:37:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88fe1b11fd9dc3e5a385571b24a6f9a6dfcd27d5482b857a184ed3204d426e53f57762e31604c7c91dd0a63287663fcd11bfb803ee9e944c76a1a24b94492c6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a2ea779428e6a14433564302a5b21a4f3cfb2611da623ecff3fb6a1a02a8080efe89847038b191009e22906021463745648ca1c6c4dd02485de0f4c36af9a5d"} 14:37:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:37:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:37:43 INFO - Contents: 14:37:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88fe1b11fd9dc3e5a385571b24a6f9a6dfcd27d5482b857a184ed3204d426e53f57762e31604c7c91dd0a63287663fcd11bfb803ee9e944c76a1a24b94492c6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a2ea779428e6a14433564302a5b21a4f3cfb2611da623ecff3fb6a1a02a8080efe89847038b191009e22906021463745648ca1c6c4dd02485de0f4c36af9a5d"} 14:37:43 INFO - Running post-run listener: copy_logs_to_upload_dir 14:37:43 INFO - Copying logs to upload dir... 14:37:43 INFO - mkdir: /builds/slave/test/build/upload/logs 14:37:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1326.029466 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2016-07-05 14:37:43.340810) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 14:37:43.344741) ========= 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.K6tVXuGxiF/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.Ddpo3soczX/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-Beta/sha512/88fe1b11fd9dc3e5a385571b24a6f9a6dfcd27d5482b857a184ed3204d426e53f57762e31604c7c91dd0a63287663fcd11bfb803ee9e944c76a1a24b94492c6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a2ea779428e6a14433564302a5b21a4f3cfb2611da623ecff3fb6a1a02a8080efe89847038b191009e22906021463745648ca1c6c4dd02485de0f4c36af9a5d"} build_url:https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011033 build_url: 'https://queue.taskcluster.net/v1/task/TmxHjy9hSyuDoKL6OVVjjw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88fe1b11fd9dc3e5a385571b24a6f9a6dfcd27d5482b857a184ed3204d426e53f57762e31604c7c91dd0a63287663fcd11bfb803ee9e944c76a1a24b94492c6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a2ea779428e6a14433564302a5b21a4f3cfb2611da623ecff3fb6a1a02a8080efe89847038b191009e22906021463745648ca1c6c4dd02485de0f4c36af9a5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 14:37:43.378348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:37:43.378684) ========= 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.K6tVXuGxiF/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.Ddpo3soczX/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.004965 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 14:37:43.427182) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 14:37:43.427517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 14:37:43.427874) ========= ========= Total master_lag: 0.18 =========