builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0008 starttime: 1461701424.21 results: success (0) buildid: 20160426062558 builduid: 18458ff6a626441ea2f2e0a4fd3bd5e1 revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:24.215277) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:24.215701) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-26 13:10:24.215974) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-26 13:10:27.182466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:27.182861) ========= 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.wAJu2mI92k/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.Xp8nyFAipf/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 13:10:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 178M=0s 2016-04-26 13:10:27 (178 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154839 ========= master_lag: 0.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:27.574389) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:27.574706) ========= 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.wAJu2mI92k/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.Xp8nyFAipf/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026799 ========= master_lag: 1.22 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:28.822121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:28.822491) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wAJu2mI92k/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.Xp8nyFAipf/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 13:10:29,076 truncating revision to first 12 chars 2016-04-26 13:10:29,076 Setting DEBUG logging. 2016-04-26 13:10:29,076 attempt 1/10 2016-04-26 13:10:29,076 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cbeae0c4410b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:10:30,213 unpacking tar archive at: mozilla-aurora-cbeae0c4410b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.377588 ========= master_lag: 0.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:30.750540) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:30.750838) ========= script_repo_revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:30.751231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:30.751526) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:31.134759) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-04-26 13:10:31.135031) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wAJu2mI92k/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.Xp8nyFAipf/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:10:31 INFO - MultiFileLogger online at 20160426 13:10:31 in /builds/slave/test 13:10:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:10:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:10:31 INFO - {'append_to_log': False, 13:10:31 INFO - 'base_work_dir': '/builds/slave/test', 13:10:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:10:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:10:31 INFO - 'branch': 'Mozilla-Aurora', 13:10:31 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:31 INFO - 'config_files': ('talos/mac_config.py',), 13:10:31 INFO - 'default_actions': ('clobber', 13:10:31 INFO - 'read-buildbot-config', 13:10:31 INFO - 'download-and-extract', 13:10:31 INFO - 'populate-webroot', 13:10:31 INFO - 'create-virtualenv', 13:10:31 INFO - 'install', 13:10:31 INFO - 'run-tests'), 13:10:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:31 INFO - 'download_minidump_stackwalk': True, 13:10:31 INFO - 'download_symbols': 'ondemand', 13:10:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:31 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:31 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:31 INFO - 'installer_path': 'installer.exe', 13:10:31 INFO - 'log_level': 'info', 13:10:31 INFO - 'log_name': 'talos', 13:10:31 INFO - 'log_to_console': True, 13:10:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:10:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:10:31 INFO - 'opt_config_files': (), 13:10:31 INFO - 'pip_index': False, 13:10:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:31 INFO - 'cmd': ('bash', 13:10:31 INFO - '-c', 13:10:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:31 INFO - 'enabled': True, 13:10:31 INFO - 'halt_on_failure': False, 13:10:31 INFO - 'name': 'check_screen_resolution'},), 13:10:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:31 INFO - 'cmd': ('bash', 13:10:31 INFO - '-c', 13:10:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:31 INFO - 'enabled': True, 13:10:31 INFO - 'halt_on_failure': False, 13:10:31 INFO - 'name': 'check_screen_resolution'},), 13:10:31 INFO - 'run_cmd_checks_enabled': True, 13:10:31 INFO - 'sps_profile': False, 13:10:31 INFO - 'sps_profile_interval': 0, 13:10:31 INFO - 'suite': 'dromaeojs-e10s', 13:10:31 INFO - 'system_bits': '32', 13:10:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:31 INFO - 'title': 't-yosemite-r7-0008', 13:10:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:31 INFO - 'use_talos_json': True, 13:10:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:10:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:31 INFO - 'work_dir': 'build'} 13:10:31 INFO - ##### 13:10:31 INFO - ##### Running clobber step. 13:10:31 INFO - ##### 13:10:31 INFO - Running pre-action listener: _resource_record_pre_action 13:10:31 INFO - Running main action method: clobber 13:10:31 INFO - rmtree: /builds/slave/test/build 13:10:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:10:32 INFO - Running post-action listener: _resource_record_post_action 13:10:32 INFO - ##### 13:10:32 INFO - ##### Running read-buildbot-config step. 13:10:32 INFO - ##### 13:10:32 INFO - Running pre-action listener: _resource_record_pre_action 13:10:32 INFO - Running main action method: read_buildbot_config 13:10:32 INFO - Using buildbot properties: 13:10:32 INFO - { 13:10:32 INFO - "project": "", 13:10:32 INFO - "product": "firefox", 13:10:32 INFO - "script_repo_revision": "production", 13:10:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:10:32 INFO - "repository": "", 13:10:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:10:32 INFO - "buildid": "20160426062558", 13:10:32 INFO - "pgo_build": "False", 13:10:32 INFO - "basedir": "/builds/slave/test", 13:10:32 INFO - "buildnumber": 264, 13:10:32 INFO - "slavename": "t-yosemite-r7-0008", 13:10:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:10:32 INFO - "platform": "yosemite_r7", 13:10:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:10:32 INFO - "branch": "mozilla-aurora", 13:10:32 INFO - "repo_path": "releases/mozilla-aurora", 13:10:32 INFO - "revision": "cbeae0c4410bef3864b3d3f4ad939321c61cb15b", 13:10:32 INFO - "stage_platform": "macosx64", 13:10:32 INFO - "builduid": "18458ff6a626441ea2f2e0a4fd3bd5e1", 13:10:32 INFO - "slavebuilddir": "test" 13:10:32 INFO - } 13:10:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:10:32 INFO - Running post-action listener: _resource_record_post_action 13:10:32 INFO - ##### 13:10:32 INFO - ##### Running download-and-extract step. 13:10:32 INFO - ##### 13:10:32 INFO - Running pre-action listener: _resource_record_pre_action 13:10:32 INFO - Running main action method: download_and_extract 13:10:32 INFO - mkdir: /builds/slave/test/build/tests 13:10: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')]} 13:10:32 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:10:34 INFO - Downloaded 1199 bytes. 13:10:34 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:34 INFO - Using the following test package requirements: 13:10:34 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:10:34 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:10:34 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:10:34 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'jsshell-mac.zip'], 13:10:34 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:10:34 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:10:34 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:10:34 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:10:34 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:10:34 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:34 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:10:34 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:34 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:10:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:10:35 INFO - Downloaded 18110203 bytes. 13:10:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:10:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:10:38 INFO - Return code: 0 13:10:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:10: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')]} 13:10:38 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:10:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:10:41 INFO - Downloaded 18110203 bytes. 13:10:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:10:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:10:45 INFO - Return code: 0 13:10:45 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')]} 13:10:45 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:10:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:10:46 INFO - Downloaded 10840145 bytes. 13:10:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:10:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:10:46 INFO - Return code: 0 13:10: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')]} 13:10:46 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:10:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:10:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:10:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:11:23 INFO - Downloaded 114070774 bytes. 13:11:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:11:23 INFO - mkdir: /builds/slave/test/properties 13:11:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:11:23 INFO - Writing to file /builds/slave/test/properties/build_url 13:11:23 INFO - Contents: 13:11:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:11:24 INFO - Running post-action listener: _resource_record_post_action 13:11:24 INFO - Running post-action listener: set_extra_try_arguments 13:11:24 INFO - ##### 13:11:24 INFO - ##### Running populate-webroot step. 13:11:24 INFO - ##### 13:11:24 INFO - Running pre-action listener: _resource_record_pre_action 13:11:24 INFO - Running main action method: populate_webroot 13:11:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:11:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:11:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:11:24 INFO - '--tppagecycles', 13:11:24 INFO - '7'], 13:11:24 INFO - 'tests': ['tsvgm']}}, 13:11:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:11:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:11:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:11:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:11:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['damp', 'tps']}, 13:11:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'tests': ['damp', 'tps']}, 13:11:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['dromaeo_dom']}, 13:11:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:11:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['a11yr', 13:11:24 INFO - 'ts_paint', 13:11:24 INFO - 'tpaint', 13:11:24 INFO - 'sessionrestore', 13:11:24 INFO - 'sessionrestore_no_auto_restore', 13:11:24 INFO - 'tabpaint']}, 13:11:24 INFO - 'other-e10s': {'tests': ['a11yr', 13:11:24 INFO - 'ts_paint', 13:11:24 INFO - 'tpaint', 13:11:24 INFO - 'sessionrestore', 13:11:24 INFO - 'sessionrestore_no_auto_restore', 13:11:24 INFO - 'tabpaint']}, 13:11:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:11:24 INFO - 'ts_paint', 13:11:24 INFO - 'tpaint', 13:11:24 INFO - 'sessionrestore', 13:11:24 INFO - 'sessionrestore_no_auto_restore', 13:11:24 INFO - 'tabpaint']}, 13:11:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:11:24 INFO - 'ts_paint', 13:11:24 INFO - 'tpaint', 13:11:24 INFO - 'sessionrestore', 13:11:24 INFO - 'sessionrestore_no_auto_restore', 13:11:24 INFO - 'tabpaint']}, 13:11:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['a11yr', 13:11:24 INFO - 'ts_paint', 13:11:24 INFO - 'tpaint', 13:11:24 INFO - 'sessionrestore', 13:11:24 INFO - 'sessionrestore_no_auto_restore', 13:11:24 INFO - 'tabpaint']}, 13:11:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['a11yr', 13:11:24 INFO - 'ts_paint', 13:11:24 INFO - 'tpaint', 13:11:24 INFO - 'sessionrestore', 13:11:24 INFO - 'sessionrestore_no_auto_restore', 13:11:24 INFO - 'tabpaint']}, 13:11:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['tsvgx', 13:11:24 INFO - 'tsvgr_opacity', 13:11:24 INFO - 'tart', 13:11:24 INFO - 'tscrollx', 13:11:24 INFO - 'cart']}, 13:11:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:11:24 INFO - 'tsvgr_opacity', 13:11:24 INFO - 'tart', 13:11:24 INFO - 'tscrollx', 13:11:24 INFO - 'cart']}, 13:11:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'talos_options': ['--disable-e10s'], 13:11:24 INFO - 'tests': ['tp5o']}, 13:11:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'tests': ['tp5o']}, 13:11:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'talos_options': ['--disable-e10s', 13:11:24 INFO - '--xperf_path', 13:11:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:24 INFO - 'tests': ['tp5n']}, 13:11:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:24 INFO - 'talos_options': ['--xperf_path', 13:11:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:24 INFO - 'tests': ['tp5n']}}, 13:11:24 INFO - 'talos.zip': {'path': '', 13:11:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:11:24 INFO - Running post-action listener: _resource_record_post_action 13:11:24 INFO - ##### 13:11:24 INFO - ##### Running create-virtualenv step. 13:11:24 INFO - ##### 13:11:24 INFO - Running pre-action listener: _resource_record_pre_action 13:11:24 INFO - Running main action method: create_virtualenv 13:11:24 INFO - Creating virtualenv /builds/slave/test/build/venv 13:11:24 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 13:11:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:11:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:11:24 INFO - Using real prefix '/tools/python27' 13:11:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:11:25 INFO - Installing distribute.............................................................................................................................................................................................done. 13:11:28 INFO - Installing pip.................done. 13:11:28 INFO - Return code: 0 13:11:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:28 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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:28 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 13:11:28 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 13:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:28 INFO - 'HOME': '/Users/cltbld', 13:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:28 INFO - 'LOGNAME': 'cltbld', 13:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:28 INFO - 'MOZ_NO_REMOTE': '1', 13:11:28 INFO - 'NO_EM_RESTART': '1', 13:11:28 INFO - 'PAGER': '/bin/cat', 13:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:28 INFO - 'PWD': '/builds/slave/test', 13:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:28 INFO - 'SHELL': '/bin/bash', 13:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:28 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:28 INFO - 'USER': 'cltbld', 13:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:28 INFO - 'XPC_FLAGS': '0x0', 13:11:28 INFO - 'XPC_SERVICE_NAME': '0', 13:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:28 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 13:11:28 INFO - Cleaning up... 13:11:28 INFO - Return code: 0 13:11:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:28 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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:28 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 13:11:28 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 13:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:28 INFO - 'HOME': '/Users/cltbld', 13:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:28 INFO - 'LOGNAME': 'cltbld', 13:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:28 INFO - 'MOZ_NO_REMOTE': '1', 13:11:28 INFO - 'NO_EM_RESTART': '1', 13:11:28 INFO - 'PAGER': '/bin/cat', 13:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:28 INFO - 'PWD': '/builds/slave/test', 13:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:28 INFO - 'SHELL': '/bin/bash', 13:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:28 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:28 INFO - 'USER': 'cltbld', 13:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:28 INFO - 'XPC_FLAGS': '0x0', 13:11:28 INFO - 'XPC_SERVICE_NAME': '0', 13:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:28 INFO - Downloading/unpacking psutil>=0.7.1 13:11:28 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 13:11:28 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 13:11:28 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 13:11:28 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 13:11:28 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 13:11:28 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 13:11:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:32 INFO - Installing collected packages: psutil 13:11:32 INFO - Running setup.py install for psutil 13:11:32 INFO - building 'psutil._psutil_osx' extension 13:11:32 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 13:11:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:11:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:11:33 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 13:11:33 INFO - building 'psutil._psutil_posix' extension 13:11:33 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 13:11:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:11:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:11:33 INFO - ^ 13:11:33 INFO - 1 warning generated. 13:11:33 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 13:11:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:33 INFO - Successfully installed psutil 13:11:33 INFO - Cleaning up... 13:11:33 INFO - Return code: 0 13:11:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:34 INFO - 'HOME': '/Users/cltbld', 13:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:34 INFO - 'LOGNAME': 'cltbld', 13:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:34 INFO - 'MOZ_NO_REMOTE': '1', 13:11:34 INFO - 'NO_EM_RESTART': '1', 13:11:34 INFO - 'PAGER': '/bin/cat', 13:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:34 INFO - 'PWD': '/builds/slave/test', 13:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:34 INFO - 'SHELL': '/bin/bash', 13:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:34 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:34 INFO - 'USER': 'cltbld', 13:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:34 INFO - 'XPC_FLAGS': '0x0', 13:11:34 INFO - 'XPC_SERVICE_NAME': '0', 13:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:11: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 13:11: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 13:11: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 13:11: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 13:11: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 13:11: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 13:11:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:11:36 INFO - Installing collected packages: mozsystemmonitor 13:11:36 INFO - Running setup.py install for mozsystemmonitor 13:11:36 INFO - Successfully installed mozsystemmonitor 13:11:36 INFO - Cleaning up... 13:11:36 INFO - Return code: 0 13:11:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:11:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:36 INFO - 'HOME': '/Users/cltbld', 13:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:36 INFO - 'LOGNAME': 'cltbld', 13:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:36 INFO - 'MOZ_NO_REMOTE': '1', 13:11:36 INFO - 'NO_EM_RESTART': '1', 13:11:36 INFO - 'PAGER': '/bin/cat', 13:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:36 INFO - 'PWD': '/builds/slave/test', 13:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:36 INFO - 'SHELL': '/bin/bash', 13:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:36 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:36 INFO - 'USER': 'cltbld', 13:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:36 INFO - 'XPC_FLAGS': '0x0', 13:11:36 INFO - 'XPC_SERVICE_NAME': '0', 13:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:37 INFO - Downloading/unpacking blobuploader==1.2.4 13:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:39 INFO - Downloading blobuploader-1.2.4.tar.gz 13:11:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:11:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:11:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:11: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 13:11: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 13:11: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 13:11: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 13:11: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 13:11: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 13:11:40 INFO - Downloading docopt-0.6.1.tar.gz 13:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:11:40 INFO - Installing collected packages: blobuploader, requests, docopt 13:11:40 INFO - Running setup.py install for blobuploader 13:11:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:11:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:11:41 INFO - Running setup.py install for requests 13:11:41 INFO - Running setup.py install for docopt 13:11:41 INFO - Successfully installed blobuploader requests docopt 13:11:41 INFO - Cleaning up... 13:11:41 INFO - Return code: 0 13:11:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:41 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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:41 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 13:11:41 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 13:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:41 INFO - 'HOME': '/Users/cltbld', 13:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:41 INFO - 'LOGNAME': 'cltbld', 13:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:41 INFO - 'MOZ_NO_REMOTE': '1', 13:11:41 INFO - 'NO_EM_RESTART': '1', 13:11:41 INFO - 'PAGER': '/bin/cat', 13:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:41 INFO - 'PWD': '/builds/slave/test', 13:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:41 INFO - 'SHELL': '/bin/bash', 13:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:41 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:41 INFO - 'USER': 'cltbld', 13:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:41 INFO - 'XPC_FLAGS': '0x0', 13:11:41 INFO - 'XPC_SERVICE_NAME': '0', 13:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-i4pDcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-bVgmuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-RPKwFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-wv9X0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-T_epu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-D7ICYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Fp1dG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6XUaGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-O7J_GJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-rFrBdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-SKVcUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-UBSbhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-3azfEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-okZyb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6G4Ols-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-H_J7Fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-gwBNG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:11:43 INFO - Running setup.py install for manifestparser 13:11:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:11:43 INFO - Running setup.py install for mozcrash 13:11:44 INFO - Running setup.py install for mozdebug 13:11:44 INFO - Running setup.py install for mozdevice 13:11:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Running setup.py install for mozfile 13:11:44 INFO - Running setup.py install for mozhttpd 13:11:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Running setup.py install for mozinfo 13:11:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Running setup.py install for mozInstall 13:11:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:11:44 INFO - Running setup.py install for mozleak 13:11:45 INFO - Running setup.py install for mozlog 13:11:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:11:45 INFO - Running setup.py install for moznetwork 13:11:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:11:45 INFO - Running setup.py install for mozprocess 13:11:45 INFO - Running setup.py install for mozprofile 13:11:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:11:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:11:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:11:45 INFO - Running setup.py install for mozrunner 13:11:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:11:45 INFO - Running setup.py install for mozscreenshot 13:11:46 INFO - Running setup.py install for moztest 13:11:46 INFO - Running setup.py install for mozversion 13:11:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:11:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:11:46 INFO - Cleaning up... 13:11:46 INFO - Return code: 0 13:11:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:46 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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:46 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 13:11:46 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 13:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:46 INFO - 'HOME': '/Users/cltbld', 13:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:46 INFO - 'LOGNAME': 'cltbld', 13:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:46 INFO - 'MOZ_NO_REMOTE': '1', 13:11:46 INFO - 'NO_EM_RESTART': '1', 13:11:46 INFO - 'PAGER': '/bin/cat', 13:11:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:46 INFO - 'PWD': '/builds/slave/test', 13:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:46 INFO - 'SHELL': '/bin/bash', 13:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:46 INFO - 'USER': 'cltbld', 13:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:46 INFO - 'XPC_FLAGS': '0x0', 13:11:46 INFO - 'XPC_SERVICE_NAME': '0', 13:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:46 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-jWWcoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:46 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)) 13:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:46 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-fI1jz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:46 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)) 13:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:46 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-h1fbce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:46 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)) 13:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:46 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-mBVbzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-kkKDiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-5WKP82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-3QXNJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-j06KJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-0RbvOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-HmHkPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-quF_tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Bmlp_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:47 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-VPPi_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:47 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)) 13:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OFt_vQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-h6AoPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-JisrFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:48 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)) 13:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Sftqyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:48 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)) 13:11:48 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)) 13:11:48 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)) 13:11:48 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)) 13:11:48 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)) 13:11:48 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)) 13:11:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:50 INFO - Downloading blessings-1.6.tar.gz 13:11:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:11:50 INFO - Installing collected packages: blessings 13:11:50 INFO - Running setup.py install for blessings 13:11:51 INFO - Successfully installed blessings 13:11:51 INFO - Cleaning up... 13:11:51 INFO - Return code: 0 13:11:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:11:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:51 INFO - Reading from file tmpfile_stdout 13:11:51 INFO - Current package versions: 13:11:51 INFO - blessings == 1.6 13:11:51 INFO - blobuploader == 1.2.4 13:11:51 INFO - docopt == 0.6.1 13:11:51 INFO - manifestparser == 1.1 13:11:51 INFO - mozInstall == 1.12 13:11:51 INFO - mozcrash == 0.17 13:11:51 INFO - mozdebug == 0.1 13:11:51 INFO - mozdevice == 0.48 13:11:51 INFO - mozfile == 1.2 13:11:51 INFO - mozhttpd == 0.7 13:11:51 INFO - mozinfo == 0.9 13:11:51 INFO - mozleak == 0.1 13:11:51 INFO - mozlog == 3.1 13:11:51 INFO - moznetwork == 0.27 13:11:51 INFO - mozprocess == 0.22 13:11:51 INFO - mozprofile == 0.28 13:11:51 INFO - mozrunner == 6.11 13:11:51 INFO - mozscreenshot == 0.1 13:11:51 INFO - mozsystemmonitor == 0.0 13:11:51 INFO - moztest == 0.7 13:11:51 INFO - mozversion == 1.4 13:11:51 INFO - psutil == 3.1.1 13:11:51 INFO - requests == 1.2.3 13:11:51 INFO - wsgiref == 0.1.2 13:11:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:51 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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:51 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 13:11:51 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 13:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:51 INFO - 'HOME': '/Users/cltbld', 13:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:51 INFO - 'LOGNAME': 'cltbld', 13:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:51 INFO - 'MOZ_NO_REMOTE': '1', 13:11:51 INFO - 'NO_EM_RESTART': '1', 13:11:51 INFO - 'PAGER': '/bin/cat', 13:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:51 INFO - 'PWD': '/builds/slave/test', 13:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:51 INFO - 'SHELL': '/bin/bash', 13:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:51 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:51 INFO - 'USER': 'cltbld', 13:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:51 INFO - 'XPC_FLAGS': '0x0', 13:11:51 INFO - 'XPC_SERVICE_NAME': '0', 13:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 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)) 13:11:51 INFO - Cleaning up... 13:11:51 INFO - Return code: 0 13:11:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:51 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 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:51 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 13:11:51 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 13:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:51 INFO - 'HOME': '/Users/cltbld', 13:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:51 INFO - 'LOGNAME': 'cltbld', 13:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:51 INFO - 'MOZ_NO_REMOTE': '1', 13:11:51 INFO - 'NO_EM_RESTART': '1', 13:11:51 INFO - 'PAGER': '/bin/cat', 13:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:51 INFO - 'PWD': '/builds/slave/test', 13:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:51 INFO - 'SHELL': '/bin/bash', 13:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:51 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:51 INFO - 'USER': 'cltbld', 13:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:51 INFO - 'XPC_FLAGS': '0x0', 13:11:51 INFO - 'XPC_SERVICE_NAME': '0', 13:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:52 INFO - Downloading/unpacking jsonschema 13:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:54 INFO - Downloading/unpacking functools32 (from jsonschema) 13:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:54 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:55 INFO - warning: no files found matching '*.txt' 13:11:55 INFO - no previously-included directories found matching 'build' 13:11:55 INFO - no previously-included directories found matching 'dist' 13:11:55 INFO - no previously-included directories found matching '.git*' 13:11:55 INFO - Installing collected packages: jsonschema, functools32 13:11:55 INFO - Running setup.py install for functools32 13:11:55 INFO - warning: no files found matching '*.txt' 13:11:55 INFO - no previously-included directories found matching 'build' 13:11:55 INFO - no previously-included directories found matching 'dist' 13:11:55 INFO - no previously-included directories found matching '.git*' 13:11:55 INFO - Successfully installed jsonschema functools32 13:11:55 INFO - Cleaning up... 13:11:55 INFO - Return code: 0 13:11:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd7def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, '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.wAJu2mI92k/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.Xp8nyFAipf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:55 INFO - 'HOME': '/Users/cltbld', 13:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:55 INFO - 'LOGNAME': 'cltbld', 13:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:55 INFO - 'MOZ_NO_REMOTE': '1', 13:11:55 INFO - 'NO_EM_RESTART': '1', 13:11:55 INFO - 'PAGER': '/bin/cat', 13:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:55 INFO - 'PWD': '/builds/slave/test', 13:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:55 INFO - 'SHELL': '/bin/bash', 13:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:11:55 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:55 INFO - 'USER': 'cltbld', 13:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:55 INFO - 'XPC_FLAGS': '0x0', 13:11:55 INFO - 'XPC_SERVICE_NAME': '0', 13:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:55 INFO - Downloading/unpacking flake8 13:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:11:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:11:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:11:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:11:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:11:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:11:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:11:59 INFO - no previously-included directories found matching 'docs/_build' 13:11:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:11:59 INFO - Running setup.py install for pep8 13:11:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:11:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:11:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:11:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:11:59 INFO - no previously-included directories found matching 'docs/_build' 13:11:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:11:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:11:59 INFO - Cleaning up... 13:11:59 INFO - Return code: 0 13:11:59 INFO - Running post-action listener: _resource_record_post_action 13:11:59 INFO - Running post-action listener: _start_resource_monitoring 13:11:59 INFO - Starting resource monitoring. 13:11:59 INFO - ##### 13:11:59 INFO - ##### Running install step. 13:11:59 INFO - ##### 13:11:59 INFO - Running pre-action listener: _resource_record_pre_action 13:11:59 INFO - Running main action method: install 13:11:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:59 INFO - Reading from file tmpfile_stdout 13:11:59 INFO - Detecting whether we're running mozinstall >=1.0... 13:11:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:12:00 INFO - Reading from file tmpfile_stdout 13:12:00 INFO - Output received: 13:12:00 INFO - Usage: mozinstall [options] installer 13:12:00 INFO - Options: 13:12:00 INFO - -h, --help show this help message and exit 13:12:00 INFO - -d DEST, --destination=DEST 13:12:00 INFO - Directory to install application into. [default: 13:12:00 INFO - "/builds/slave/test"] 13:12:00 INFO - --app=APP Application being installed. [default: firefox] 13:12:00 INFO - mkdir: /builds/slave/test/build/application 13:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:12:31 INFO - Reading from file tmpfile_stdout 13:12:31 INFO - Output received: 13:12:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:12:31 INFO - Running post-action listener: _resource_record_post_action 13:12:31 INFO - ##### 13:12:31 INFO - ##### Running run-tests step. 13:12:31 INFO - ##### 13:12:31 INFO - Running pre-action listener: _resource_record_pre_action 13:12:31 INFO - Running main action method: run_tests 13:12:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:12:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:12:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:12:31 INFO - 2016-04-26 13:12:31.888 screenresolution[1646:7711] starting screenresolution argv=screenresolution get 13:12:31 INFO - 2016-04-26 13:12:31.891 screenresolution[1646:7711] Display 0: 1600x1200x32@60 13:12:31 INFO - 2016-04-26 13:12:31.897 screenresolution[1647:7713] starting screenresolution argv=screenresolution list 13:12:31 INFO - Available Modes on Display 0 13:12:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:12:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:12:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:12:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:12:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:12:32 INFO - Intel Iris: 13:12:32 INFO - Chipset Model: Intel Iris 13:12:32 INFO - Type: GPU 13:12:32 INFO - Bus: Built-In 13:12:32 INFO - VRAM (Dynamic, Max): 1536 MB 13:12:32 INFO - Vendor: Intel (0x8086) 13:12:32 INFO - Device ID: 0x0a2e 13:12:32 INFO - Revision ID: 0x0009 13:12:32 INFO - Displays: 13:12:32 INFO - Display: 13:12:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:12:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:12:32 INFO - Display Serial Number: 1600x1200 60 13:12:32 INFO - Main Display: Yes 13:12:32 INFO - Mirror: Off 13:12:32 INFO - Online: Yes 13:12:32 INFO - Rotation: Supported 13:12:32 INFO - Return code: 0 13:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:12:32 INFO - Python 2.7.3 13:12:32 INFO - Return code: 0 13:12:32 INFO - grabbing minidump binary from tooltool 13:12: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')]} 13:12:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b658e00>, '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 0x10b4c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b653b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:12:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:12:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:12:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:12:32 INFO - Return code: 0 13:12:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:12:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:12:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:12:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:12:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:12:33 INFO - Return code: 0 13:12:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 13:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --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 13:12:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAJu2mI92k/Render', 13:12:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:33 INFO - 'HOME': '/Users/cltbld', 13:12:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:33 INFO - 'LOGNAME': 'cltbld', 13:12:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:12:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:33 INFO - 'MOZ_NO_REMOTE': '1', 13:12:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:12:33 INFO - 'NO_EM_RESTART': '1', 13:12:33 INFO - 'PAGER': '/bin/cat', 13:12:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:33 INFO - 'PWD': '/builds/slave/test', 13:12:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:12:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:33 INFO - 'SHELL': '/bin/bash', 13:12:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xp8nyFAipf/Listeners', 13:12:33 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 13:12:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:33 INFO - 'USER': 'cltbld', 13:12:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:33 INFO - 'XPC_FLAGS': '0x0', 13:12:33 INFO - 'XPC_SERVICE_NAME': '0', 13:12:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 13:12:33 INFO - mozversion application_buildid: 20160426062558 13:12:33 INFO - mozversion application_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:12:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:12:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:12:33 INFO - mozversion application_name: Firefox 13:12:33 INFO - mozversion application_remotingname: firefox-dev 13:12:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:12:33 INFO - mozversion application_vendor: Mozilla 13:12:33 INFO - mozversion application_version: 48.0a2 13:12:33 INFO - mozversion platform_buildid: 20160426062558 13:12:33 INFO - mozversion platform_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:12:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:12:33 INFO - mozversion platform_version: 48.0a2 13:12:33 INFO - using testdate: 1461701553 13:12:33 INFO - actual date: 1461701553 13:12:33 INFO - starting webserver on 'localhost:49270' 13:12:33 INFO - SUITE-START | Running 2 tests 13:12:33 INFO - TEST-START | dromaeo_css 13:12:33 INFO - operating with platform_type : mac_ 13:12:33 INFO - Initialising browser for dromaeo_css test... 13:12:33 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmp5IfdhO/profile http://localhost:49270/getInfo.html) 13:12:36 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 13:12:36 INFO - PROCESS | 1665 | colorDepth:24 13:12:36 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 13:12:36 INFO - PROCESS | 1665 | __metrics 13:12:36 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:12:37 INFO - TEST-INFO | 1665: exit 0 13:12:37 INFO - Browser initialized. 13:12:37 INFO - Running cycle 1/1 for dromaeo_css test... 13:12:37 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmp5IfdhO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:48 INFO - PROCESS | 1669 | RSS: Main: 221618176 13:12:48 INFO - PROCESS | 1669 | 13:13:38 INFO - PROCESS | 1669 | 1461701618591 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] 13:15:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 13:15:50 INFO - PROCESS | 1669 | RSS: Main: 233230336 13:15:50 INFO - PROCESS | 1669 | 13:18:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 13:18:53 INFO - PROCESS | 1669 | RSS: Main: 243806208 13:18:53 INFO - PROCESS | 1669 | 13:21:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 13:21:55 INFO - PROCESS | 1669 | RSS: Main: 245805056 13:21:55 INFO - PROCESS | 1669 | 13:24:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 13:24:57 INFO - PROCESS | 1669 | RSS: Main: 247877632 13:24:57 INFO - PROCESS | 1669 | 13:28:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 13:28:01 INFO - PROCESS | 1669 | RSS: Main: 250388480 13:28:01 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 13:31:03 INFO - PROCESS | 1669 | RSS: Main: 251293696 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | __start_tp_report 13:31:03 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 13:31:03 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 13:31:03 INFO - PROCESS | 1669 | |i|pagename|runs| 13:31:03 INFO - PROCESS | 1669 | |0;dojo.html;3440;3486.9651303486967;3494.091536200587;3512.806795626241;3544.8582056717732;5720.170575266584;5720.485156335929;5728.255326807514;5752.626079304842;5786.103154011124;5742.425757424256;5762.423757624236;5794.333651630064;5796.275465566804;5812.796552120677;5180.1452760294505;5188.766505507242;5260.894782104375;5267.578593712513;5286.392064912527;5101.7194054327;5168.379794424674;5251.606129540281;5311.468853114677;5344.198370244456;4684.625229981611;5257.684538927677;5307.336582927134;5312.4687531246955;5318.840434786963;5285.2600635911;5295.8146464873735;5307.575393968512;5323.707196104213;5328.653637513588;5023.6232282578585;5098.286239926413;5158.277841102249;5244.580433565306;5244.790208391698;5005.2492126181;5558.277423934863;5560.138178582326;5560.972195139039;5578.944210557924;5078.390593128811;6170.84572885677;6195.659238741868;6240.625562466266;6275.121482992385;6160.414760597736;6178.227721534809;6247.781327653511;6262.248530176363;6263.874722505524;5918.141869428909;5945.970270148621;5978.2228310319815;5979.1031345298115;5999.340072592011;5305.893882122413;6342.5560210785725;6361.968190159112;6366.331535188739;6383.3616638336725;6073.331933487313;6168.013117901204;6200.81397558074;6243.562950593415;6251.187345645128;6262.655553944485;6333.398327158912;6339.985602303609;6353.650549219838;6367.936320636827;6104.3590423005835;6284.748610055546;6292.937070629327;6305.211848518935;6326.2724786649205;5626.874625074919;5949;6279.277883043508;6285.717142728588;6288.465480434123;4881.121398148367;4901.117798796251;4920.6309526785635;4934.5312195341385;4945.2582112683385;3746.3818469952153;4371.75955322454;4461.486929003141;4463.888402789957;4481.865544033684;5600.719964001783;5691.8292451226525;5812.3025236971835;5815.854603634943;5863.296404431496;6268.968655156695;6279.717412716347;6292.811215663446;6296.748130074746;6331.081993110973;5669.744861481243;5782.797602083907;5842.707864606753;5846.123081537803;5891.617044892068;5440.319960004999;5443.401225865152;5460.262864513319;5479.260299859548;5522.9447705523235;6408.487321014308;6419.2296924369375;6428.839279018062;6431.6462594556815;6434.774782882764;6172.567920245539;6233.407826256498;6270.184875966095;6291.937080629135;6320.4311611955145;6335.1447554579545;6339.714712837841;6353;6354.491640668828;6366.96816515933;6188;6226;6229.595076320024;6231.53263505248;6234.438900498795;6191.133241346125;6227.06594010902;6242.656653883898;6297.842553936188;6311.621302721851;5934.258217722785;6116.785912493041;6132.202813634378;6153.169322141453;6160.722767475385;6075.362086980804;6115.694215289307;6137.202163718688;6172.4136207060255;6178.969105154445;4941.678790878582;5866.472017518273;5896.64620122794;5944.5838791284195;6012.248468941382;6124.173236613;6132.816015519542;6137.907931380943;6142.355052719578;6151.169592105009;5871.500922421471;6041.093835924647;6058.212432383762;6068.939310607014;6071.241094863142 13:31:03 INFO - PROCESS | 1669 | |1;ext.html;521.1895502493624;537.9704116273605;578.9160571717099;589.9763909616817;591.5829340315076;36805.631943680586;38815.611843881554;39781.60218397815;39835;40651.79674101625;40826.7958660207;41529.792351038275;41583.58416415835;41668.374974375336;41731.58268417315;9503.857442138373;9670.516474176264;9700.951495242514;9769.804603907918;9821.066998635153;9673;9764.853527197098;9770.218382529381;9855.359401638907;9938.552765125587;9041.773955651095;9222.262219022463;9791.951040244789;9846.55690493926;9902.752431189241;8504.914950850536;9723.02769723029;9800.872899616494;9804.509774511282;9821.558029888602;9600.471974041426;9618.663346782829;9711.708648740549;9734.123928846368;9790.118889299998;39130.413043804365;39454.02729863495;39701.808945731675;39704;39727;2786.233785708926;2801.7758579313604;3050.145959131447;3129.984350078235;3317.552130462405;27050.864745676343;27635.447291054068;27641.308967275978;27981.160565183076;28038.439231215467;2713.7150599187;2719.728027197284;2731.6312297839936;2841.7597889998347;2856.8000239983207;432509;435358;439885;440676;441802;34072.659273407444;35457;35506;35631.643683562834;35721.1069723259;35584.11039723976;35682.28635427277;35689.82155089208;36003.81998089993;36185.276294473966;15562.610934726496;15939.123348215959;15972.201389930457;15987.36050557988;16042.358305667876;13186.802197967038;13262.336883155804;13332.00009999254;13385.66535836612;13398.732025359439;8036.075851277178;8233.50598964064;8293.875591866126;8365;8391.874121888177;3380.154961259685;3405.6424075472214;3420.8369154487596;3435.347284016029;3463.0995941055508;1003.2375394699934;1009.1422291052693;1026.9180009462477;1096.6216655253925;1111.2110401614943;25878.6118208227;30351.544726829114;30467.085987420414;30531.694683052887;31015.689843101733;1039.73486760877;1080.2600218850114;1138.8405623212757;1144.2162118948547;1187.7624475105013;14069.788953165711;14800.851991480164;14835.554933352018;14964.850351496563;15148.394064237307;420288;541331;543952;544765.1046979039;551046;56210.43789562134;57172;57529;58259.70870145707;58645;1502.6168327076525;1502.7295086884467;1516.3252352703162;1525.6872341170033;1541.7610270408104;28882;29188.854055729586;29259.41481170407;29395.412091758473;29849.850750746107;50307;51666;51864.48135518596;52105;52254.738726306125;575149;583016;594658;595387;596443;27013.189604311116;27050.72949270482;27282.454350912474;27352;27380;45574;45945.54054459546;46027.53972460233;47017.05965880595;47675.761621191676;37661.246775064894;38158.236835262585;38159.618403815606;38446.80776596099;38842.6115738839;34843.477347839296;35960.100997475274;38144;38828.80585597054;38830.223395531364;34724.82637586796;35208.295834083685;35293.64706352904;35363.46954795629;35587.644123558435;23706.170284039978;24163.879180603984;24512.264632061066;30962.723305673102;31550.36899262048;23780.16769413062;23992.40018999539;24138.87930560336;24327.635085474085;24614.753852461246 13:31:03 INFO - PROCESS | 1669 | |2;jquery.html;331.7346123101519;396.9880903572892;401.2296390929415;412.0872267926544;412.20855956563395;18991.715124273145;20670;20982.475438114056;21006.109450149484;21449.785502144994;20107.592468527208;20480.89759551204;20727.896360518214;21195.152193912225;21290.361289161312;5343.706096164709;5919.822405327836;6124;6136.969315153418;6140.447359737623;5901.17383566301;6065.696715164224;6069.302030266532;6118.3269840317525;6128.142060111588;5997.8800423991715;6050.304215015286;6054.727537260834;6096.847578810521;6100.084987251926;5903.3506314305605;5906.055031194988;5913.940860591382;5987.1318658794235;6020.608660437079;5205.8958820823755;5249.737513124329;5250.789968401259;5274.683518988873;5275.155975044013;25198.622020669703;26065.218043458728;26332.73667263322;26381.3404664883;26400.60399094015;2140.871547707127;2186.35502526755;2244.539869326784;2262.3552287597954;2286.874221917794;14132.081414708011;14589.6352591185;14591.41634334636;14857.554273371708;14928.701425971529;1725.8187890271465;1754.7894252689632;1774.5297496163528;1796.0301437223884;1818;64132;111470;115964.42017789857;115998;119514;11107.222494425474;11222.438878056064;11550.88449115515;11662.12534059932;11717.648470545897;12977.091603587658;13905.79141312881;14604.634884127981;14635.414583416545;14704;8950.015498295183;9090.727278181665;9117.726468205965;9145.496997665192;9165.633374664938;9137.77155571116;9167.26661867049;9238.353315267866;9387.671431499864;9398.389104708172;5715.2855893013375;6976.5465244758925;7015.438764898796;7021;7022.403095736818;1923.1922592511178;1958.4418440744319;1965.7641083070123;1970.664986952193;1977.4166620846884;897.8967418746797;975.2149519636619;980.8724865767547;987.8666380038603;1012.711377257478;19820.40538783839;20512.38462846117;21426.357209283746;21723.45691357697;21976.670349944765;975.3660120921458;1008.3848852200152;1035.8756949166147;1071.5713714514256;1080.670395529371;9930.404175749478;10083.899161008296;10762.569497220024;11288.379139147426;11466.2546934449;530078;592097;599673;627602;631357;104454.47772761088;110043;135908;137766;141432;1058.5448257249457;1071.8660167479065;1123.6292023632182;1123.9719007024726;1132.7054965708976;17808.46574602764;18116.365927192484;18253.178606962454;18421.631567368844;18425.907870460564;51012;52841.20738189007;54664.726676366365;54838.72580637071;58237.41762582489;82830;83166;83372;84060.89847753855;87014;7957.801054973672;8033.437659363788;8092.190780921861;8118.309943654619;8337.83324333522;9101;11858.288502689866;12423.813642795547;12711.364431778558;12759.298238596966;10810.945945270223;10851;10907.696063046966;11095.445227738743;11159.832602510807;9176.944651365178;10710.928907109226;10731.731706707395;10944.288621239593;11187.832182517432;9306.92380682705;11228;11319.434028298718;11324.37715925632;11330.263532870336;8095.311898488459;8179.468334558234;8262.793430164294;8298.834023319621;8304.418690691593;10186.439745814052;11077.335359878432;11135.888641113484;11160.553577856796;11386.032187264176 13:31:03 INFO - PROCESS | 1669 | |3;mootools.html;1583.3903946980408;1601.7517284820854;1846.7968523462416;1939.738135351728;1959.1869374209705;7073.398761105299;7431.62841857907;8517;8579.940814204585;8925.28597712183;8500.489970601768;8507.617157227924;8548.615312310945;8648.91351086489;8653.134686531344;2400.675908752318;2436.878156092197;2437.5856104462196;2439.4389290463205;2441.755824417553;2376.8217383696206;2396.532676128156;2418.600930846408;2441.2432146034785;2446.5473887330813;2433.9756602433927;2439.316991242455;2445.1808644104244;2465.174166654173;2479.82641215115;2395.377201927494;2421.2978236311446;2421.7820396164343;2425.163318655061;2436.488337449138;2384.6899903012495;2421.9152329668377;2425.641640681224;2438.4635380216323;2445.8410203336725;7799.922000779976;8433.481973244814;8726.171013753683;8792.252658524034;8829.646814127427;5305.124654432014;5390.73046347685;5508.338999320067;5543.307086614173;5551.77792888284;3530.382183117944;3548.2371290172478;3573.535440392758;3630.8547658093644;3647.452882067711;1024.9948750256276;1034.7051090439188;1039.5062345385957;1048.4652827058255;1075.0485820049234;11548.249363791327;11693.356865372401;11703.18077734559;11705.824412633818;11755.882441175565;4793.664443488992;4821.228603423436;4930.433000204951;4933.629977751683;4964.851054468371;9865.013498650222;9952.452615106095;10064.647737329158;10077.496125193711;10094.343867648578;5385.165299378603;5401.675899446046;5411.594130440233;5474.233607294982;5500.58745594074;4470.150671372428;4843.709377437365;4959.107360675041;4991.151504244287;5005.649604527721;3884.0872394987155;3938.9955561331676;3996.580359062257;4116.938245926313;4135.945333939826;1334.6129622409392;1347.4812197304109;1352.4184600621827;1358.7418390505773;1362.4876387210188;909.4770506958525;925.2829057480507;926.4765407544851;926.7451450850936;936.7143021378408;5805.448482394166;5834.766609335579;5865.237519122486;5999.430054144849;6012.248468941382;415.71315792103354;443.5697373547654;444.35346570739495;458.00612670505086;463.04150408653766;3889.747166434173;3901.6878649707955;3942.704297177723;4003.91992160161;4184.476940382452;10506.106980906708;10747.247692661595;10750;10757.56969721218;10941.726456838484;10495.317804342692;10638.627648032281;10649.520771565298;10689.465526723632;10716.517756700967;7744.6514906829325;7856.978592783016;8100.149484304181;8115.715949941724;8144.022717273965;4959.528844759742;5888.735006924784;5901.91147132802;6020.8795824084145;6088.238970128734;7655.88516172269;9738.75653108678;9848.507574621097;9850.113489785954;9990.900090999288;8591.398602097794;9500.762480938032;9769.218462522838;9791.314607977372;10214.285000049926;6648.404382947961;6902.620355880475;7311.963440182765;7506.849863002819;7600.239976002355;7844.21557844211;8223.753287401387;8333.458325208841;8360.12218717026;8383.874241886255;6091.390860913873;7403.629818508945;7422.703091876265;7487.288707572772;7510.248975102446;7293.307135822089;7315.597642129734;7361.322990841707;7397.556146631219;7463.067116610424;6917.827054323682;6926.341997510229;6931.133608298963;6968.198657154492;7004.964975175091;4809.927851082307;4834.468208497133;4855.927161092516;4909.410870695539;4924.704517728947;4868.853934381974;4875.439324477588;4895.485973972682;4943.802247910044;5101.336826212569 13:31:03 INFO - PROCESS | 1669 | |4;prototype.html;271.8148870922778;311.15366203925316;313.57667149348384;318.14260567769855;320.2106806721431;5455.727213639322;5537.197106419571;5565.610407271488;5603.859903502404;5681.579605098726;4601.884952876171;4886.8778280543;5212.452692467284;5511.649645836776;5545.667259964405;3697.6487233712755;4062.8872986047313;4119.443875076871;4165.875023749277;4270.850520231793;3873.895939657198;4096.77096870939;4250.702450828443;4256.170046840867;4261.659067274626;3466.8093254870973;3777.7544459610035;4050.736702114353;4172.833086676529;4216.198922204787;3673.981630091846;3881.437191607201;4078.085038363175;4141.358089496133;4141.544430112685;3012.728854403092;3033.969660303391;3059.4340047091137;3127.1556679696355;3184.85668144934;4305.9354109688065;4744.739039352834;4986.750662466899;5367.973160134214;5388.973055134738;4097.135803209532;4280.807363668642;4769.809207631691;4788.712677239342;4803.831865884677;5718.7426565804635;5763;5832.387599302055;5835.708214589254;5866.424189132484;3674.3018826422895;3923.2741942740686;3940.271049855786;3973.205358928226;3976.6619837313865;5783.971080144573;5921.763129474816;6018.307894592134;6108.3280839107665;6148.907766383508;3339.7328213742844;3460.8615655374006;3977.244323578511;3996.5603783583783;4000.179963107556;4040.040580318543;4045.664930572904;4047.332190188586;4069.2675318442966;4070.3283958147167;4682.601978831769;4719.150552900443;4724.740139292372;4750.954789946206;4777.3072904429355;5508;5646.548276137982;5835.299764028344;5851.853703657443;5938.910916336258;1464.3995961655883;1466.8826493880676;1474.5281509916938;1486.7398205314028;1487.1820498725874;888.5201990924828;905.6151135767273;936.4174545018897;963.816874793787;970.6263088710897;5864.120381942743;5874.941250587525;5929.851753706192;5933.970330148321;5972.671503067373;6018.879622407528;6223.190985171989;6489.197993484787;6530.738770449224;6546.541742078009;4619.445666520039;5608.327000759853;5613.719314034363;5627.184058311522;5924;3453.4114307418367;3482.338355712457;3483.860645574199;3526.2418580005424;3543.3090547343536;6237.44063984004;6428.742850285937;6656.933430665728;6891.3797758202;6905.205901321312;6670.533062685662;6755.2906944771075;6804.890483949991;6808.659567021728;6838.179418469816;5400.891982160335;6749.93250067493;6942.687579058955;6969.686364113627;6973.825654358681;6352.555321127476;6597.130248341987;6611.471082313499;6633.336666333328;6673.1992160941;6325.525585581007;6466.967665161644;6482.282195218177;6559.901601475886;6587.440067594308;5777.566682498746;5979.790707325223;6216.502679785697;6987.441004719509;7005.22942476317;3548.2903419315726;3610.4042832932273;4150.294449943577;4249.405083288404;4260.957390426056;6369.14016607752;6623.205215374186;6637.780676371859;6650.866982660223;6667.966465197902;5800.738966746591;6298.433141017331;6383.96808015957;6420;6439.259485159079;5526.723663816873;5840.328362238397;6177.320494745484;6378.840528986812;6444.9033264502;4444.9776551392715;5386.272853164773;5566.22072909801;5617.9719101404235;6232.283287422005;4729.905401891874;5105.948940510696;5924.674142922008;5938.317093534299;5939.46544810969;5277.1292736698015;5746.482816546531;5769.798057067983;5794.783095549813;5820.563457740602 13:31:03 INFO - PROCESS | 1669 | |5;yui.html;668.15144766147;678.6064082831956;721.3760097515651;751.9661615227315;782.8082119880628;11104.27822191558;12305.815412768816;14444;14653.853461465382;14776.852231477682;14285.714285714279;14991.025583337076;15023.699526009501;15133.848661513382;15136.394544218218;682.8395327098128;902.963881444743;905.4024343933006;912.7307444303947;913.2693844924067;768.3929695540544;880.8634661627458;899.7615631857562;912.1744820937063;914.1133100892124;820.6963423533301;964.6816550538341;970.8786401699788;975.1029053271008;975.1321324021626;815.3110621524802;926.4719110107242;932.6502561539422;964.5418426247546;965.0156840023172;689.931006899306;692.9099217101794;703.2721133626671;713.0552018575409;715.2704241673516;14945.953783235178;15017.399304027935;15057.397704091823;15251.694966100727;15275.236238188158;12154.33151176685;12238.143329966906;12376.504939802397;12404.875951240552;12576.496940122384;10011.199104071657;10208.948955255251;10256.94871525645;10325.122364598943;10337.844932326021;3381.881634142818;3478.7043101336417;3492.912677183065;3559.3059353426092;3649.9817500912327;74359;76337.23662763412;76529.23470765277;76584.61707691482;80630;9300.767480813034;9390.718278451657;9515.666951656658;9598.66404675833;9601.855972160423;20430.69353959692;20701.275455359293;20740.688889666668;20766.06552705132;20776.58446831055;7519.924800752032;7582.886256706154;7594.620268986529;7625.504342217849;7766.106897706723;4132.049628585442;4142.831693751121;4146.709730318909;4182.811773470201;4299.011227417712;3510.666486683761;3590.1024743814046;3648.908777280589;3675.3751862183494;3681.5214022176947;661.1570247933922;665.2449469851753;672.0456951129408;674.5986138247735;675.3381685947799;484.65991533410147;521.2832355511172;537.7069497124077;543.5298466826167;562.0389134579832;7835.804104897423;8199.262066414052;8285.130061343472;8380.20388063133;8405.201505856934;596.397638385234;605.8455093951014;610.7770663707804;631.2456614345813;641.4515589171285;4024.71209213049;4149.107941792529;4316.935245971313;4341.717788343748;4386.956130438654;98872.50563747279;105053.47473262738;105732;105819.94180058254;106823;85867.14132858592;86862;87320;87902.56048719716;88007.11992880117;76885;77098.61450692822;77176.61411693018;77521.22478775252;77600.83598746023;8317.334613230805;8438.789030274293;8488.236058754495;8597.785055373666;8599.312055035458;44581.33128003018;46972.060558789315;47518.287225690954;47544.2868356968;48841.511584883694;66865;69529;69577.95633065407;69796.6510167446;72031.63984180045;11058.944705276423;11154.72113197144;11183.105351571692;11390.544378224778;11524;12059.016003331799;12090.335031573348;12218.694532636755;12406.131570789958;12859.035572331926;11271.774564508827;11396.601118960796;11442.313461192354;11493.2529385593;11725;11533.826992595286;11588.826167607323;11603.593874214363;11879.940600297288;12075.51697932108;9496;11241;11287.887121128684;11399.20205585601;11579.826302605636;8311.958440208002;8893.599788009426;9151;9683.031696830261;10005.499725013866;9723.367981081206;9928.602855885683;9979.900200998187;10099.899001009895;10107.494625268855 13:31:03 INFO - PROCESS | 1669 | __end_tp_report 13:31:03 INFO - PROCESS | 1669 | __start_cc_report 13:31:03 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,422 13:31:03 INFO - PROCESS | 1669 | __end_cc_report 13:31:03 INFO - PROCESS | 1669 | __startTimestamp1461702663561__endTimestamp 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | ------- Summary: start ------- 13:31:03 INFO - PROCESS | 1669 | Number of tests: 6 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5756.49 Median:5978.22 stddev:654.65 (11.0%) stddev-sans-first:630.93 13:31:03 INFO - PROCESS | 1669 | Values: 3440.0 3487.0 3494.1 3512.8 3544.9 5720.2 5720.5 5728.3 5752.6 5786.1 5742.4 5762.4 5794.3 5796.3 5812.8 5180.1 5188.8 5260.9 5267.6 5286.4 5101.7 5168.4 5251.6 5311.5 5344.2 4684.6 5257.7 5307.3 5312.5 5318.8 5285.3 5295.8 5307.6 5323.7 5328.7 5023.6 5098.3 5158.3 5244.6 5244.8 5005.2 5558.3 5560.1 5561.0 5578.9 5078.4 6170.8 6195.7 6240.6 6275.1 6160.4 6178.2 6247.8 6262.2 6263.9 5918.1 5946.0 5978.2 5979.1 5999.3 5305.9 6342.6 6362.0 6366.3 6383.4 6073.3 6168.0 6200.8 6243.6 6251.2 6262.7 6333.4 6340.0 6353.7 6367.9 6104.4 6284.7 6292.9 6305.2 6326.3 5626.9 5949.0 6279.3 6285.7 6288.5 4881.1 4901.1 4920.6 4934.5 4945.3 3746.4 4371.8 4461.5 4463.9 4481.9 5600.7 5691.8 5812.3 5815.9 5863.3 6269.0 6279.7 6292.8 6296.7 6331.1 5669.7 5782.8 5842.7 5846.1 5891.6 5440.3 5443.4 5460.3 5479.3 5522.9 6408.5 6419.2 6428.8 6431.6 6434.8 6172.6 6233.4 6270.2 6291.9 6320.4 6335.1 6339.7 6353.0 6354.5 6367.0 6188.0 6226.0 6229.6 6231.5 6234.4 6191.1 6227.1 6242.7 6297.8 6311.6 5934.3 6116.8 6132.2 6153.2 6160.7 6075.4 6115.7 6137.2 6172.4 6179.0 4941.7 5866.5 5896.6 5944.6 6012.2 6124.2 6132.8 6137.9 6142.4 6151.2 5871.5 6041.1 6058.2 6068.9 6071.2 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:63444.11 Median:36094.55 stddev:139078.89 (385.3%) stddev-sans-first:139388.81 13:31:03 INFO - PROCESS | 1669 | Values: 521.2 538.0 578.9 590.0 591.6 36805.6 38815.6 39781.6 39835.0 40651.8 40826.8 41529.8 41583.6 41668.4 41731.6 9503.9 9670.5 9701.0 9769.8 9821.1 9673.0 9764.9 9770.2 9855.4 9938.6 9041.8 9222.3 9792.0 9846.6 9902.8 8504.9 9723.0 9800.9 9804.5 9821.6 9600.5 9618.7 9711.7 9734.1 9790.1 39130.4 39454.0 39701.8 39704.0 39727.0 2786.2 2801.8 3050.1 3130.0 3317.6 27050.9 27635.4 27641.3 27981.2 28038.4 2713.7 2719.7 2731.6 2841.8 2856.8 432509.0 435358.0 439885.0 440676.0 441802.0 34072.7 35457.0 35506.0 35631.6 35721.1 35584.1 35682.3 35689.8 36003.8 36185.3 15562.6 15939.1 15972.2 15987.4 16042.4 13186.8 13262.3 13332.0 13385.7 13398.7 8036.1 8233.5 8293.9 8365.0 8391.9 3380.2 3405.6 3420.8 3435.3 3463.1 1003.2 1009.1 1026.9 1096.6 1111.2 25878.6 30351.5 30467.1 30531.7 31015.7 1039.7 1080.3 1138.8 1144.2 1187.8 14069.8 14800.9 14835.6 14964.9 15148.4 420288.0 541331.0 543952.0 544765.1 551046.0 56210.4 57172.0 57529.0 58259.7 58645.0 1502.6 1502.7 1516.3 1525.7 1541.8 28882.0 29188.9 29259.4 29395.4 29849.9 50307.0 51666.0 51864.5 52105.0 52254.7 575149.0 583016.0 594658.0 595387.0 596443.0 27013.2 27050.7 27282.5 27352.0 27380.0 45574.0 45945.5 46027.5 47017.1 47675.8 37661.2 38158.2 38159.6 38446.8 38842.6 34843.5 35960.1 38144.0 38828.8 38830.2 34724.8 35208.3 35293.6 35363.5 35587.6 23706.2 24163.9 24512.3 30962.7 31550.4 23780.2 23992.4 24138.9 24327.6 24614.8 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:34970.21 Median:12081.51 stddev:99510.04 (823.7%) stddev-sans-first:99755.21 13:31:03 INFO - PROCESS | 1669 | Values: 331.7 397.0 401.2 412.1 412.2 18991.7 20670.0 20982.5 21006.1 21449.8 20107.6 20480.9 20727.9 21195.2 21290.4 5343.7 5919.8 6124.0 6137.0 6140.4 5901.2 6065.7 6069.3 6118.3 6128.1 5997.9 6050.3 6054.7 6096.8 6100.1 5903.4 5906.1 5913.9 5987.1 6020.6 5205.9 5249.7 5250.8 5274.7 5275.2 25198.6 26065.2 26332.7 26381.3 26400.6 2140.9 2186.4 2244.5 2262.4 2286.9 14132.1 14589.6 14591.4 14857.6 14928.7 1725.8 1754.8 1774.5 1796.0 1818.0 64132.0 111470.0 115964.4 115998.0 119514.0 11107.2 11222.4 11550.9 11662.1 11717.6 12977.1 13905.8 14604.6 14635.4 14704.0 8950.0 9090.7 9117.7 9145.5 9165.6 9137.8 9167.3 9238.4 9387.7 9398.4 5715.3 6976.5 7015.4 7021.0 7022.4 1923.2 1958.4 1965.8 1970.7 1977.4 897.9 975.2 980.9 987.9 1012.7 19820.4 20512.4 21426.4 21723.5 21976.7 975.4 1008.4 1035.9 1071.6 1080.7 9930.4 10083.9 10762.6 11288.4 11466.3 530078.0 592097.0 599673.0 627602.0 631357.0 104454.5 110043.0 135908.0 137766.0 141432.0 1058.5 1071.9 1123.6 1124.0 1132.7 17808.5 18116.4 18253.2 18421.6 18425.9 51012.0 52841.2 54664.7 54838.7 58237.4 82830.0 83166.0 83372.0 84060.9 87014.0 7957.8 8033.4 8092.2 8118.3 8337.8 9101.0 11858.3 12423.8 12711.4 12759.3 10810.9 10851.0 10907.7 11095.4 11159.8 9176.9 10710.9 10731.7 10944.3 11187.8 9306.9 11228.0 11319.4 11324.4 11330.3 8095.3 8179.5 8262.8 8298.8 8304.4 10186.4 11077.3 11135.9 11160.6 11386.0 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5654.58 Median:4927.57 stddev:3130.16 (63.5%) stddev-sans-first:3123.99 13:31:03 INFO - PROCESS | 1669 | Values: 1583.4 1601.8 1846.8 1939.7 1959.2 7073.4 7431.6 8517.0 8579.9 8925.3 8500.5 8507.6 8548.6 8648.9 8653.1 2400.7 2436.9 2437.6 2439.4 2441.8 2376.8 2396.5 2418.6 2441.2 2446.5 2434.0 2439.3 2445.2 2465.2 2479.8 2395.4 2421.3 2421.8 2425.2 2436.5 2384.7 2421.9 2425.6 2438.5 2445.8 7799.9 8433.5 8726.2 8792.3 8829.6 5305.1 5390.7 5508.3 5543.3 5551.8 3530.4 3548.2 3573.5 3630.9 3647.5 1025.0 1034.7 1039.5 1048.5 1075.0 11548.2 11693.4 11703.2 11705.8 11755.9 4793.7 4821.2 4930.4 4933.6 4964.9 9865.0 9952.5 10064.6 10077.5 10094.3 5385.2 5401.7 5411.6 5474.2 5500.6 4470.2 4843.7 4959.1 4991.2 5005.6 3884.1 3939.0 3996.6 4116.9 4135.9 1334.6 1347.5 1352.4 1358.7 1362.5 909.5 925.3 926.5 926.7 936.7 5805.4 5834.8 5865.2 5999.4 6012.2 415.7 443.6 444.4 458.0 463.0 3889.7 3901.7 3942.7 4003.9 4184.5 10506.1 10747.2 10750.0 10757.6 10941.7 10495.3 10638.6 10649.5 10689.5 10716.5 7744.7 7857.0 8100.1 8115.7 8144.0 4959.5 5888.7 5901.9 6020.9 6088.2 7655.9 9738.8 9848.5 9850.1 9990.9 8591.4 9500.8 9769.2 9791.3 10214.3 6648.4 6902.6 7312.0 7506.8 7600.2 7844.2 8223.8 8333.5 8360.1 8383.9 6091.4 7403.6 7422.7 7487.3 7510.2 7293.3 7315.6 7361.3 7397.6 7463.1 6917.8 6926.3 6931.1 6968.2 7005.0 4809.9 4834.5 4855.9 4909.4 4924.7 4868.9 4875.4 4895.5 4943.8 5101.3 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4900.50 Median:5555.64 stddev:1626.00 (29.3%) stddev-sans-first:1593.01 13:31:03 INFO - PROCESS | 1669 | Values: 271.8 311.2 313.6 318.1 320.2 5455.7 5537.2 5565.6 5603.9 5681.6 4601.9 4886.9 5212.5 5511.6 5545.7 3697.6 4062.9 4119.4 4165.9 4270.9 3873.9 4096.8 4250.7 4256.2 4261.7 3466.8 3777.8 4050.7 4172.8 4216.2 3674.0 3881.4 4078.1 4141.4 4141.5 3012.7 3034.0 3059.4 3127.2 3184.9 4305.9 4744.7 4986.8 5368.0 5389.0 4097.1 4280.8 4769.8 4788.7 4803.8 5718.7 5763.0 5832.4 5835.7 5866.4 3674.3 3923.3 3940.3 3973.2 3976.7 5784.0 5921.8 6018.3 6108.3 6148.9 3339.7 3460.9 3977.2 3996.6 4000.2 4040.0 4045.7 4047.3 4069.3 4070.3 4682.6 4719.2 4724.7 4751.0 4777.3 5508.0 5646.5 5835.3 5851.9 5938.9 1464.4 1466.9 1474.5 1486.7 1487.2 888.5 905.6 936.4 963.8 970.6 5864.1 5874.9 5929.9 5934.0 5972.7 6018.9 6223.2 6489.2 6530.7 6546.5 4619.4 5608.3 5613.7 5627.2 5924.0 3453.4 3482.3 3483.9 3526.2 3543.3 6237.4 6428.7 6656.9 6891.4 6905.2 6670.5 6755.3 6804.9 6808.7 6838.2 5400.9 6749.9 6942.7 6969.7 6973.8 6352.6 6597.1 6611.5 6633.3 6673.2 6325.5 6467.0 6482.3 6559.9 6587.4 5777.6 5979.8 6216.5 6987.4 7005.2 3548.3 3610.4 4150.3 4249.4 4261.0 6369.1 6623.2 6637.8 6650.9 6668.0 5800.7 6298.4 6384.0 6420.0 6439.3 5526.7 5840.3 6177.3 6378.8 6444.9 4445.0 5386.3 5566.2 5618.0 6232.3 4729.9 5105.9 5924.7 5938.3 5939.5 5277.1 5746.5 5769.8 5794.8 5820.6 13:31:03 INFO - PROCESS | 1669 | 13:31:03 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18954.62 Median:579.22 stddev:27494.00 (4746.7%) stddev-sans-first:27536.84 13:31:03 INFO - PROCESS | 1669 | Values: 668.2 678.6 721.4 752.0 782.8 11104.3 12305.8 14444.0 14653.9 14776.9 14285.7 14991.0 15023.7 15133.8 15136.4 682.8 903.0 905.4 912.7 913.3 768.4 880.9 899.8 912.2 914.1 820.7 964.7 970.9 975.1 975.1 815.3 926.5 932.7 964.5 965.0 689.9 692.9 703.3 713.1 715.3 14946.0 15017.4 15057.4 15251.7 15275.2 12154.3 12238.1 12376.5 12404.9 12576.5 10011.2 10208.9 10256.9 10325.1 10337.8 3381.9 3478.7 3492.9 3559.3 3650.0 74359.0 76337.2 76529.2 76584.6 80630.0 9300.8 9390.7 9515.7 9598.7 9601.9 20430.7 20701.3 20740.7 20766.1 20776.6 7519.9 7582.9 7594.6 7625.5 7766.1 4132.0 4142.8 4146.7 4182.8 4299.0 3510.7 3590.1 3648.9 3675.4 3681.5 661.2 665.2 672.0 674.6 675.3 484.7 521.3 537.7 543.5 562.0 7835.8 8199.3 8285.1 8380.2 8405.2 596.4 605.8 610.8 631.2 641.5 4024.7 4149.1 4316.9 4341.7 4387.0 98872.5 105053.5 105732.0 105819.9 106823.0 85867.1 86862.0 87320.0 87902.6 88007.1 76885.0 77098.6 77176.6 77521.2 77600.8 8317.3 8438.8 8488.2 8597.8 8599.3 44581.3 46972.1 47518.3 47544.3 48841.5 66865.0 69529.0 69578.0 69796.7 72031.6 11058.9 11154.7 11183.1 11390.5 11524.0 12059.0 12090.3 12218.7 12406.1 12859.0 11271.8 11396.6 11442.3 11493.3 11725.0 11533.8 11588.8 11603.6 11879.9 12075.5 9496.0 11241.0 11287.9 11399.2 11579.8 8312.0 8893.6 9151.0 9683.0 10005.5 9723.4 9928.6 9979.9 10099.9 10107.5 13:31:03 INFO - PROCESS | 1669 | -------- Summary: end -------- 13:31:03 INFO - PROCESS | 1669 | 13:31:04 INFO - TEST-INFO | 1669: exit 0 13:31:04 INFO - TEST-OK | dromaeo_css | took 1110515ms 13:31:04 INFO - TEST-START | kraken 13:31:04 INFO - operating with platform_type : mac_ 13:31:04 INFO - Initialising browser for kraken test... 13:31:04 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpuaZuD5/profile http://localhost:49270/getInfo.html) 13:31:07 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 13:31:07 INFO - PROCESS | 1677 | colorDepth:24 13:31:07 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 13:31:07 INFO - PROCESS | 1677 | __metrics 13:31:07 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:31:07 INFO - TEST-INFO | 1677: exit 0 13:31:07 INFO - Browser initialized. 13:31:07 INFO - Running cycle 1/1 for kraken test... 13:31:08 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpuaZuD5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:31:19 INFO - PROCESS | 1680 | RSS: Main: 221437952 13:31:19 INFO - PROCESS | 1680 | 13:31:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 13:31:23 INFO - PROCESS | 1680 | RSS: Main: 233439232 13:31:23 INFO - PROCESS | 1680 | 13:31:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 13:31:26 INFO - PROCESS | 1680 | RSS: Main: 242667520 13:31:26 INFO - PROCESS | 1680 | 13:31:30 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 13:31:30 INFO - PROCESS | 1680 | RSS: Main: 246296576 13:31:30 INFO - PROCESS | 1680 | 13:31:32 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 13:31:33 INFO - PROCESS | 1680 | RSS: Main: 246390784 13:31:33 INFO - PROCESS | 1680 | 13:31:35 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:31:36 INFO - PROCESS | 1680 | RSS: Main: 246562816 13:31:36 INFO - PROCESS | 1680 | 13:31:39 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 13:31:39 INFO - PROCESS | 1680 | RSS: Main: 246808576 13:31:39 INFO - PROCESS | 1680 | 13:31:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 13:31:43 INFO - PROCESS | 1680 | RSS: Main: 248123392 13:31:43 INFO - PROCESS | 1680 | 13:31:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 13:31:46 INFO - PROCESS | 1680 | RSS: Main: 248262656 13:31:46 INFO - PROCESS | 1680 | 13:31:48 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:31:48 INFO - PROCESS | 1680 | RSS: Main: 248188928 13:31:48 INFO - PROCESS | 1680 | 13:31:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:31:51 INFO - PROCESS | 1680 | RSS: Main: 247812096 13:31:51 INFO - PROCESS | 1680 | 13:31:53 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:31:54 INFO - PROCESS | 1680 | RSS: Main: 247771136 13:31:54 INFO - PROCESS | 1680 | 13:31:56 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:31:57 INFO - PROCESS | 1680 | RSS: Main: 248008704 13:31:57 INFO - PROCESS | 1680 | 13:32:00 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:32:01 INFO - PROCESS | 1680 | RSS: Main: 248016896 13:32:01 INFO - PROCESS | 1680 | 13:32:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 13:32:03 INFO - PROCESS | 1680 | RSS: Main: 249753600 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | __start_tp_report 13:32:03 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 13:32:03 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 13:32:03 INFO - PROCESS | 1680 | |i|pagename|runs| 13:32:03 INFO - PROCESS | 1680 | |0;ai-astar;118;115;117;118;117;157;105;112;159;173 13:32:03 INFO - PROCESS | 1680 | |1;audio-beat-detection;151;167;179;156;124;140;124;157;157;123 13:32:03 INFO - PROCESS | 1680 | |2;audio-dft;192;200;198;197;196;194;192;194;201;193 13:32:03 INFO - PROCESS | 1680 | |3;audio-fft;60;73;75;148;66;77;83;90;157;57 13:32:03 INFO - PROCESS | 1680 | |4;audio-oscillator;78;84;122;77;80;81;116;81;80;117 13:32:03 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;90;86;131;131;93;85;90;86;89;87 13:32:03 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;95;95;115;96;95;96;97;95;96 13:32:03 INFO - PROCESS | 1680 | |7;imaging-desaturate;92;94;92;92;101;90;90;94;91;91 13:32:03 INFO - PROCESS | 1680 | |8;json-parse-financial;60;66;65;65;66;67;62;97;101;92 13:32:03 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;67;67;71;69;78;74;67;68;68;67 13:32:03 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;85;81;83;83;83;82;79;84;82;85 13:32:03 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;138;136;134;135;137;134;137;146;172;153 13:32:03 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;171;168;160;173;174;171;171;175;177;170 13:32:03 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;72;65;70;69;64;70;66;71;81;101 13:32:03 INFO - PROCESS | 1680 | __end_tp_report 13:32:03 INFO - PROCESS | 1680 | __start_cc_report 13:32:03 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1091 13:32:03 INFO - PROCESS | 1680 | __end_cc_report 13:32:03 INFO - PROCESS | 1680 | __startTimestamp1461702722998__endTimestamp 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | ------- Summary: start ------- 13:32:03 INFO - PROCESS | 1680 | Number of tests: 14 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:129.10 Median:118.00 stddev:24.06 (20.4%) stddev-sans-first:25.18 13:32:03 INFO - PROCESS | 1680 | Values: 118.0 115.0 117.0 118.0 117.0 157.0 105.0 112.0 159.0 173.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:156.50 stddev:19.43 (12.4%) stddev-sans-first:20.57 13:32:03 INFO - PROCESS | 1680 | Values: 151.0 167.0 179.0 156.0 124.0 140.0 124.0 157.0 157.0 123.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:195.70 Median:196.50 stddev:3.23 (1.6%) stddev-sans-first:3.14 13:32:03 INFO - PROCESS | 1680 | Values: 192.0 200.0 198.0 197.0 196.0 194.0 192.0 194.0 201.0 193.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:88.60 Median:74.00 stddev:35.17 (47.5%) stddev-sans-first:35.74 13:32:03 INFO - PROCESS | 1680 | Values: 60.0 73.0 75.0 148.0 66.0 77.0 83.0 90.0 157.0 57.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:80.00 stddev:18.60 (23.3%) stddev-sans-first:19.07 13:32:03 INFO - PROCESS | 1680 | Values: 78.0 84.0 122.0 77.0 80.0 81.0 116.0 81.0 80.0 117.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:88.00 stddev:18.18 (20.7%) stddev-sans-first:19.12 13:32:03 INFO - PROCESS | 1680 | Values: 90.0 86.0 131.0 131.0 93.0 85.0 90.0 86.0 89.0 87.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:95.50 stddev:6.19 (6.5%) stddev-sans-first:6.50 13:32:03 INFO - PROCESS | 1680 | Values: 95.0 95.0 95.0 115.0 96.0 95.0 96.0 97.0 95.0 96.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:92.00 stddev:3.23 (3.5%) stddev-sans-first:3.42 13:32:03 INFO - PROCESS | 1680 | Values: 92.0 94.0 92.0 92.0 101.0 90.0 90.0 94.0 91.0 91.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:74.10 Median:66.00 stddev:15.85 (24.0%) stddev-sans-first:15.97 13:32:03 INFO - PROCESS | 1680 | Values: 60.0 66.0 65.0 65.0 66.0 67.0 62.0 97.0 101.0 92.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:68.50 stddev:3.72 (5.4%) stddev-sans-first:3.82 13:32:03 INFO - PROCESS | 1680 | Values: 67.0 67.0 71.0 69.0 78.0 74.0 67.0 68.0 68.0 67.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.83 (2.2%) stddev-sans-first:1.74 13:32:03 INFO - PROCESS | 1680 | Values: 85.0 81.0 83.0 83.0 83.0 82.0 79.0 84.0 82.0 85.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:137.50 stddev:12.09 (8.8%) stddev-sans-first:12.73 13:32:03 INFO - PROCESS | 1680 | Values: 138.0 136.0 134.0 135.0 137.0 134.0 137.0 146.0 172.0 153.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:172.00 stddev:4.67 (2.7%) stddev-sans-first:4.95 13:32:03 INFO - PROCESS | 1680 | Values: 171.0 168.0 160.0 173.0 174.0 171.0 171.0 175.0 177.0 170.0 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:70.00 stddev:10.96 (15.7%) stddev-sans-first:11.62 13:32:03 INFO - PROCESS | 1680 | Values: 72.0 65.0 70.0 69.0 64.0 70.0 66.0 71.0 81.0 101.0 13:32:03 INFO - PROCESS | 1680 | -------- Summary: end -------- 13:32:03 INFO - PROCESS | 1680 | 13:32:03 INFO - TEST-INFO | 1680: exit 0 13:32:03 INFO - TEST-OK | kraken | took 59725ms 13:32:03 INFO - SUITE-END | took 1170s 13:32:04 INFO - Completed test suite (00:19:30) 13:32:04 INFO - javascript benchmark 13:32:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3440.0, 3486.9651303486967, 3494.091536200587, 3512.806795626241, 3544.8582056717732, 5720.170575266584, 5720.485156335929, 5728.255326807514, 5752.626079304842, 5786.103154011124, 5742.425757424256, 5762.423757624236, 5794.333651630064, 5796.275465566804, 5812.796552120677, 5180.1452760294505, 5188.766505507242, 5260.894782104375, 5267.578593712513, 5286.392064912527, 5101.7194054327, 5168.379794424674, 5251.606129540281, 5311.468853114677, 5344.198370244456, 4684.625229981611, 5257.684538927677, 5307.336582927134, 5312.4687531246955, 5318.840434786963, 5285.2600635911, 5295.8146464873735, 5307.575393968512, 5323.707196104213, 5328.653637513588, 5023.6232282578585, 5098.286239926413, 5158.277841102249, 5244.580433565306, 5244.790208391698, 5005.2492126181, 5558.277423934863, 5560.138178582326, 5560.972195139039, 5578.944210557924, 5078.390593128811, 6170.84572885677, 6195.659238741868, 6240.625562466266, 6275.121482992385, 6160.414760597736, 6178.227721534809, 6247.781327653511, 6262.248530176363, 6263.874722505524, 5918.141869428909, 5945.970270148621, 5978.2228310319815, 5979.1031345298115, 5999.340072592011, 5305.893882122413, 6342.5560210785725, 6361.968190159112, 6366.331535188739, 6383.3616638336725, 6073.331933487313, 6168.013117901204, 6200.81397558074, 6243.562950593415, 6251.187345645128, 6262.655553944485, 6333.398327158912, 6339.985602303609, 6353.650549219838, 6367.936320636827, 6104.3590423005835, 6284.748610055546, 6292.937070629327, 6305.211848518935, 6326.2724786649205, 5626.874625074919, 5949.0, 6279.277883043508, 6285.717142728588, 6288.465480434123, 4881.121398148367, 4901.117798796251, 4920.6309526785635, 4934.5312195341385, 4945.2582112683385, 3746.3818469952153, 4371.75955322454, 4461.486929003141, 4463.888402789957, 4481.865544033684, 5600.719964001783, 5691.8292451226525, 5812.3025236971835, 5815.854603634943, 5863.296404431496, 6268.968655156695, 6279.717412716347, 6292.811215663446, 6296.748130074746, 6331.081993110973, 5669.744861481243, 5782.797602083907, 5842.707864606753, 5846.123081537803, 5891.617044892068, 5440.319960004999, 5443.401225865152, 5460.262864513319, 5479.260299859548, 5522.9447705523235, 6408.487321014308, 6419.2296924369375, 6428.839279018062, 6431.6462594556815, 6434.774782882764, 6172.567920245539, 6233.407826256498, 6270.184875966095, 6291.937080629135, 6320.4311611955145, 6335.1447554579545, 6339.714712837841, 6353.0, 6354.491640668828, 6366.96816515933, 6188.0, 6226.0, 6229.595076320024, 6231.53263505248, 6234.438900498795, 6191.133241346125, 6227.06594010902, 6242.656653883898, 6297.842553936188, 6311.621302721851, 5934.258217722785, 6116.785912493041, 6132.202813634378, 6153.169322141453, 6160.722767475385, 6075.362086980804, 6115.694215289307, 6137.202163718688, 6172.4136207060255, 6178.969105154445, 4941.678790878582, 5866.472017518273, 5896.64620122794, 5944.5838791284195, 6012.248468941382, 6124.173236613, 6132.816015519542, 6137.907931380943, 6142.355052719578, 6151.169592105009, 5871.500922421471, 6041.093835924647, 6058.212432383762, 6068.939310607014, 6071.241094863142], "value": 5715.798493066797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.1895502493624, 537.9704116273605, 578.9160571717099, 589.9763909616817, 591.5829340315076, 36805.631943680586, 38815.611843881554, 39781.60218397815, 39835.0, 40651.79674101625, 40826.7958660207, 41529.792351038275, 41583.58416415835, 41668.374974375336, 41731.58268417315, 9503.857442138373, 9670.516474176264, 9700.951495242514, 9769.804603907918, 9821.066998635153, 9673.0, 9764.853527197098, 9770.218382529381, 9855.359401638907, 9938.552765125587, 9041.773955651095, 9222.262219022463, 9791.951040244789, 9846.55690493926, 9902.752431189241, 8504.914950850536, 9723.02769723029, 9800.872899616494, 9804.509774511282, 9821.558029888602, 9600.471974041426, 9618.663346782829, 9711.708648740549, 9734.123928846368, 9790.118889299998, 39130.413043804365, 39454.02729863495, 39701.808945731675, 39704.0, 39727.0, 2786.233785708926, 2801.7758579313604, 3050.145959131447, 3129.984350078235, 3317.552130462405, 27050.864745676343, 27635.447291054068, 27641.308967275978, 27981.160565183076, 28038.439231215467, 2713.7150599187, 2719.728027197284, 2731.6312297839936, 2841.7597889998347, 2856.8000239983207, 432509.0, 435358.0, 439885.0, 440676.0, 441802.0, 34072.659273407444, 35457.0, 35506.0, 35631.643683562834, 35721.1069723259, 35584.11039723976, 35682.28635427277, 35689.82155089208, 36003.81998089993, 36185.276294473966, 15562.610934726496, 15939.123348215959, 15972.201389930457, 15987.36050557988, 16042.358305667876, 13186.802197967038, 13262.336883155804, 13332.00009999254, 13385.66535836612, 13398.732025359439, 8036.075851277178, 8233.50598964064, 8293.875591866126, 8365.0, 8391.874121888177, 3380.154961259685, 3405.6424075472214, 3420.8369154487596, 3435.347284016029, 3463.0995941055508, 1003.2375394699934, 1009.1422291052693, 1026.9180009462477, 1096.6216655253925, 1111.2110401614943, 25878.6118208227, 30351.544726829114, 30467.085987420414, 30531.694683052887, 31015.689843101733, 1039.73486760877, 1080.2600218850114, 1138.8405623212757, 1144.2162118948547, 1187.7624475105013, 14069.788953165711, 14800.851991480164, 14835.554933352018, 14964.850351496563, 15148.394064237307, 420288.0, 541331.0, 543952.0, 544765.1046979039, 551046.0, 56210.43789562134, 57172.0, 57529.0, 58259.70870145707, 58645.0, 1502.6168327076525, 1502.7295086884467, 1516.3252352703162, 1525.6872341170033, 1541.7610270408104, 28882.0, 29188.854055729586, 29259.41481170407, 29395.412091758473, 29849.850750746107, 50307.0, 51666.0, 51864.48135518596, 52105.0, 52254.738726306125, 575149.0, 583016.0, 594658.0, 595387.0, 596443.0, 27013.189604311116, 27050.72949270482, 27282.454350912474, 27352.0, 27380.0, 45574.0, 45945.54054459546, 46027.53972460233, 47017.05965880595, 47675.761621191676, 37661.246775064894, 38158.236835262585, 38159.618403815606, 38446.80776596099, 38842.6115738839, 34843.477347839296, 35960.100997475274, 38144.0, 38828.80585597054, 38830.223395531364, 34724.82637586796, 35208.295834083685, 35293.64706352904, 35363.46954795629, 35587.644123558435, 23706.170284039978, 24163.879180603984, 24512.264632061066, 30962.723305673102, 31550.36899262048, 23780.16769413062, 23992.40018999539, 24138.87930560336, 24327.635085474085, 24614.753852461246], "value": 18133.925532140747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.7346123101519, 396.9880903572892, 401.2296390929415, 412.0872267926544, 412.20855956563395, 18991.715124273145, 20670.0, 20982.475438114056, 21006.109450149484, 21449.785502144994, 20107.592468527208, 20480.89759551204, 20727.896360518214, 21195.152193912225, 21290.361289161312, 5343.706096164709, 5919.822405327836, 6124.0, 6136.969315153418, 6140.447359737623, 5901.17383566301, 6065.696715164224, 6069.302030266532, 6118.3269840317525, 6128.142060111588, 5997.8800423991715, 6050.304215015286, 6054.727537260834, 6096.847578810521, 6100.084987251926, 5903.3506314305605, 5906.055031194988, 5913.940860591382, 5987.1318658794235, 6020.608660437079, 5205.8958820823755, 5249.737513124329, 5250.789968401259, 5274.683518988873, 5275.155975044013, 25198.622020669703, 26065.218043458728, 26332.73667263322, 26381.3404664883, 26400.60399094015, 2140.871547707127, 2186.35502526755, 2244.539869326784, 2262.3552287597954, 2286.874221917794, 14132.081414708011, 14589.6352591185, 14591.41634334636, 14857.554273371708, 14928.701425971529, 1725.8187890271465, 1754.7894252689632, 1774.5297496163528, 1796.0301437223884, 1818.0, 64132.0, 111470.0, 115964.42017789857, 115998.0, 119514.0, 11107.222494425474, 11222.438878056064, 11550.88449115515, 11662.12534059932, 11717.648470545897, 12977.091603587658, 13905.79141312881, 14604.634884127981, 14635.414583416545, 14704.0, 8950.015498295183, 9090.727278181665, 9117.726468205965, 9145.496997665192, 9165.633374664938, 9137.77155571116, 9167.26661867049, 9238.353315267866, 9387.671431499864, 9398.389104708172, 5715.2855893013375, 6976.5465244758925, 7015.438764898796, 7021.0, 7022.403095736818, 1923.1922592511178, 1958.4418440744319, 1965.7641083070123, 1970.664986952193, 1977.4166620846884, 897.8967418746797, 975.2149519636619, 980.8724865767547, 987.8666380038603, 1012.711377257478, 19820.40538783839, 20512.38462846117, 21426.357209283746, 21723.45691357697, 21976.670349944765, 975.3660120921458, 1008.3848852200152, 1035.8756949166147, 1071.5713714514256, 1080.670395529371, 9930.404175749478, 10083.899161008296, 10762.569497220024, 11288.379139147426, 11466.2546934449, 530078.0, 592097.0, 599673.0, 627602.0, 631357.0, 104454.47772761088, 110043.0, 135908.0, 137766.0, 141432.0, 1058.5448257249457, 1071.8660167479065, 1123.6292023632182, 1123.9719007024726, 1132.7054965708976, 17808.46574602764, 18116.365927192484, 18253.178606962454, 18421.631567368844, 18425.907870460564, 51012.0, 52841.20738189007, 54664.726676366365, 54838.72580637071, 58237.41762582489, 82830.0, 83166.0, 83372.0, 84060.89847753855, 87014.0, 7957.801054973672, 8033.437659363788, 8092.190780921861, 8118.309943654619, 8337.83324333522, 9101.0, 11858.288502689866, 12423.813642795547, 12711.364431778558, 12759.298238596966, 10810.945945270223, 10851.0, 10907.696063046966, 11095.445227738743, 11159.832602510807, 9176.944651365178, 10710.928907109226, 10731.731706707395, 10944.288621239593, 11187.832182517432, 9306.92380682705, 11228.0, 11319.434028298718, 11324.37715925632, 11330.263532870336, 8095.311898488459, 8179.468334558234, 8262.793430164294, 8298.834023319621, 8304.418690691593, 10186.439745814052, 11077.335359878432, 11135.888641113484, 11160.553577856796, 11386.032187264176], "value": 9838.71729032287, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.3903946980408, 1601.7517284820854, 1846.7968523462416, 1939.738135351728, 1959.1869374209705, 7073.398761105299, 7431.62841857907, 8517.0, 8579.940814204585, 8925.28597712183, 8500.489970601768, 8507.617157227924, 8548.615312310945, 8648.91351086489, 8653.134686531344, 2400.675908752318, 2436.878156092197, 2437.5856104462196, 2439.4389290463205, 2441.755824417553, 2376.8217383696206, 2396.532676128156, 2418.600930846408, 2441.2432146034785, 2446.5473887330813, 2433.9756602433927, 2439.316991242455, 2445.1808644104244, 2465.174166654173, 2479.82641215115, 2395.377201927494, 2421.2978236311446, 2421.7820396164343, 2425.163318655061, 2436.488337449138, 2384.6899903012495, 2421.9152329668377, 2425.641640681224, 2438.4635380216323, 2445.8410203336725, 7799.922000779976, 8433.481973244814, 8726.171013753683, 8792.252658524034, 8829.646814127427, 5305.124654432014, 5390.73046347685, 5508.338999320067, 5543.307086614173, 5551.77792888284, 3530.382183117944, 3548.2371290172478, 3573.535440392758, 3630.8547658093644, 3647.452882067711, 1024.9948750256276, 1034.7051090439188, 1039.5062345385957, 1048.4652827058255, 1075.0485820049234, 11548.249363791327, 11693.356865372401, 11703.18077734559, 11705.824412633818, 11755.882441175565, 4793.664443488992, 4821.228603423436, 4930.433000204951, 4933.629977751683, 4964.851054468371, 9865.013498650222, 9952.452615106095, 10064.647737329158, 10077.496125193711, 10094.343867648578, 5385.165299378603, 5401.675899446046, 5411.594130440233, 5474.233607294982, 5500.58745594074, 4470.150671372428, 4843.709377437365, 4959.107360675041, 4991.151504244287, 5005.649604527721, 3884.0872394987155, 3938.9955561331676, 3996.580359062257, 4116.938245926313, 4135.945333939826, 1334.6129622409392, 1347.4812197304109, 1352.4184600621827, 1358.7418390505773, 1362.4876387210188, 909.4770506958525, 925.2829057480507, 926.4765407544851, 926.7451450850936, 936.7143021378408, 5805.448482394166, 5834.766609335579, 5865.237519122486, 5999.430054144849, 6012.248468941382, 415.71315792103354, 443.5697373547654, 444.35346570739495, 458.00612670505086, 463.04150408653766, 3889.747166434173, 3901.6878649707955, 3942.704297177723, 4003.91992160161, 4184.476940382452, 10506.106980906708, 10747.247692661595, 10750.0, 10757.56969721218, 10941.726456838484, 10495.317804342692, 10638.627648032281, 10649.520771565298, 10689.465526723632, 10716.517756700967, 7744.6514906829325, 7856.978592783016, 8100.149484304181, 8115.715949941724, 8144.022717273965, 4959.528844759742, 5888.735006924784, 5901.91147132802, 6020.8795824084145, 6088.238970128734, 7655.88516172269, 9738.75653108678, 9848.507574621097, 9850.113489785954, 9990.900090999288, 8591.398602097794, 9500.762480938032, 9769.218462522838, 9791.314607977372, 10214.285000049926, 6648.404382947961, 6902.620355880475, 7311.963440182765, 7506.849863002819, 7600.239976002355, 7844.21557844211, 8223.753287401387, 8333.458325208841, 8360.12218717026, 8383.874241886255, 6091.390860913873, 7403.629818508945, 7422.703091876265, 7487.288707572772, 7510.248975102446, 7293.307135822089, 7315.597642129734, 7361.322990841707, 7397.556146631219, 7463.067116610424, 6917.827054323682, 6926.341997510229, 6931.133608298963, 6968.198657154492, 7004.964975175091, 4809.927851082307, 4834.468208497133, 4855.927161092516, 4909.410870695539, 4924.704517728947, 4868.853934381974, 4875.439324477588, 4895.485973972682, 4943.802247910044, 5101.336826212569], "value": 4511.974673923263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.8148870922778, 311.15366203925316, 313.57667149348384, 318.14260567769855, 320.2106806721431, 5455.727213639322, 5537.197106419571, 5565.610407271488, 5603.859903502404, 5681.579605098726, 4601.884952876171, 4886.8778280543, 5212.452692467284, 5511.649645836776, 5545.667259964405, 3697.6487233712755, 4062.8872986047313, 4119.443875076871, 4165.875023749277, 4270.850520231793, 3873.895939657198, 4096.77096870939, 4250.702450828443, 4256.170046840867, 4261.659067274626, 3466.8093254870973, 3777.7544459610035, 4050.736702114353, 4172.833086676529, 4216.198922204787, 3673.981630091846, 3881.437191607201, 4078.085038363175, 4141.358089496133, 4141.544430112685, 3012.728854403092, 3033.969660303391, 3059.4340047091137, 3127.1556679696355, 3184.85668144934, 4305.9354109688065, 4744.739039352834, 4986.750662466899, 5367.973160134214, 5388.973055134738, 4097.135803209532, 4280.807363668642, 4769.809207631691, 4788.712677239342, 4803.831865884677, 5718.7426565804635, 5763.0, 5832.387599302055, 5835.708214589254, 5866.424189132484, 3674.3018826422895, 3923.2741942740686, 3940.271049855786, 3973.205358928226, 3976.6619837313865, 5783.971080144573, 5921.763129474816, 6018.307894592134, 6108.3280839107665, 6148.907766383508, 3339.7328213742844, 3460.8615655374006, 3977.244323578511, 3996.5603783583783, 4000.179963107556, 4040.040580318543, 4045.664930572904, 4047.332190188586, 4069.2675318442966, 4070.3283958147167, 4682.601978831769, 4719.150552900443, 4724.740139292372, 4750.954789946206, 4777.3072904429355, 5508.0, 5646.548276137982, 5835.299764028344, 5851.853703657443, 5938.910916336258, 1464.3995961655883, 1466.8826493880676, 1474.5281509916938, 1486.7398205314028, 1487.1820498725874, 888.5201990924828, 905.6151135767273, 936.4174545018897, 963.816874793787, 970.6263088710897, 5864.120381942743, 5874.941250587525, 5929.851753706192, 5933.970330148321, 5972.671503067373, 6018.879622407528, 6223.190985171989, 6489.197993484787, 6530.738770449224, 6546.541742078009, 4619.445666520039, 5608.327000759853, 5613.719314034363, 5627.184058311522, 5924.0, 3453.4114307418367, 3482.338355712457, 3483.860645574199, 3526.2418580005424, 3543.3090547343536, 6237.44063984004, 6428.742850285937, 6656.933430665728, 6891.3797758202, 6905.205901321312, 6670.533062685662, 6755.2906944771075, 6804.890483949991, 6808.659567021728, 6838.179418469816, 5400.891982160335, 6749.93250067493, 6942.687579058955, 6969.686364113627, 6973.825654358681, 6352.555321127476, 6597.130248341987, 6611.471082313499, 6633.336666333328, 6673.1992160941, 6325.525585581007, 6466.967665161644, 6482.282195218177, 6559.901601475886, 6587.440067594308, 5777.566682498746, 5979.790707325223, 6216.502679785697, 6987.441004719509, 7005.22942476317, 3548.2903419315726, 3610.4042832932273, 4150.294449943577, 4249.405083288404, 4260.957390426056, 6369.14016607752, 6623.205215374186, 6637.780676371859, 6650.866982660223, 6667.966465197902, 5800.738966746591, 6298.433141017331, 6383.96808015957, 6420.0, 6439.259485159079, 5526.723663816873, 5840.328362238397, 6177.320494745484, 6378.840528986812, 6444.9033264502, 4444.9776551392715, 5386.272853164773, 5566.22072909801, 5617.9719101404235, 6232.283287422005, 4729.905401891874, 5105.948940510696, 5924.674142922008, 5938.317093534299, 5939.46544810969, 5277.1292736698015, 5746.482816546531, 5769.798057067983, 5794.783095549813, 5820.563457740602], "value": 4380.831464442206, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.15144766147, 678.6064082831956, 721.3760097515651, 751.9661615227315, 782.8082119880628, 11104.27822191558, 12305.815412768816, 14444.0, 14653.853461465382, 14776.852231477682, 14285.714285714279, 14991.025583337076, 15023.699526009501, 15133.848661513382, 15136.394544218218, 682.8395327098128, 902.963881444743, 905.4024343933006, 912.7307444303947, 913.2693844924067, 768.3929695540544, 880.8634661627458, 899.7615631857562, 912.1744820937063, 914.1133100892124, 820.6963423533301, 964.6816550538341, 970.8786401699788, 975.1029053271008, 975.1321324021626, 815.3110621524802, 926.4719110107242, 932.6502561539422, 964.5418426247546, 965.0156840023172, 689.931006899306, 692.9099217101794, 703.2721133626671, 713.0552018575409, 715.2704241673516, 14945.953783235178, 15017.399304027935, 15057.397704091823, 15251.694966100727, 15275.236238188158, 12154.33151176685, 12238.143329966906, 12376.504939802397, 12404.875951240552, 12576.496940122384, 10011.199104071657, 10208.948955255251, 10256.94871525645, 10325.122364598943, 10337.844932326021, 3381.881634142818, 3478.7043101336417, 3492.912677183065, 3559.3059353426092, 3649.9817500912327, 74359.0, 76337.23662763412, 76529.23470765277, 76584.61707691482, 80630.0, 9300.767480813034, 9390.718278451657, 9515.666951656658, 9598.66404675833, 9601.855972160423, 20430.69353959692, 20701.275455359293, 20740.688889666668, 20766.06552705132, 20776.58446831055, 7519.924800752032, 7582.886256706154, 7594.620268986529, 7625.504342217849, 7766.106897706723, 4132.049628585442, 4142.831693751121, 4146.709730318909, 4182.811773470201, 4299.011227417712, 3510.666486683761, 3590.1024743814046, 3648.908777280589, 3675.3751862183494, 3681.5214022176947, 661.1570247933922, 665.2449469851753, 672.0456951129408, 674.5986138247735, 675.3381685947799, 484.65991533410147, 521.2832355511172, 537.7069497124077, 543.5298466826167, 562.0389134579832, 7835.804104897423, 8199.262066414052, 8285.130061343472, 8380.20388063133, 8405.201505856934, 596.397638385234, 605.8455093951014, 610.7770663707804, 631.2456614345813, 641.4515589171285, 4024.71209213049, 4149.107941792529, 4316.935245971313, 4341.717788343748, 4386.956130438654, 98872.50563747279, 105053.47473262738, 105732.0, 105819.94180058254, 106823.0, 85867.14132858592, 86862.0, 87320.0, 87902.56048719716, 88007.11992880117, 76885.0, 77098.61450692822, 77176.61411693018, 77521.22478775252, 77600.83598746023, 8317.334613230805, 8438.789030274293, 8488.236058754495, 8597.785055373666, 8599.312055035458, 44581.33128003018, 46972.060558789315, 47518.287225690954, 47544.2868356968, 48841.511584883694, 66865.0, 69529.0, 69577.95633065407, 69796.6510167446, 72031.63984180045, 11058.944705276423, 11154.72113197144, 11183.105351571692, 11390.544378224778, 11524.0, 12059.016003331799, 12090.335031573348, 12218.694532636755, 12406.131570789958, 12859.035572331926, 11271.774564508827, 11396.601118960796, 11442.313461192354, 11493.2529385593, 11725.0, 11533.826992595286, 11588.826167607323, 11603.593874214363, 11879.940600297288, 12075.51697932108, 9496.0, 11241.0, 11287.887121128684, 11399.20205585601, 11579.826302605636, 8311.958440208002, 8893.599788009426, 9151.0, 9683.031696830261, 10005.499725013866, 9723.367981081206, 9928.602855885683, 9979.900200998187, 10099.899001009895, 10107.494625268855], "value": 6964.646916334967, "unit": "score"}], "name": "dromaeo_css", "value": 7209.331254051967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 117.0, 118.0, 117.0, 157.0, 105.0, 112.0, 159.0, 173.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 167.0, 179.0, 156.0, 124.0, 140.0, 124.0, 157.0, 157.0, 123.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 200.0, 198.0, 197.0, 196.0, 194.0, 192.0, 194.0, 201.0, 193.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 73.0, 75.0, 148.0, 66.0, 77.0, 83.0, 90.0, 157.0, 57.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 122.0, 77.0, 80.0, 81.0, 116.0, 81.0, 80.0, 117.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 131.0, 131.0, 93.0, 85.0, 90.0, 86.0, 89.0, 87.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 115.0, 96.0, 95.0, 96.0, 97.0, 95.0, 96.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 92.0, 101.0, 90.0, 90.0, 94.0, 91.0, 91.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 66.0, 65.0, 65.0, 66.0, 67.0, 62.0, 97.0, 101.0, 92.0], "value": 74.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 71.0, 69.0, 78.0, 74.0, 67.0, 68.0, 68.0, 67.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 83.0, 83.0, 83.0, 82.0, 79.0, 84.0, 82.0, 85.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 134.0, 135.0, 137.0, 134.0, 137.0, 146.0, 172.0, 153.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 160.0, 173.0, 174.0, 171.0, 171.0, 175.0, 177.0, 170.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 65.0, 70.0, 69.0, 64.0, 70.0, 66.0, 71.0, 81.0, 101.0], "value": 72.9, "unit": "score"}], "name": "kraken", "value": 1552.3000000000002, "alertThreshold": 2.0}]} 13:32:04 INFO - Return code: 0 13:32:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:32:04 ERROR - # TBPL SUCCESS # 13:32:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:04 INFO - 2016-04-26 13:32:04.282 screenresolution[1685:10395] starting screenresolution argv=screenresolution get 13:32:04 INFO - 2016-04-26 13:32:04.285 screenresolution[1685:10395] Display 0: 1600x1200x32@60 13:32:04 INFO - 2016-04-26 13:32:04.291 screenresolution[1686:10398] starting screenresolution argv=screenresolution list 13:32:04 INFO - Available Modes on Display 0 13:32:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:04 INFO - Intel Iris: 13:32:04 INFO - Chipset Model: Intel Iris 13:32:04 INFO - Type: GPU 13:32:04 INFO - Bus: Built-In 13:32:04 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:04 INFO - Vendor: Intel (0x8086) 13:32:04 INFO - Device ID: 0x0a2e 13:32:04 INFO - Revision ID: 0x0009 13:32:04 INFO - Displays: 13:32:04 INFO - Display: 13:32:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:04 INFO - Display Serial Number: 1600x1200 60 13:32:04 INFO - Main Display: Yes 13:32:04 INFO - Mirror: Off 13:32:04 INFO - Online: Yes 13:32:04 INFO - Rotation: Supported 13:32:04 INFO - Return code: 0 13:32:04 INFO - Running post-action listener: _resource_record_post_action 13:32:04 INFO - Running post-run listener: _resource_record_post_run 13:32:05 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 32374272; Write bytes: 656202752; Read time: 323; Write time: 2009 13:32:05 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353354240; Write bytes: 347682304; Read time: 27885; Write time: 660 13:32:05 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 30359040; Write bytes: 298681856; Read time: 301; Write time: 1304 13:32:05 INFO - Running post-run listener: _upload_blobber_files 13:32:05 INFO - Blob upload gear active. 13:32:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:32:05 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 13:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:32:06 INFO - (blobuploader) - INFO - Open directory for files ... 13:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:06 INFO - (blobuploader) - INFO - Done attempting. 13:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:32:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:07 INFO - (blobuploader) - INFO - Done attempting. 13:32:07 INFO - (blobuploader) - INFO - Iteration through files over. 13:32:07 INFO - Return code: 0 13:32:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:32:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:32:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/435f7fb657bba809b34ca4a8b4f641b182d9666d6f44d63b7c83ca941565ee8a421a794c5c837d5bdfa75341d52235e1add5ae6ee9b344b1abb3e262d5e251a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f145785ee99103e7957463e9f2050e45fb8ddaeade3cec2666b6affe8cbc032e53bfcb6081e3dee0e87b5985946785607998ca9d7a8de7bceff327d793e86d"} 13:32:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:32:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:32:07 INFO - Contents: 13:32:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/435f7fb657bba809b34ca4a8b4f641b182d9666d6f44d63b7c83ca941565ee8a421a794c5c837d5bdfa75341d52235e1add5ae6ee9b344b1abb3e262d5e251a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f145785ee99103e7957463e9f2050e45fb8ddaeade3cec2666b6affe8cbc032e53bfcb6081e3dee0e87b5985946785607998ca9d7a8de7bceff327d793e86d"} 13:32:07 INFO - Running post-run listener: copy_logs_to_upload_dir 13:32:07 INFO - Copying logs to upload dir... 13:32:07 INFO - mkdir: /builds/slave/test/build/upload/logs 13:32:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.585165 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-04-26 13:32:07.781208) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:07.785472) ========= 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.wAJu2mI92k/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.Xp8nyFAipf/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/435f7fb657bba809b34ca4a8b4f641b182d9666d6f44d63b7c83ca941565ee8a421a794c5c837d5bdfa75341d52235e1add5ae6ee9b344b1abb3e262d5e251a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f145785ee99103e7957463e9f2050e45fb8ddaeade3cec2666b6affe8cbc032e53bfcb6081e3dee0e87b5985946785607998ca9d7a8de7bceff327d793e86d"} build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010917 build_url: 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/435f7fb657bba809b34ca4a8b4f641b182d9666d6f44d63b7c83ca941565ee8a421a794c5c837d5bdfa75341d52235e1add5ae6ee9b344b1abb3e262d5e251a1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f145785ee99103e7957463e9f2050e45fb8ddaeade3cec2666b6affe8cbc032e53bfcb6081e3dee0e87b5985946785607998ca9d7a8de7bceff327d793e86d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:07.815332) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:07.815637) ========= 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.wAJu2mI92k/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.Xp8nyFAipf/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004979 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:07.863672) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:32:07.863966) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:32:07.867337) ========= ========= Total master_lag: 2.13 =========