builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0042 starttime: 1467697995.09 results: success (0) buildid: 20160704210422 builduid: baad91b65fc14f139b47f66cd71ef201 revision: ade7d8506f42b92f662db72b6d63a4645b66d0df ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.088362) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.089343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.089656) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.233265) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.233673) ========= 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.ojD0BCmQed/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.2IBgEEropy/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-04 22:53:15-- 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% 15.6M=0.001s 2016-07-04 22:53:15 (15.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.789880) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.790284) ========= 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.ojD0BCmQed/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.2IBgEEropy/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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.027793 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:15.840551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 22:53:15.840909) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ade7d8506f42b92f662db72b6d63a4645b66d0df --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ade7d8506f42b92f662db72b6d63a4645b66d0df --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ojD0BCmQed/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.2IBgEEropy/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-04 22:53:15,919 truncating revision to first 12 chars 2016-07-04 22:53:15,919 Setting DEBUG logging. 2016-07-04 22:53:15,919 attempt 1/10 2016-07-04 22:53:15,919 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ade7d8506f42?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 22:53:17,163 unpacking tar archive at: mozilla-beta-ade7d8506f42/testing/mozharness/ program finished with exit code 0 elapsedTime=1.502396 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 22:53:17.366151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:17.366510) ========= script_repo_revision: ade7d8506f42b92f662db72b6d63a4645b66d0df ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:17.366970) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:17.367290) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 22:53:17.385669) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-07-04 22:53:17.385976) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ojD0BCmQed/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.2IBgEEropy/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:17 INFO - MultiFileLogger online at 20160704 22:53:17 in /builds/slave/test 22:53:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:53:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:53:17 INFO - {'append_to_log': False, 22:53:17 INFO - 'base_work_dir': '/builds/slave/test', 22:53:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:53:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:53:17 INFO - 'branch': 'Mozilla-Beta', 22:53:17 INFO - 'buildbot_json_path': 'buildprops.json', 22:53:17 INFO - 'config_files': ('talos/mac_config.py',), 22:53:17 INFO - 'default_actions': ('clobber', 22:53:17 INFO - 'read-buildbot-config', 22:53:17 INFO - 'download-and-extract', 22:53:17 INFO - 'populate-webroot', 22:53:17 INFO - 'create-virtualenv', 22:53:17 INFO - 'install', 22:53:17 INFO - 'run-tests'), 22:53:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:53:17 INFO - 'download_minidump_stackwalk': True, 22:53:17 INFO - 'download_symbols': 'ondemand', 22:53:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:53:17 INFO - 'tooltool.py': '/tools/tooltool.py', 22:53:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:53:17 INFO - '/tools/misc-python/virtualenv.py')}, 22:53:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:53:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:53:17 INFO - 'installer_path': 'installer.exe', 22:53:17 INFO - 'log_level': 'info', 22:53:17 INFO - 'log_name': 'talos', 22:53:17 INFO - 'log_to_console': True, 22:53:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:53:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:53:17 INFO - 'opt_config_files': (), 22:53:17 INFO - 'pip_index': False, 22:53:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:17 INFO - 'cmd': ('bash', 22:53:17 INFO - '-c', 22:53:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:17 INFO - 'enabled': True, 22:53:17 INFO - 'halt_on_failure': False, 22:53:17 INFO - 'name': 'check_screen_resolution'},), 22:53:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:17 INFO - 'cmd': ('bash', 22:53:17 INFO - '-c', 22:53:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:17 INFO - 'enabled': True, 22:53:17 INFO - 'halt_on_failure': False, 22:53:17 INFO - 'name': 'check_screen_resolution'},), 22:53:17 INFO - 'run_cmd_checks_enabled': True, 22:53:17 INFO - 'sps_profile': False, 22:53:17 INFO - 'sps_profile_interval': 0, 22:53:17 INFO - 'suite': 'dromaeojs-e10s', 22:53:17 INFO - 'system_bits': '32', 22:53:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:53:17 INFO - 'title': 't-yosemite-r7-0042', 22:53:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:53:17 INFO - 'use_talos_json': True, 22:53:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:53:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:53:17 INFO - 'work_dir': 'build'} 22:53:17 INFO - ##### 22:53:17 INFO - ##### Running clobber step. 22:53:17 INFO - ##### 22:53:17 INFO - Running pre-action listener: _resource_record_pre_action 22:53:17 INFO - Running main action method: clobber 22:53:17 INFO - rmtree: /builds/slave/test/build 22:53:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:53:19 INFO - Running post-action listener: _resource_record_post_action 22:53:19 INFO - ##### 22:53:19 INFO - ##### Running read-buildbot-config step. 22:53:19 INFO - ##### 22:53:19 INFO - Running pre-action listener: _resource_record_pre_action 22:53:19 INFO - Running main action method: read_buildbot_config 22:53:19 INFO - Using buildbot properties: 22:53:19 INFO - { 22:53:19 INFO - "project": "", 22:53:19 INFO - "product": "firefox", 22:53:19 INFO - "script_repo_revision": "production", 22:53:19 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 22:53:19 INFO - "repository": "", 22:53:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 22:53:19 INFO - "buildid": "20160704210422", 22:53:19 INFO - "pgo_build": "False", 22:53:19 INFO - "basedir": "/builds/slave/test", 22:53:19 INFO - "buildnumber": 21, 22:53:19 INFO - "slavename": "t-yosemite-r7-0042", 22:53:19 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:53:19 INFO - "platform": "yosemite_r7", 22:53:19 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:53:19 INFO - "branch": "mozilla-beta", 22:53:19 INFO - "repo_path": "releases/mozilla-beta", 22:53:19 INFO - "revision": "ade7d8506f42b92f662db72b6d63a4645b66d0df", 22:53:19 INFO - "stage_platform": "macosx64", 22:53:19 INFO - "builduid": "baad91b65fc14f139b47f66cd71ef201", 22:53:19 INFO - "slavebuilddir": "test" 22:53:19 INFO - } 22:53:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 22:53:19 INFO - Running post-action listener: _resource_record_post_action 22:53:19 INFO - ##### 22:53:19 INFO - ##### Running download-and-extract step. 22:53:19 INFO - ##### 22:53:19 INFO - Running pre-action listener: _resource_record_pre_action 22:53:19 INFO - Running main action method: download_and_extract 22:53:19 INFO - mkdir: /builds/slave/test/build/tests 22:53:19 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')]} 22:53:19 INFO - https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:53:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:53:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:53:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:53:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 22:53:23 INFO - Downloaded 1165 bytes. 22:53:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:53:23 INFO - Using the following test package requirements: 22:53:23 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:53:23 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 22:53:23 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 22:53:23 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:53:23 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 22:53:23 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:53:23 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 22:53:23 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 22:53:23 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 22:53:23 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:53:23 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 22:53:23 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 22:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:23 INFO - https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:53:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:53:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:53:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 22:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 22:53:24 INFO - Downloaded 18351364 bytes. 22:53:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:53:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:53:27 INFO - Return code: 0 22:53:27 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:53:27 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')]} 22:53:27 INFO - https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:53:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:53:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:53:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 22:53:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 22:53:28 INFO - Downloaded 18351364 bytes. 22:53:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:53:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:53:32 INFO - Return code: 0 22:53: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')]} 22:53:32 INFO - https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:53:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:53:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:53:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 22:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 22:53:34 INFO - Downloaded 10840145 bytes. 22:53:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:53:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:53:34 INFO - Return code: 0 22:53: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')]} 22:53:34 INFO - https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 22:53:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:53:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:53:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 22:53:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 22:53:37 INFO - Downloaded 86193743 bytes. 22:53:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:53:37 INFO - mkdir: /builds/slave/test/properties 22:53:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:53:37 INFO - Writing to file /builds/slave/test/properties/build_url 22:53:37 INFO - Contents: 22:53:37 INFO - build_url:https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:53:38 INFO - Running post-action listener: _resource_record_post_action 22:53:38 INFO - Running post-action listener: set_extra_try_arguments 22:53:38 INFO - ##### 22:53:38 INFO - ##### Running populate-webroot step. 22:53:38 INFO - ##### 22:53:38 INFO - Running pre-action listener: _resource_record_pre_action 22:53:38 INFO - Running main action method: populate_webroot 22:53:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:53:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:53:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:53:38 INFO - '--tppagecycles', 22:53:38 INFO - '7'], 22:53:38 INFO - 'tests': ['tsvgm']}}, 22:53:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:53:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:53:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:53:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:53:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['damp', 'tps']}, 22:53:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'tests': ['damp', 'tps']}, 22:53:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['dromaeo_dom']}, 22:53:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:53:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['a11yr', 22:53:38 INFO - 'ts_paint', 22:53:38 INFO - 'tpaint', 22:53:38 INFO - 'sessionrestore', 22:53:38 INFO - 'sessionrestore_no_auto_restore', 22:53:38 INFO - 'tabpaint']}, 22:53:38 INFO - 'other-e10s': {'tests': ['a11yr', 22:53:38 INFO - 'ts_paint', 22:53:38 INFO - 'tpaint', 22:53:38 INFO - 'sessionrestore', 22:53:38 INFO - 'sessionrestore_no_auto_restore', 22:53:38 INFO - 'tabpaint']}, 22:53:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:53:38 INFO - 'ts_paint', 22:53:38 INFO - 'tpaint', 22:53:38 INFO - 'sessionrestore', 22:53:38 INFO - 'sessionrestore_no_auto_restore', 22:53:38 INFO - 'tabpaint']}, 22:53:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:53:38 INFO - 'ts_paint', 22:53:38 INFO - 'tpaint', 22:53:38 INFO - 'sessionrestore', 22:53:38 INFO - 'sessionrestore_no_auto_restore', 22:53:38 INFO - 'tabpaint']}, 22:53:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['a11yr', 22:53:38 INFO - 'ts_paint', 22:53:38 INFO - 'tpaint', 22:53:38 INFO - 'sessionrestore', 22:53:38 INFO - 'sessionrestore_no_auto_restore', 22:53:38 INFO - 'tabpaint']}, 22:53:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['a11yr', 22:53:38 INFO - 'ts_paint', 22:53:38 INFO - 'tpaint', 22:53:38 INFO - 'sessionrestore', 22:53:38 INFO - 'sessionrestore_no_auto_restore', 22:53:38 INFO - 'tabpaint']}, 22:53:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['tsvgx', 22:53:38 INFO - 'tsvgr_opacity', 22:53:38 INFO - 'tart', 22:53:38 INFO - 'tscrollx', 22:53:38 INFO - 'cart']}, 22:53:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:53:38 INFO - 'tsvgr_opacity', 22:53:38 INFO - 'tart', 22:53:38 INFO - 'tscrollx', 22:53:38 INFO - 'cart']}, 22:53:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'talos_options': ['--disable-e10s'], 22:53:38 INFO - 'tests': ['tp5o']}, 22:53:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'tests': ['tp5o']}, 22:53:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'talos_options': ['--disable-e10s', 22:53:38 INFO - '--xperf_path', 22:53:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:38 INFO - 'tests': ['tp5n']}, 22:53:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:38 INFO - 'talos_options': ['--xperf_path', 22:53:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:38 INFO - 'tests': ['tp5n']}}, 22:53:38 INFO - 'talos.zip': {'path': '', 22:53:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:53:38 INFO - Running post-action listener: _resource_record_post_action 22:53:38 INFO - ##### 22:53:38 INFO - ##### Running create-virtualenv step. 22:53:38 INFO - ##### 22:53:38 INFO - Running pre-action listener: _resource_record_pre_action 22:53:38 INFO - Running main action method: create_virtualenv 22:53:38 INFO - Creating virtualenv /builds/slave/test/build/venv 22:53:38 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 22:53:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:53:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:53:38 INFO - Using real prefix '/tools/python27' 22:53:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:53:39 INFO - Installing distribute.............................................................................................................................................................................................done. 22:53:42 INFO - Installing pip.................done. 22:53:42 INFO - Return code: 0 22:53:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:42 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:42 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 22:53:42 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 22:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:42 INFO - 'HOME': '/Users/cltbld', 22:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:42 INFO - 'LOGNAME': 'cltbld', 22:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:42 INFO - 'MOZ_NO_REMOTE': '1', 22:53:42 INFO - 'NO_EM_RESTART': '1', 22:53:42 INFO - 'PAGER': '/bin/cat', 22:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:42 INFO - 'PWD': '/builds/slave/test', 22:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:42 INFO - 'SHELL': '/bin/bash', 22:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:53:42 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:42 INFO - 'USER': 'cltbld', 22:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:42 INFO - 'XPC_FLAGS': '0x0', 22:53:42 INFO - 'XPC_SERVICE_NAME': '0', 22:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:42 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 22:53:42 INFO - Cleaning up... 22:53:42 INFO - Return code: 0 22:53:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:42 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:42 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 22:53:42 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 22:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:42 INFO - 'HOME': '/Users/cltbld', 22:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:42 INFO - 'LOGNAME': 'cltbld', 22:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:42 INFO - 'MOZ_NO_REMOTE': '1', 22:53:42 INFO - 'NO_EM_RESTART': '1', 22:53:42 INFO - 'PAGER': '/bin/cat', 22:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:42 INFO - 'PWD': '/builds/slave/test', 22:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:42 INFO - 'SHELL': '/bin/bash', 22:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:53:42 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:42 INFO - 'USER': 'cltbld', 22:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:42 INFO - 'XPC_FLAGS': '0x0', 22:53:42 INFO - 'XPC_SERVICE_NAME': '0', 22:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:42 INFO - Downloading/unpacking psutil>=0.7.1 22:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:53:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:45 INFO - Installing collected packages: psutil 22:53:45 INFO - Running setup.py install for psutil 22:53:45 INFO - building 'psutil._psutil_osx' extension 22:53:45 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 22:53:46 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 22:53:46 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 22:53:46 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 22:53:46 INFO - building 'psutil._psutil_posix' extension 22:53:46 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 22:53:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:53:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:53:46 INFO - ^ 22:53:46 INFO - 1 warning generated. 22:53:46 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 22:53:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:46 INFO - Successfully installed psutil 22:53:46 INFO - Cleaning up... 22:53:46 INFO - Return code: 0 22:53:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:53: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')]} 22:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53: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')]} 22:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:46 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:46 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 22:53:46 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 22:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:46 INFO - 'HOME': '/Users/cltbld', 22:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:46 INFO - 'LOGNAME': 'cltbld', 22:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:46 INFO - 'MOZ_NO_REMOTE': '1', 22:53:46 INFO - 'NO_EM_RESTART': '1', 22:53:46 INFO - 'PAGER': '/bin/cat', 22:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:46 INFO - 'PWD': '/builds/slave/test', 22:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:46 INFO - 'SHELL': '/bin/bash', 22:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:53:46 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:46 INFO - 'USER': 'cltbld', 22:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:46 INFO - 'XPC_FLAGS': '0x0', 22:53:46 INFO - 'XPC_SERVICE_NAME': '0', 22:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:47 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 22:53:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:53:49 INFO - Installing collected packages: mozsystemmonitor 22:53:49 INFO - Running setup.py install for mozsystemmonitor 22:53:49 INFO - Successfully installed mozsystemmonitor 22:53:49 INFO - Cleaning up... 22:53:50 INFO - Return code: 0 22:53:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:53:50 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')]} 22:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 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')]} 22:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:50 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:50 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 22:53:50 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 22:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:50 INFO - 'HOME': '/Users/cltbld', 22:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:50 INFO - 'LOGNAME': 'cltbld', 22:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:50 INFO - 'MOZ_NO_REMOTE': '1', 22:53:50 INFO - 'NO_EM_RESTART': '1', 22:53:50 INFO - 'PAGER': '/bin/cat', 22:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:50 INFO - 'PWD': '/builds/slave/test', 22:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:50 INFO - 'SHELL': '/bin/bash', 22:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:53:50 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:50 INFO - 'USER': 'cltbld', 22:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:50 INFO - 'XPC_FLAGS': '0x0', 22:53:50 INFO - 'XPC_SERVICE_NAME': '0', 22:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:50 INFO - Downloading/unpacking blobuploader==1.2.4 22:53:50 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 22:53:50 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 22:53:50 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 22:53:50 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 22:53:50 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 22:53:50 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 22:53:52 INFO - Downloading blobuploader-1.2.4.tar.gz 22:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:53:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:53: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 22:53: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 22:53: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 22:53: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 22:53: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 22:53: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 22:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:53:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 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 22:53:53 INFO - Downloading docopt-0.6.1.tar.gz 22:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:53:53 INFO - Installing collected packages: blobuploader, requests, docopt 22:53:53 INFO - Running setup.py install for blobuploader 22:53:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:53:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:53:54 INFO - Running setup.py install for requests 22:53:54 INFO - Running setup.py install for docopt 22:53:54 INFO - Successfully installed blobuploader requests docopt 22:53:54 INFO - Cleaning up... 22:53:54 INFO - Return code: 0 22:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:54 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:54 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 22:53:54 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 22:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:54 INFO - 'HOME': '/Users/cltbld', 22:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:54 INFO - 'LOGNAME': 'cltbld', 22:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:54 INFO - 'MOZ_NO_REMOTE': '1', 22:53:54 INFO - 'NO_EM_RESTART': '1', 22:53:54 INFO - 'PAGER': '/bin/cat', 22:53:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:54 INFO - 'PWD': '/builds/slave/test', 22:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:54 INFO - 'SHELL': '/bin/bash', 22:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:53:54 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:54 INFO - 'USER': 'cltbld', 22:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:54 INFO - 'XPC_FLAGS': '0x0', 22:53:54 INFO - 'XPC_SERVICE_NAME': '0', 22:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-4E6dMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-XqnkRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-giYkcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-6LNG21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-pUuMER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ZR0c6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-AIgYkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-teNqDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:55 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-a3yXNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-r_GMxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-IwsaX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-u9XCue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-UHRmle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-SRhy5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-sRGM33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-qLFyQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:56 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-cjsJlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:53:56 INFO - Running setup.py install for manifestparser 22:53:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:53:56 INFO - Running setup.py install for mozcrash 22:53:57 INFO - Running setup.py install for mozdebug 22:53:57 INFO - Running setup.py install for mozdevice 22:53:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Running setup.py install for mozfile 22:53:57 INFO - Running setup.py install for mozhttpd 22:53:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Running setup.py install for mozinfo 22:53:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:53:57 INFO - Running setup.py install for mozInstall 22:53:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozleak 22:53:58 INFO - Running setup.py install for mozlog 22:53:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for moznetwork 22:53:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozprocess 22:53:58 INFO - Running setup.py install for mozprofile 22:53:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozrunner 22:53:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:53:58 INFO - Running setup.py install for mozscreenshot 22:53:59 INFO - Running setup.py install for moztest 22:53:59 INFO - Running setup.py install for mozversion 22:53:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:53:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:53:59 INFO - Cleaning up... 22:53:59 INFO - Return code: 0 22:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:59 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:53:59 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 22:53:59 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 22:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:59 INFO - 'HOME': '/Users/cltbld', 22:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:59 INFO - 'LOGNAME': 'cltbld', 22:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:59 INFO - 'MOZ_NO_REMOTE': '1', 22:53:59 INFO - 'NO_EM_RESTART': '1', 22:53:59 INFO - 'PAGER': '/bin/cat', 22:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:59 INFO - 'PWD': '/builds/slave/test', 22:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:59 INFO - 'SHELL': '/bin/bash', 22:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:53:59 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:59 INFO - 'USER': 'cltbld', 22:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:59 INFO - 'XPC_FLAGS': '0x0', 22:53:59 INFO - 'XPC_SERVICE_NAME': '0', 22:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-vDV0P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:59 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)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-l_ps5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:59 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)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:59 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-M3MF94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:59 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)) 22:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-f1GBzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-cqBSzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-BmLLg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-AmZXwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-W5odnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-XJNiqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-pp6Fl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-HS7KKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-DZTyzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:00 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)) 22:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:00 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-EtJNsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-lqtV7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-qNCib8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ctNKff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:01 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)) 22:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:01 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ikCv3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:01 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)) 22:54:01 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)) 22:54:01 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)) 22:54:01 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)) 22:54:01 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)) 22:54:01 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)) 22:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:03 INFO - Downloading blessings-1.6.tar.gz 22:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:54:04 INFO - Installing collected packages: blessings 22:54:04 INFO - Running setup.py install for blessings 22:54:04 INFO - Successfully installed blessings 22:54:04 INFO - Cleaning up... 22:54:04 INFO - Return code: 0 22:54:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:04 INFO - Reading from file tmpfile_stdout 22:54:04 INFO - Current package versions: 22:54:04 INFO - blessings == 1.6 22:54:04 INFO - blobuploader == 1.2.4 22:54:04 INFO - docopt == 0.6.1 22:54:04 INFO - manifestparser == 1.1 22:54:04 INFO - mozInstall == 1.12 22:54:04 INFO - mozcrash == 0.17 22:54:04 INFO - mozdebug == 0.1 22:54:04 INFO - mozdevice == 0.48 22:54:04 INFO - mozfile == 1.2 22:54:04 INFO - mozhttpd == 0.7 22:54:04 INFO - mozinfo == 0.9 22:54:04 INFO - mozleak == 0.1 22:54:04 INFO - mozlog == 3.1 22:54:04 INFO - moznetwork == 0.27 22:54:04 INFO - mozprocess == 0.22 22:54:04 INFO - mozprofile == 0.28 22:54:04 INFO - mozrunner == 6.11 22:54:04 INFO - mozscreenshot == 0.1 22:54:04 INFO - mozsystemmonitor == 0.0 22:54:04 INFO - moztest == 0.7 22:54:04 INFO - mozversion == 1.4 22:54:04 INFO - psutil == 3.1.1 22:54:04 INFO - requests == 1.2.3 22:54:04 INFO - wsgiref == 0.1.2 22:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:04 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')]} 22:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:04 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')]} 22:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:04 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:54:04 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 22:54:04 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 22:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:04 INFO - 'HOME': '/Users/cltbld', 22:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:04 INFO - 'LOGNAME': 'cltbld', 22:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:04 INFO - 'MOZ_NO_REMOTE': '1', 22:54:04 INFO - 'NO_EM_RESTART': '1', 22:54:04 INFO - 'PAGER': '/bin/cat', 22:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:04 INFO - 'PWD': '/builds/slave/test', 22:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:04 INFO - 'SHELL': '/bin/bash', 22:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:54:04 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:04 INFO - 'USER': 'cltbld', 22:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:04 INFO - 'XPC_FLAGS': '0x0', 22:54:04 INFO - 'XPC_SERVICE_NAME': '0', 22:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 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)) 22:54:04 INFO - Cleaning up... 22:54:04 INFO - Return code: 0 22:54:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:54:04 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')]} 22:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:04 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')]} 22:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:04 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 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:54:04 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 22:54:04 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 22:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:04 INFO - 'HOME': '/Users/cltbld', 22:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:04 INFO - 'LOGNAME': 'cltbld', 22:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:04 INFO - 'MOZ_NO_REMOTE': '1', 22:54:04 INFO - 'NO_EM_RESTART': '1', 22:54:04 INFO - 'PAGER': '/bin/cat', 22:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:04 INFO - 'PWD': '/builds/slave/test', 22:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:04 INFO - 'SHELL': '/bin/bash', 22:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:54:04 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:04 INFO - 'USER': 'cltbld', 22:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:04 INFO - 'XPC_FLAGS': '0x0', 22:54:04 INFO - 'XPC_SERVICE_NAME': '0', 22:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:05 INFO - Downloading/unpacking jsonschema 22:54:05 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 22:54:05 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 22:54:05 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 22:54:05 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 22:54:05 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 22:54:05 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 22:54:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:54:07 INFO - Downloading/unpacking functools32 (from jsonschema) 22:54:07 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 22:54:07 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 22:54:07 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 22:54:07 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 22:54:07 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 22:54:07 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 22:54:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:54:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:54:07 INFO - warning: no files found matching '*.txt' 22:54:07 INFO - no previously-included directories found matching 'build' 22:54:07 INFO - no previously-included directories found matching 'dist' 22:54:07 INFO - no previously-included directories found matching '.git*' 22:54:08 INFO - Installing collected packages: jsonschema, functools32 22:54:08 INFO - Running setup.py install for functools32 22:54:08 INFO - warning: no files found matching '*.txt' 22:54:08 INFO - no previously-included directories found matching 'build' 22:54:08 INFO - no previously-included directories found matching 'dist' 22:54:08 INFO - no previously-included directories found matching '.git*' 22:54:08 INFO - Successfully installed jsonschema functools32 22:54:08 INFO - Cleaning up... 22:54:08 INFO - Return code: 0 22:54:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10239aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2035a3980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, '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.ojD0BCmQed/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.2IBgEEropy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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 22:54:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:08 INFO - 'HOME': '/Users/cltbld', 22:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:08 INFO - 'LOGNAME': 'cltbld', 22:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:08 INFO - 'MOZ_NO_REMOTE': '1', 22:54:08 INFO - 'NO_EM_RESTART': '1', 22:54:08 INFO - 'PAGER': '/bin/cat', 22:54:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:08 INFO - 'PWD': '/builds/slave/test', 22:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:08 INFO - 'SHELL': '/bin/bash', 22:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:54:08 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:08 INFO - 'USER': 'cltbld', 22:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:08 INFO - 'XPC_FLAGS': '0x0', 22:54:08 INFO - 'XPC_SERVICE_NAME': '0', 22:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:08 INFO - Downloading/unpacking flake8 22:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:54:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:54:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:54:12 INFO - no previously-included directories found matching 'docs/_build' 22:54:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:54:12 INFO - Running setup.py install for pep8 22:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:54:12 INFO - no previously-included directories found matching 'docs/_build' 22:54:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:54:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:54:12 INFO - Cleaning up... 22:54:12 INFO - Return code: 0 22:54:12 INFO - Running post-action listener: _resource_record_post_action 22:54:12 INFO - Running post-action listener: _start_resource_monitoring 22:54:12 INFO - Starting resource monitoring. 22:54:12 INFO - ##### 22:54:12 INFO - ##### Running install step. 22:54:12 INFO - ##### 22:54:12 INFO - Running pre-action listener: _resource_record_pre_action 22:54:12 INFO - Running main action method: install 22:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:12 INFO - Reading from file tmpfile_stdout 22:54:12 INFO - Detecting whether we're running mozinstall >=1.0... 22:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:54:13 INFO - Reading from file tmpfile_stdout 22:54:13 INFO - Output received: 22:54:13 INFO - Usage: mozinstall [options] installer 22:54:13 INFO - Options: 22:54:13 INFO - -h, --help show this help message and exit 22:54:13 INFO - -d DEST, --destination=DEST 22:54:13 INFO - Directory to install application into. [default: 22:54:13 INFO - "/builds/slave/test"] 22:54:13 INFO - --app=APP Application being installed. [default: firefox] 22:54:13 INFO - mkdir: /builds/slave/test/build/application 22:54:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:54:35 INFO - Reading from file tmpfile_stdout 22:54:35 INFO - Output received: 22:54:35 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:54:35 INFO - Running post-action listener: _resource_record_post_action 22:54:35 INFO - ##### 22:54:35 INFO - ##### Running run-tests step. 22:54:35 INFO - ##### 22:54:35 INFO - Running pre-action listener: _resource_record_pre_action 22:54:35 INFO - Running main action method: run_tests 22:54:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:54:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:54:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:54:35 INFO - 2016-07-04 22:54:35.123 screenresolution[1654:9005] starting screenresolution argv=screenresolution get 22:54:35 INFO - 2016-07-04 22:54:35.126 screenresolution[1654:9005] Display 0: 1600x1200x32@60 22:54:35 INFO - 2016-07-04 22:54:35.132 screenresolution[1655:9008] starting screenresolution argv=screenresolution list 22:54:35 INFO - Available Modes on Display 0 22:54:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:54:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:54:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:54:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:54:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:54:35 INFO - Intel Iris: 22:54:35 INFO - Chipset Model: Intel Iris 22:54:35 INFO - Type: GPU 22:54:35 INFO - Bus: Built-In 22:54:35 INFO - VRAM (Dynamic, Max): 1536 MB 22:54:35 INFO - Vendor: Intel (0x8086) 22:54:35 INFO - Device ID: 0x0a2e 22:54:35 INFO - Revision ID: 0x0009 22:54:35 INFO - Displays: 22:54:35 INFO - Display: 22:54:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:54:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:54:35 INFO - Display Serial Number: 1600x1200 60 22:54:35 INFO - Main Display: Yes 22:54:35 INFO - Mirror: Off 22:54:35 INFO - Online: Yes 22:54:35 INFO - Rotation: Supported 22:54:35 INFO - Return code: 0 22:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:54:35 INFO - Python 2.7.3 22:54:35 INFO - Return code: 0 22:54:35 INFO - grabbing minidump binary from tooltool 22:54:35 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')]} 22:54:35 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 0x102239e00>, '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 0x1020a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102234b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:54:35 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 22:54:35 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 22:54:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:54:35 INFO - Return code: 0 22:54:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:54:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:54:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:54:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:54:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:54:36 INFO - Return code: 0 22:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--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 22:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0042 --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 22:54:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ojD0BCmQed/Render', 22:54:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:36 INFO - 'HOME': '/Users/cltbld', 22:54:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:36 INFO - 'LOGNAME': 'cltbld', 22:54:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:54:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:36 INFO - 'MOZ_NO_REMOTE': '1', 22:54:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:54:36 INFO - 'NO_EM_RESTART': '1', 22:54:36 INFO - 'PAGER': '/bin/cat', 22:54:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:36 INFO - 'PWD': '/builds/slave/test', 22:54:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:54:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:36 INFO - 'SHELL': '/bin/bash', 22:54:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2IBgEEropy/Listeners', 22:54:36 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 22:54:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:36 INFO - 'USER': 'cltbld', 22:54:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:36 INFO - 'XPC_FLAGS': '0x0', 22:54:36 INFO - 'XPC_SERVICE_NAME': '0', 22:54:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--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 22:54:36 INFO - mozversion application_buildid: 20160704210422 22:54:36 INFO - mozversion application_changeset: ade7d8506f42b92f662db72b6d63a4645b66d0df 22:54:36 INFO - mozversion application_display_name: Firefox 22:54:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:54:36 INFO - mozversion application_name: Firefox 22:54:36 INFO - mozversion application_remotingname: firefox 22:54:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:54:36 INFO - mozversion application_vendor: Mozilla 22:54:36 INFO - mozversion application_version: 48.0 22:54:36 INFO - mozversion platform_buildid: 20160704210422 22:54:36 INFO - mozversion platform_changeset: ade7d8506f42b92f662db72b6d63a4645b66d0df 22:54:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:54:36 INFO - mozversion platform_version: 48.0 22:54:36 INFO - using testdate: 1467698076 22:54:36 INFO - actual date: 1467698076 22:54:36 INFO - starting webserver on 'localhost:49279' 22:54:36 INFO - SUITE-START | Running 2 tests 22:54:36 INFO - TEST-START | dromaeo_css 22:54:36 INFO - operating with platform_type : mac_ 22:54:36 INFO - Initialising browser for dromaeo_css test... 22:54:36 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmp9QKCe7/profile http://localhost:49279/getInfo.html) 22:54:39 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 22:54:39 INFO - PROCESS | 1673 | colorDepth:24 22:54:39 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 22:54:39 INFO - PROCESS | 1673 | __metrics 22:54:39 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:54:40 INFO - TEST-INFO | 1673: exit 0 22:54:40 INFO - Browser initialized. 22:54:40 INFO - Running cycle 1/1 for dromaeo_css test... 22:54:40 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmp9QKCe7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:52 INFO - PROCESS | 1678 | RSS: Main: 171147264 22:54:52 INFO - PROCESS | 1678 | 22:55:42 INFO - PROCESS | 1678 | 1467698142079 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] 22:57:52 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 22:57:53 INFO - PROCESS | 1678 | RSS: Main: 182095872 22:57:53 INFO - PROCESS | 1678 | 23:00:55 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 23:00:56 INFO - PROCESS | 1678 | RSS: Main: 192913408 23:00:56 INFO - PROCESS | 1678 | 23:03:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 23:03:58 INFO - PROCESS | 1678 | RSS: Main: 195051520 23:03:58 INFO - PROCESS | 1678 | 23:07:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 23:07:00 INFO - PROCESS | 1678 | RSS: Main: 198754304 23:07:00 INFO - PROCESS | 1678 | 23:10:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 23:10:03 INFO - PROCESS | 1678 | RSS: Main: 198471680 23:10:03 INFO - PROCESS | 1678 | 23:13:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 23:13:06 INFO - PROCESS | 1678 | RSS: Main: 203038720 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | __start_tp_report 23:13:06 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 23:13:06 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 23:13:06 INFO - PROCESS | 1678 | |i|pagename|runs| 23:13:06 INFO - PROCESS | 1678 | |0;dojo.html;3080.507118860982;3598.8740394086194;3657.7439579229463;3685;3696.20531585709;5667.008273552133;5670.432956704328;5672.035753921833;5674.233978412918;5690.2887139107615;5669.914951275724;5671.546276297897;5683.8579035524135;5684.289463817023;5719.914201286973;5150.690958542481;5164.715940623264;5195.584353251731;5219.13884209107;5247.527722504974;4885.193942999419;5134.101532231844;5224.895502089956;5256.26412302278;5261.76322065508;4374.365716971038;5128.717820544864;5235.397929238148;5257.737113144347;5266.7366631668265;5220.6084543659;5236.188390799432;5255.290535777678;5319.9202011969855;5320.042392369372;5120.897582048338;5185.662931909452;5192.71440070796;5207.0366982108435;5277.1820367842665;5589.944100558983;5651.9152212716845;5662.801801936913;5672.319321681424;5676.2620859288445;4979.302897594303;6051.697415129226;6118.010103775246;6143.665407145256;6201.131841542187;6172.07418887166;6184.598001129957;6192.318844927055;6203.31763506014;6212.223472065992;5784.4504772046585;5807.128930660435;5810.941890581083;5827.388124246978;5846.444587764156;6125.296497837281;6218.9378106218355;6221.564490485713;6222;6238.189035425366;4954.405471343462;6085;6129.478994285536;6132.938670613326;6133.815985520441;6182.536309776785;6238.688065596702;6251.187345645037;6255.655938923403;6261.592996455216;6123.465646421213;6183.96908015466;6197.411245931719;6232.750689972441;6246.250449946036;6220.471259942865;6235.064740288993;6239.282482514569;6247.125402443661;6249.218847644044;4068.572799856032;4267.933016745814;4878.097551952829;4936.160852655057;4955;4479.596836284685;4485.372047913294;4485.730856148642;4490.146872094291;4504.594586487232;4852.296417019513;5944.256967879015;5987.251593550806;5997.190379298826;6013.218281623448;6115.301188036035;6147.73971335884;6243.188385509855;6253.093301471261;6253.343398943137;4719.646026548023;5960.910586341209;6005.969970150121;6034.909476357858;6099.664518451527;5393.892122157535;5424.783008679688;5450.5367043800925;5454.59090568201;5465.016297066566;5225.817096401608;6402;6417.97312430009;6451.677416129269;6509.641969691617;6251.78118765841;6278.748850045947;6283.277423096402;6292.276388215414;6309.085182648399;6460.935390646034;6492.577982431127;6508.414242718178;6523.608583485006;6543.247526534319;6033.969830150997;6102.115193296859;6145.432914606747;6213.1922850031015;6232.8130156095385;5854.941450585439;6240.450582701727;6255.40573645503;6258;6290.21372328459;4842.757862106951;6121.602095863755;6129.509639228761;6137.355577664281;6142.570020098729;5874.471297583238;6077.453029227391;6080.209572755513;6116.914073580098;6142.140100386039;5928.258967629046;5937.168796368598;5939.07944268639;5970.3432622412365;5970.9701451492465;6026.548008899263;6131.938680613137;6139.109829074849;6142.170806941006;6142.938570614237;5831.7667293307795;5917.556183286357;5930.436608522184;5988.4909782668965;6028.75884964614 23:13:06 INFO - PROCESS | 1678 | |1;ext.html;511.74157050689394;530.9707966061867;581.1808948026593;601.9428154325337;615.8891399548086;36702;38262.42606360907;38285.85142445724;38297.80851095744;38323.80838095813;41519;41586.79206603971;41676.74969750905;41911.95220119499;42055.789721051355;9682.56428460721;9849.950750246238;9964.551595178234;9983.650572229973;10015.248856335804;9911.454869982148;9914.652987145451;9980.953904449607;10006.949965250164;10043.497825108752;9958.053984871425;10043.497825108752;10044.497775111251;10059.748506287366;10060.496975151249;9917.256205784595;10000.149987251092;10001.499925003793;10014.899851001506;10053.246006549538;9805.166560842337;9806.460644664541;9919.752006199831;9942.701718948443;9945.552450139761;33854.66145338564;39668.40497392541;40018.59981400178;40132;40208.59791402078;3086.2438702518025;3226.3063441359986;3274.607047154333;3353.0611428799975;3432.828358582061;27665.723342766516;27749.445011099866;27749.8900043998;27985.720142798516;28281;2661.175035738927;2692.5018871508637;2705.648265725463;2733.58996150577;2835.4470878178763;307083;307332;308434;309309;309924;35622.28755424877;35973.28053438917;36132.81933590315;37383.25233495315;37916.810415947744;36868.26263474769;36885.89342319735;37108.814455928084;37217.06957326034;37225.25549488995;16365.83634163667;16548.75176872348;16580.253888575044;16584;16588;13851.376688049062;14239.359228834728;14254.144751314747;14291;14347.354369053417;8441.746747597583;8539.74380768578;8570.4000719949;8590.699325523577;8643.827123457622;3536.1513236822975;3558.967899309171;3587.802670853076;3603.3153700796765;3612.3497770401054;972.5088830140875;1046.1108058150662;1086.9891301086889;1120.2662256222188;1149.1209224942925;29089;29169.854150729112;29697;29803;29882.252943676147;1148.9770204595861;1161.6456980620824;1197.257700225848;1214.1743614342156;1223.6818427208989;14825.036372635745;15129.546113616609;15175.468858589897;15296.464623738339;15415.691686166438;602302;606992;611877;613929;614520;60255;60936;61242.77514449686;61715;61836;1308.7971364438338;1530.4643374818725;1532.831388547259;1539.722849887031;1559.5789136933192;28978;29410.705892940798;29476.55785163267;29934;30100.548491772202;50222.2466662993;51235;51264.487355127465;51397.22904156515;51419;476744;578552;579661;581376;593096;27281.59077613798;27433.58849617297;27531.44937101287;27640.170794876187;28075.157745267676;45594;46575.30137048015;47181.58455246348;47461.28808067951;47807.282890755974;38224.23551529009;38842.41736374013;39066.60933390629;40250.59749402585;41008.58991410048;35904.28191436209;36966;38101;38970.83087507379;39788;35437.82281088578;36450.635493644724;36828;37236;37334.813325933195;31211.687883120878;31800.204994875312;31866.522002169524;32099.679003210604;32120.35759284848;24398.756012439648;24416;24860;24873.378165546008;25197.370065748502 23:13:06 INFO - PROCESS | 1678 | |2;jquery.html;352.53289391556194;410.4540960522505;414.297765287837;420.0737374090132;420.23936674618943;20964.951752412377;21237.893810530924;21739.891300543495;21773.911304434776;22182.223622173187;21716.674249886226;22000.44998875029;22640.43398915028;22931.541369172606;23033.193838215666;5168.8449346519665;5766.625169363989;6218.191635087433;6239.438450539451;6256.499480041586;5727.284089488814;5727.799527016556;5786.537077033827;5839.737211825457;5855.47300742933;5385.892282154355;5650.3219613646215;6196.81409557714;6202.069689546582;6210.534209934251;6092.390760923917;6178.84552886177;6187.226596675416;6192.690365481708;6206.317305096436;5465.152901300305;5667.489925906688;5749.942500574983;5750.367459579443;5750.856228594277;13807.723845523033;16470.917645411817;25410;26178.952841886494;26302.079427219956;2120.120150137691;2139.144342263092;2208.4147700859285;2450.7304196538366;2861.2846788302923;9965.10314071737;10243.129334006544;10993.61522346714;11845.526178952832;15004.699906001819;1566.302995167151;1681.2938565802392;1821.4899828048167;1850.2136591948367;1857.1642760757713;101717.4914125432;104814.90370192629;106444.4677776614;107037;108267;11903.523859045541;12000.519979200908;12038.39808009596;12144.574939877239;12219.633410997685;15322.23388830554;15338.769918451233;15343.92328038375;15370.077795332316;15408.383664653289;8254.637984732572;8600.838886750334;9074.954625226832;9223.262139028862;9303.953480232556;7923.009623797026;8680.392372533863;9248.722538323862;9270.67552635668;9280.350375473652;3910.354791459434;4693.413323334583;4744.857654270378;4758.643101767381;4821.252705830602;1771.5305444057335;1958.8041195880298;2005.9899700501696;2053.260826102602;2096.549241912996;825.8265764189588;893.8882639670041;983.9409635421897;1088.613542192526;1105.237386203517;14596.562103136923;16562.58593535171;19751.308704195286;20714.275000374913;20805.375838724864;978.1148061004802;998.8551660009258;1021.2493817044466;1085.766560189563;1103.591671081689;10210.744731381626;10332.638357657446;11197.328160310408;11338.659840204808;11345.716357090974;549726;611093;623414;623560;645883;104442;118723;125481;135013.32493337666;140394.29802851126;1098.6264670012233;1101.6970333158445;1112.249231768553;1121.3216004317433;1125.3979121170134;13735.587932362045;18408.447746567625;18888.716669250247;19355.322563710204;19426.902865485583;35861.82069089638;51789.7410512945;52007.699807505116;52517;52609.47390526046;72597;72981;73210;74092.25907740854;74200.62899685468;8484.660613575636;8559.358048146289;8651.351148663749;8776.605052772513;8992.190702836777;9754.219662426847;11428.371439570903;11836.644900652995;13400.530981415604;13454.058215924792;11095.22333436684;11439.027682647069;11548.769024619292;11794.99742521861;11816.468258928197;11043.503042363274;11399.20205585601;11405.429728513707;11437.939436823155;11673;11479.483423245807;11681.532738690357;11729.237599556001;11826.76346473083;11859;8540.14598540166;8627.309815214643;8655.394122411371;8734.956325218332;8765.780855478664;8696.130386961253;8881.24509416681;9857.014298570086;10537.367757934548;10791.082757965347 23:13:06 INFO - PROCESS | 1678 | |3;mootools.html;1465.4284828916723;1647.2340361731797;1883.4632129842998;2025.4430031741279;2055.352563942357;7891.289783919446;8773.60518776655;8878.556072196381;8926.687565935194;9197.080291970808;8416.789580260496;8892.377533572653;8969.55152242387;9011.963624183221;9013.143751343629;2522.4072342999425;2540.3903063264775;2547.5796493578537;2550.5536531107073;2558.3092565007437;2504.098524531167;2570.910018149365;2571.961420578693;2576.1756238003845;2585.133980116663;2542.618607208924;2568.7944964402895;2569.100814714844;2596.597527383249;2596.792256619466;2552.1705445730117;2560.1295559509763;2571.5885458326766;2576.3687896465394;2596.545604519202;2537.9974909910484;2558.6545816314747;2577.510273048115;2589.7539733725266;2603.1409634820466;9014.774630634221;9154.267658587298;9168.954155229248;9203.033681463483;9234.538273086318;5586.581006424493;5634.661920284755;5762.596618236725;5771.855703607402;5783.392743761929;3021.8489075546354;3085.768567357457;3387.779794313362;3392.8464322130594;3700.666939975415;1058.439027315524;1072.6835583502886;1074.4466599701161;1080.1412876762993;1081.5885269723008;10935.890641093567;11554.306741595532;12325.753484930341;12351.197172183869;12361.381930903508;4925.236588328815;4952.727599981963;5043.117454445457;5043.949560504421;5075.695458272516;10432.739181520523;10436.791264174673;10439.843402348972;10447.94776026115;10468.424236667057;5586.692731899784;5663;5677.489025987681;5731.455511726379;5760.596758226884;4623.190941585276;5033.245013248042;5128.7179205144075;5162;5174.6119041071315;3476;3702.3520883845217;4212.978935105366;4343.04453020335;4368.039031413084;1230.7139039703532;1296.1445446005591;1301.6420484366874;1321.1346567997978;1418.4680744720729;859.2395729779065;961.4375590279765;978.6281213138961;984.5603043186452;1009.4195837393528;5942.732577034044;5973.701314934323;5977.701114944235;6146.754129834846;6160.845978850564;467.54881539314727;477.2626292378284;477.42947178122506;483.32576056401126;485.9368282123301;3944.3886197639413;4019.638232559084;4045.3122969095325;4052.1895620875944;4097.323941549671;10731.570737170427;11154.274972126948;11170.218084734153;11197.72005699864;11219.046381057702;10615.52230149645;10761.515731792088;10976.286541374786;11039.172062095215;11079.169062320358;8429.452085614415;8531.146885311418;8565.828683426422;8579.528125953007;8580.22777949975;5920.940790592039;5948.256467941507;5981.641101533922;6038.3357830637715;6108.541859360477;9375.812483750151;9517.904820951979;9564.378315409476;9715.07706767856;9810.607575696893;9668.661596844076;9831.360961537477;9934.801303974025;10058.949705251427;10059.647912323033;5773.393793651606;7447.515911465737;7656.5788881609815;7658.655360508679;7779.494332868346;8326.62530186131;8375.706850260212;8419.242268195892;8479.576021199038;8492.447990880573;7336.559806411632;7400.851982960418;7474.364679002346;7511.812204694927;7564.50830696003;7396.519226250277;7408.37028852532;7468.290512401313;7550.3582195514;7575.924240757522;7084.079069720903;7099.787006389816;7108.182559005573;7153.713851446092;7171.067761191011;4896.36347274852;4900.975495122501;5006.324146240211;5028.396592408934;5029.371328583927;4978.153713868579;5000.399472696006;5011.924821127759;5086.694798312114;5197.896042079213 23:13:06 INFO - PROCESS | 1678 | |4;prototype.html;323.94330992076397;334.1812559229887;343.37334364784243;343.39733767238494;343.4161924727963;6243.56295059346;6664.966675166629;6805.35349141832;6856.04602688234;6859.348361905617;5588.608797384185;6781.966090169542;6811.659417029141;6826.385625293723;6837.965810170955;5086.618503612225;5090.160123579614;5091.185410334348;5091.567216786559;5112.079825631385;5107.514786095315;5113.821016264432;5114.488551144875;5126.384833819947;5130.666506677054;5035.471275516073;5050.772715227805;5064.265681476184;5074.974625126888;5087;4758.595519380857;4907.312976183337;5031.3207716958295;5063.443021267658;5068.163752980754;3606.459031145323;4153.356229784388;4185.162967406501;4186.35111557709;4198.454200953887;5124.282600435942;5139.280500729901;6486.578372405778;6490.578112422678;6525.543211975164;4708.317293992386;5850.707464626794;5854.853628659318;5855.0678276168765;5855.531557475434;7211.314925082109;7267.63661816907;7270.709171633128;7295.708171673126;7312.451566132509;4563.954360456386;4694.624430045588;4704;4710.788014539354;4711.481737008926;7597.582132982684;7602.277783610548;7671.961640191819;7687.730929417499;7736.690532378698;4352.39066530686;4374.884404476838;4775.403074615673;4788.808447662124;4790.880227994258;3989.364360612135;4864.075825593126;4864.440589332201;4866;4878.8046928502745;4752.405949256343;5760.423957604206;5809.912851307234;5810.157527158538;5811.389804070596;7108.8933665995055;7338.633068346561;7343.412526997828;7350.558966462029;7411.703531858773;1673.2888522378184;1676.8155502894672;1690.3830102012678;1694.5932976085896;1698.3376483171573;888.5779254854021;1003.5082809423476;1057.1595581512715;1058.8147074261974;1074.387599068539;7020.368166864904;7087.220405755363;7134.464915131386;7136.536125151849;7175.282471752887;7613.733519326908;7630.160682324939;7636.50362726421;7653.6938522459595;7726.9613651932505;7179.712811487482;7195.784126476214;7196.676149573282;7204.639768011579;7237.601931893796;3909.550401703784;4029.2747305485295;4057.533383660858;4060.675145988373;4069.308217603015;8161.63272652732;8201.671933122727;8255.669773209005;8262.8347433051;8273.627686754111;7803.453758236982;7866.763997079982;7877.881831772528;7883.329916957008;7891.447598668153;8229.46508476947;8235.052968908652;8237.093919668832;8250.422470427013;8283.62723677436;7372.926270737224;7626.466147369631;7628.961855190689;7652.081750190013;7672.271134242238;7440.962795185989;7484.101907770884;7497.887531687138;7512.248775122444;7518.962405187939;8198.549079800669;8217.219364160395;8222.218889205516;8224.218699223564;8253.83492330162;4920.606351491943;5021;5026.673266237683;5027.622928280321;5028.094942910311;7216.3505284524645;7497.2877576630135;7505.849883002418;7512.32389084985;7543.811404714706;7209.71161153548;7218.783436496913;7274.854502910018;7283.271672832674;7303.452241081834;7254.492185546961;7259.455540834353;7278.6724597392185;7299.598522081336;7315.487915846053;6887.862242755217;7059.964700176466;7072.5402848814665;7095.609741464269;7124.465665075037;6691.929291313367;7034.261402552863;7337.0828646419195;7350.264973502607;7422.888656670047;5554.472325129106;5719.914201286901;5781.4218578141845;7074.257202993612;7271.636418179176 23:13:06 INFO - PROCESS | 1678 | |5;yui.html;676.313541755119;692.1901375390793;696.8536906328508;745.6681776609411;787.8778789287662;15298.388064477422;15405.768913466307;15459.45891893784;15468.690626187483;15536.223188840555;12858.71412858716;13491.5277965271;14573.05275157114;14680.486182983597;14760.778588321184;781.1875649324695;933.4725879877848;937.9108984646465;947.5688561704408;961.2214106573698;939.7227817793768;947.663579429303;958.918491928187;962.6486332488627;965.2663975378728;867.1848462445281;960.4429430930044;980.9803803923917;1004.9346792458504;1005.4972513743129;982.4792859784326;991.1129539062534;1001.1040119093407;1002.5438425516372;1003.5032658833887;717.6089031477857;733.050699344345;744.3301029073823;747.2303527366821;749.0935967479356;15129.924350378178;15388.691961183313;15444.150571718552;15473.381064757396;15490.922545387313;13220.14069085516;13340.86659133406;13384.86615133846;13441;13441.865581344158;8579.442336248125;8812.07473215316;8861.424007439497;10317.123044541224;10498.370097794157;3560.12776869666;3608.675219230282;3626.0753507855593;3633.291508155911;3689.538807649044;63128.05307920385;83098.58450707768;83823.5808820952;85914.57042714747;87327.12672873317;9811.558479868423;9819.75450613726;9823.607055717692;9842.261830362751;9887.752806179758;22295.108195672317;22354.77645223527;22890.969906354254;22923.541529169324;22941.19705810322;7483.438742094365;7625.961870190725;7629.427792915553;7684.884726729104;7816.7264145754625;4154.563770804022;4182.1008483176265;4194.266003449384;4221.451211342567;4251.319788833772;3688.6495782900583;3708.6847617952776;3764.3412402829395;3768.982374758855;3782.8108594570162;662.4501663619185;684.4011489946297;686.7596341280512;687.8864987277143;688.9311068893071;519.888224031835;592.3780030967553;618.1623899616045;619.2909119058653;623.2209737827716;8312.376571757022;8454.661813527513;8538.78653033679;8545.273651739672;8649.913500865037;514.63975217348;529.8834256463572;581.5812614917253;587.3304432946444;596.7821745063108;3733.346664333785;3748.981255093762;3936.5079365079364;3942.5268967724055;4112.609302116294;86341.70487442693;87484.5625771867;87776;88121.23757524813;90263.54868225747;58520;77246.61376693193;79141.60429197818;79943.60028199939;82776;58833.11750323749;73663;73855;74367;75695.62152189315;5954.940450595612;7210.89183662256;7415.555066696015;8716.60775265102;8758.562071896507;35214.647853521135;49501;50952;52393.47606523886;52755.73622131865;62652.373476266475;63854.36145638484;66111.66944165248;69453.65273173602;74033;11057.004869561779;11128.22102452853;11140.275882067639;11504.367259800456;11557.24877882935;11970.521179152736;12113.212641178648;12188.902998730156;12288;12317.753644926872;9147.496887671243;11793.587224447103;11911.642650720492;11949.820752688891;12012.219205751948;11719.296842189497;11956.940215298868;11970.281783093042;12065.577704780291;12123.818142728043;11220.438978051228;11244.493997769956;11313.943430282796;11390.829137562778;11480.081593472669;10035.648752293635;10067.395956242648;10202.387856728323;10342.5345859435;10394.636187733393;9927.950360248153;10139.797204056025;10139.949300253451;10146.695599132037;10165.949170254102 23:13:06 INFO - PROCESS | 1678 | __end_tp_report 23:13:06 INFO - PROCESS | 1678 | __start_cc_report 23:13:06 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,392 23:13:06 INFO - PROCESS | 1678 | __end_cc_report 23:13:06 INFO - PROCESS | 1678 | __startTimestamp1467699186223__endTimestamp 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | ------- Summary: start ------- 23:13:06 INFO - PROCESS | 1678 | Number of tests: 6 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | [#0] dojo.html Cycles:165 Average:5715.82 Median:5970.97 stddev:661.77 (11.1%) stddev-sans-first:630.69 23:13:06 INFO - PROCESS | 1678 | Values: 3080.5 3598.9 3657.7 3685.0 3696.2 5667.0 5670.4 5672.0 5674.2 5690.3 5669.9 5671.5 5683.9 5684.3 5719.9 5150.7 5164.7 5195.6 5219.1 5247.5 4885.2 5134.1 5224.9 5256.3 5261.8 4374.4 5128.7 5235.4 5257.7 5266.7 5220.6 5236.2 5255.3 5319.9 5320.0 5120.9 5185.7 5192.7 5207.0 5277.2 5589.9 5651.9 5662.8 5672.3 5676.3 4979.3 6051.7 6118.0 6143.7 6201.1 6172.1 6184.6 6192.3 6203.3 6212.2 5784.5 5807.1 5810.9 5827.4 5846.4 6125.3 6218.9 6221.6 6222.0 6238.2 4954.4 6085.0 6129.5 6132.9 6133.8 6182.5 6238.7 6251.2 6255.7 6261.6 6123.5 6184.0 6197.4 6232.8 6246.3 6220.5 6235.1 6239.3 6247.1 6249.2 4068.6 4267.9 4878.1 4936.2 4955.0 4479.6 4485.4 4485.7 4490.1 4504.6 4852.3 5944.3 5987.3 5997.2 6013.2 6115.3 6147.7 6243.2 6253.1 6253.3 4719.6 5960.9 6006.0 6034.9 6099.7 5393.9 5424.8 5450.5 5454.6 5465.0 5225.8 6402.0 6418.0 6451.7 6509.6 6251.8 6278.7 6283.3 6292.3 6309.1 6460.9 6492.6 6508.4 6523.6 6543.2 6034.0 6102.1 6145.4 6213.2 6232.8 5854.9 6240.5 6255.4 6258.0 6290.2 4842.8 6121.6 6129.5 6137.4 6142.6 5874.5 6077.5 6080.2 6116.9 6142.1 5928.3 5937.2 5939.1 5970.3 5971.0 6026.5 6131.9 6139.1 6142.2 6142.9 5831.8 5917.6 5930.4 5988.5 6028.8 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | [#1] ext.html Cycles:180 Average:62083.55 Median:19746.04 stddev:137056.59 (694.1%) stddev-sans-first:137363.10 23:13:06 INFO - PROCESS | 1678 | Values: 511.7 531.0 581.2 601.9 615.9 36702.0 38262.4 38285.9 38297.8 38323.8 41519.0 41586.8 41676.7 41912.0 42055.8 9682.6 9850.0 9964.6 9983.7 10015.2 9911.5 9914.7 9981.0 10006.9 10043.5 9958.1 10043.5 10044.5 10059.7 10060.5 9917.3 10000.1 10001.5 10014.9 10053.2 9805.2 9806.5 9919.8 9942.7 9945.6 33854.7 39668.4 40018.6 40132.0 40208.6 3086.2 3226.3 3274.6 3353.1 3432.8 27665.7 27749.4 27749.9 27985.7 28281.0 2661.2 2692.5 2705.6 2733.6 2835.4 307083.0 307332.0 308434.0 309309.0 309924.0 35622.3 35973.3 36132.8 37383.3 37916.8 36868.3 36885.9 37108.8 37217.1 37225.3 16365.8 16548.8 16580.3 16584.0 16588.0 13851.4 14239.4 14254.1 14291.0 14347.4 8441.7 8539.7 8570.4 8590.7 8643.8 3536.2 3559.0 3587.8 3603.3 3612.3 972.5 1046.1 1087.0 1120.3 1149.1 29089.0 29169.9 29697.0 29803.0 29882.3 1149.0 1161.6 1197.3 1214.2 1223.7 14825.0 15129.5 15175.5 15296.5 15415.7 602302.0 606992.0 611877.0 613929.0 614520.0 60255.0 60936.0 61242.8 61715.0 61836.0 1308.8 1530.5 1532.8 1539.7 1559.6 28978.0 29410.7 29476.6 29934.0 30100.5 50222.2 51235.0 51264.5 51397.2 51419.0 476744.0 578552.0 579661.0 581376.0 593096.0 27281.6 27433.6 27531.4 27640.2 28075.2 45594.0 46575.3 47181.6 47461.3 47807.3 38224.2 38842.4 39066.6 40250.6 41008.6 35904.3 36966.0 38101.0 38970.8 39788.0 35437.8 36450.6 36828.0 37236.0 37334.8 31211.7 31800.2 31866.5 32099.7 32120.4 24398.8 24416.0 24860.0 24873.4 25197.4 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | [#2] jquery.html Cycles:180 Average:34745.01 Median:12195.32 stddev:101562.31 (832.8%) stddev-sans-first:101814.39 23:13:06 INFO - PROCESS | 1678 | Values: 352.5 410.5 414.3 420.1 420.2 20965.0 21237.9 21739.9 21773.9 22182.2 21716.7 22000.4 22640.4 22931.5 23033.2 5168.8 5766.6 6218.2 6239.4 6256.5 5727.3 5727.8 5786.5 5839.7 5855.5 5385.9 5650.3 6196.8 6202.1 6210.5 6092.4 6178.8 6187.2 6192.7 6206.3 5465.2 5667.5 5749.9 5750.4 5750.9 13807.7 16470.9 25410.0 26179.0 26302.1 2120.1 2139.1 2208.4 2450.7 2861.3 9965.1 10243.1 10993.6 11845.5 15004.7 1566.3 1681.3 1821.5 1850.2 1857.2 101717.5 104814.9 106444.5 107037.0 108267.0 11903.5 12000.5 12038.4 12144.6 12219.6 15322.2 15338.8 15343.9 15370.1 15408.4 8254.6 8600.8 9075.0 9223.3 9304.0 7923.0 8680.4 9248.7 9270.7 9280.4 3910.4 4693.4 4744.9 4758.6 4821.3 1771.5 1958.8 2006.0 2053.3 2096.5 825.8 893.9 983.9 1088.6 1105.2 14596.6 16562.6 19751.3 20714.3 20805.4 978.1 998.9 1021.2 1085.8 1103.6 10210.7 10332.6 11197.3 11338.7 11345.7 549726.0 611093.0 623414.0 623560.0 645883.0 104442.0 118723.0 125481.0 135013.3 140394.3 1098.6 1101.7 1112.2 1121.3 1125.4 13735.6 18408.4 18888.7 19355.3 19426.9 35861.8 51789.7 52007.7 52517.0 52609.5 72597.0 72981.0 73210.0 74092.3 74200.6 8484.7 8559.4 8651.4 8776.6 8992.2 9754.2 11428.4 11836.6 13400.5 13454.1 11095.2 11439.0 11548.8 11795.0 11816.5 11043.5 11399.2 11405.4 11437.9 11673.0 11479.5 11681.5 11729.2 11826.8 11859.0 8540.1 8627.3 8655.4 8735.0 8765.8 8696.1 8881.2 9857.0 10537.4 10791.1 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | [#3] mootools.html Cycles:180 Average:5838.95 Median:5009.12 stddev:3241.79 (64.7%) stddev-sans-first:3234.22 23:13:06 INFO - PROCESS | 1678 | Values: 1465.4 1647.2 1883.5 2025.4 2055.4 7891.3 8773.6 8878.6 8926.7 9197.1 8416.8 8892.4 8969.6 9012.0 9013.1 2522.4 2540.4 2547.6 2550.6 2558.3 2504.1 2570.9 2572.0 2576.2 2585.1 2542.6 2568.8 2569.1 2596.6 2596.8 2552.2 2560.1 2571.6 2576.4 2596.5 2538.0 2558.7 2577.5 2589.8 2603.1 9014.8 9154.3 9169.0 9203.0 9234.5 5586.6 5634.7 5762.6 5771.9 5783.4 3021.8 3085.8 3387.8 3392.8 3700.7 1058.4 1072.7 1074.4 1080.1 1081.6 10935.9 11554.3 12325.8 12351.2 12361.4 4925.2 4952.7 5043.1 5043.9 5075.7 10432.7 10436.8 10439.8 10447.9 10468.4 5586.7 5663.0 5677.5 5731.5 5760.6 4623.2 5033.2 5128.7 5162.0 5174.6 3476.0 3702.4 4213.0 4343.0 4368.0 1230.7 1296.1 1301.6 1321.1 1418.5 859.2 961.4 978.6 984.6 1009.4 5942.7 5973.7 5977.7 6146.8 6160.8 467.5 477.3 477.4 483.3 485.9 3944.4 4019.6 4045.3 4052.2 4097.3 10731.6 11154.3 11170.2 11197.7 11219.0 10615.5 10761.5 10976.3 11039.2 11079.2 8429.5 8531.1 8565.8 8579.5 8580.2 5920.9 5948.3 5981.6 6038.3 6108.5 9375.8 9517.9 9564.4 9715.1 9810.6 9668.7 9831.4 9934.8 10058.9 10059.6 5773.4 7447.5 7656.6 7658.7 7779.5 8326.6 8375.7 8419.2 8479.6 8492.4 7336.6 7400.9 7474.4 7511.8 7564.5 7396.5 7408.4 7468.3 7550.4 7575.9 7084.1 7099.8 7108.2 7153.7 7171.1 4896.4 4901.0 5006.3 5028.4 5029.4 4978.2 5000.4 5011.9 5086.7 5197.9 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | [#4] prototype.html Cycles:180 Average:5975.87 Median:6847.01 stddev:1968.81 (28.8%) stddev-sans-first:1928.09 23:13:06 INFO - PROCESS | 1678 | Values: 323.9 334.2 343.4 343.4 343.4 6243.6 6665.0 6805.4 6856.0 6859.3 5588.6 6782.0 6811.7 6826.4 6838.0 5086.6 5090.2 5091.2 5091.6 5112.1 5107.5 5113.8 5114.5 5126.4 5130.7 5035.5 5050.8 5064.3 5075.0 5087.0 4758.6 4907.3 5031.3 5063.4 5068.2 3606.5 4153.4 4185.2 4186.4 4198.5 5124.3 5139.3 6486.6 6490.6 6525.5 4708.3 5850.7 5854.9 5855.1 5855.5 7211.3 7267.6 7270.7 7295.7 7312.5 4564.0 4694.6 4704.0 4710.8 4711.5 7597.6 7602.3 7672.0 7687.7 7736.7 4352.4 4374.9 4775.4 4788.8 4790.9 3989.4 4864.1 4864.4 4866.0 4878.8 4752.4 5760.4 5809.9 5810.2 5811.4 7108.9 7338.6 7343.4 7350.6 7411.7 1673.3 1676.8 1690.4 1694.6 1698.3 888.6 1003.5 1057.2 1058.8 1074.4 7020.4 7087.2 7134.5 7136.5 7175.3 7613.7 7630.2 7636.5 7653.7 7727.0 7179.7 7195.8 7196.7 7204.6 7237.6 3909.6 4029.3 4057.5 4060.7 4069.3 8161.6 8201.7 8255.7 8262.8 8273.6 7803.5 7866.8 7877.9 7883.3 7891.4 8229.5 8235.1 8237.1 8250.4 8283.6 7372.9 7626.5 7629.0 7652.1 7672.3 7441.0 7484.1 7497.9 7512.2 7519.0 8198.5 8217.2 8222.2 8224.2 8253.8 4920.6 5021.0 5026.7 5027.6 5028.1 7216.4 7497.3 7505.8 7512.3 7543.8 7209.7 7218.8 7274.9 7283.3 7303.5 7254.5 7259.5 7278.7 7299.6 7315.5 6887.9 7060.0 7072.5 7095.6 7124.5 6691.9 7034.3 7337.1 7350.3 7422.9 5554.5 5719.9 5781.4 7074.3 7271.6 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - PROCESS | 1678 | [#5] yui.html Cycles:180 Average:18216.92 Median:52574.61 stddev:25277.27 (48.1%) stddev-sans-first:25313.87 23:13:06 INFO - PROCESS | 1678 | Values: 676.3 692.2 696.9 745.7 787.9 15298.4 15405.8 15459.5 15468.7 15536.2 12858.7 13491.5 14573.1 14680.5 14760.8 781.2 933.5 937.9 947.6 961.2 939.7 947.7 958.9 962.6 965.3 867.2 960.4 981.0 1004.9 1005.5 982.5 991.1 1001.1 1002.5 1003.5 717.6 733.1 744.3 747.2 749.1 15129.9 15388.7 15444.2 15473.4 15490.9 13220.1 13340.9 13384.9 13441.0 13441.9 8579.4 8812.1 8861.4 10317.1 10498.4 3560.1 3608.7 3626.1 3633.3 3689.5 63128.1 83098.6 83823.6 85914.6 87327.1 9811.6 9819.8 9823.6 9842.3 9887.8 22295.1 22354.8 22891.0 22923.5 22941.2 7483.4 7626.0 7629.4 7684.9 7816.7 4154.6 4182.1 4194.3 4221.5 4251.3 3688.6 3708.7 3764.3 3769.0 3782.8 662.5 684.4 686.8 687.9 688.9 519.9 592.4 618.2 619.3 623.2 8312.4 8454.7 8538.8 8545.3 8649.9 514.6 529.9 581.6 587.3 596.8 3733.3 3749.0 3936.5 3942.5 4112.6 86341.7 87484.6 87776.0 88121.2 90263.5 58520.0 77246.6 79141.6 79943.6 82776.0 58833.1 73663.0 73855.0 74367.0 75695.6 5954.9 7210.9 7415.6 8716.6 8758.6 35214.6 49501.0 50952.0 52393.5 52755.7 62652.4 63854.4 66111.7 69453.7 74033.0 11057.0 11128.2 11140.3 11504.4 11557.2 11970.5 12113.2 12188.9 12288.0 12317.8 9147.5 11793.6 11911.6 11949.8 12012.2 11719.3 11956.9 11970.3 12065.6 12123.8 11220.4 11244.5 11313.9 11390.8 11480.1 10035.6 10067.4 10202.4 10342.5 10394.6 9928.0 10139.8 10139.9 10146.7 10165.9 23:13:06 INFO - PROCESS | 1678 | -------- Summary: end -------- 23:13:06 INFO - PROCESS | 1678 | 23:13:06 INFO - TEST-INFO | 1678: exit 0 23:13:06 INFO - TEST-OK | dromaeo_css | took 1109943ms 23:13:06 INFO - TEST-START | kraken 23:13:06 INFO - operating with platform_type : mac_ 23:13:06 INFO - Initialising browser for kraken test... 23:13:06 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpw1Yylo/profile http://localhost:49279/getInfo.html) 23:13:09 INFO - PROCESS | 1686 | __metrics Screen width/height:1600/1200 23:13:09 INFO - PROCESS | 1686 | colorDepth:24 23:13:09 INFO - PROCESS | 1686 | Browser inner width/height: 1024/689 23:13:09 INFO - PROCESS | 1686 | __metrics 23:13:09 ERROR - PROCESS | 1686 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 23:13:09 INFO - TEST-INFO | 1686: exit 0 23:13:09 INFO - Browser initialized. 23:13:09 INFO - Running cycle 1/1 for kraken test... 23:13:09 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpw1Yylo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:21 INFO - PROCESS | 1690 | RSS: Main: 171163648 23:13:21 INFO - PROCESS | 1690 | 23:13:24 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 23:13:25 INFO - PROCESS | 1690 | RSS: Main: 183836672 23:13:25 INFO - PROCESS | 1690 | 23:13:28 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 23:13:28 INFO - PROCESS | 1690 | RSS: Main: 192512000 23:13:28 INFO - PROCESS | 1690 | 23:13:31 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 23:13:32 INFO - PROCESS | 1690 | RSS: Main: 196554752 23:13:32 INFO - PROCESS | 1690 | 23:13:34 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 23:13:35 INFO - PROCESS | 1690 | RSS: Main: 196816896 23:13:35 INFO - PROCESS | 1690 | 23:13:37 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:13:37 INFO - PROCESS | 1690 | RSS: Main: 196587520 23:13:37 INFO - PROCESS | 1690 | 23:13:40 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 23:13:41 INFO - PROCESS | 1690 | RSS: Main: 198475776 23:13:41 INFO - PROCESS | 1690 | 23:13:44 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 23:13:45 INFO - PROCESS | 1690 | RSS: Main: 198397952 23:13:45 INFO - PROCESS | 1690 | 23:13:47 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 23:13:48 INFO - PROCESS | 1690 | RSS: Main: 198561792 23:13:48 INFO - PROCESS | 1690 | 23:13:50 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:13:50 INFO - PROCESS | 1690 | RSS: Main: 197943296 23:13:50 INFO - PROCESS | 1690 | 23:13:52 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:13:53 INFO - PROCESS | 1690 | RSS: Main: 197914624 23:13:53 INFO - PROCESS | 1690 | 23:13:55 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:13:56 INFO - PROCESS | 1690 | RSS: Main: 198197248 23:13:56 INFO - PROCESS | 1690 | 23:13:59 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:13:59 INFO - PROCESS | 1690 | RSS: Main: 198385664 23:13:59 INFO - PROCESS | 1690 | 23:14:02 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:14:03 INFO - PROCESS | 1690 | RSS: Main: 198250496 23:14:03 INFO - PROCESS | 1690 | 23:14:04 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 23:14:05 INFO - PROCESS | 1690 | RSS: Main: 199897088 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | __start_tp_report 23:14:05 INFO - PROCESS | 1690 | _x_x_mozilla_page_load 23:14:05 INFO - PROCESS | 1690 | _x_x_mozilla_page_load_details 23:14:05 INFO - PROCESS | 1690 | |i|pagename|runs| 23:14:05 INFO - PROCESS | 1690 | |0;ai-astar;126;160;117;116;120;131;116;116;115;118 23:14:05 INFO - PROCESS | 1690 | |1;audio-beat-detection;147;155;143;198;151;170;139;120;166;120 23:14:05 INFO - PROCESS | 1690 | |2;audio-dft;196;196;193;196;192;198;199;193;197;196 23:14:05 INFO - PROCESS | 1690 | |3;audio-fft;68;79;72;143;57;55;57;66;124;56 23:14:05 INFO - PROCESS | 1690 | |4;audio-oscillator;91;122;159;113;77;81;148;116;115;121 23:14:05 INFO - PROCESS | 1690 | |5;imaging-gaussian-blur;90;89;90;88;91;103;78;120;135;87 23:14:05 INFO - PROCESS | 1690 | |6;imaging-darkroom;142;138;95;96;95;97;98;119;150;105 23:14:05 INFO - PROCESS | 1690 | |7;imaging-desaturate;92;131;85;75;97;103;133;144;111;92 23:14:05 INFO - PROCESS | 1690 | |8;json-parse-financial;60;63;63;63;63;64;64;82;71;72 23:14:05 INFO - PROCESS | 1690 | |9;json-stringify-tinderbox;63;68;56;92;105;104;99;67;67;96 23:14:05 INFO - PROCESS | 1690 | |10;stanford-crypto-aes;81;93;102;81;80;82;82;83;82;84 23:14:05 INFO - PROCESS | 1690 | |11;stanford-crypto-ccm;128;125;127;127;148;120;139;150;200;153 23:14:05 INFO - PROCESS | 1690 | |12;stanford-crypto-pbkdf2;164;165;160;162;164;165;164;164;167;163 23:14:05 INFO - PROCESS | 1690 | |13;stanford-crypto-sha256-iterative;69;92;91;93;97;60;68;95;104;96 23:14:05 INFO - PROCESS | 1690 | __end_tp_report 23:14:05 INFO - PROCESS | 1690 | __start_cc_report 23:14:05 INFO - PROCESS | 1690 | _x_x_mozilla_cycle_collect,1037 23:14:05 INFO - PROCESS | 1690 | __end_cc_report 23:14:05 INFO - PROCESS | 1690 | __startTimestamp1467699245148__endTimestamp 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | ------- Summary: start ------- 23:14:05 INFO - PROCESS | 1690 | Number of tests: 14 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#0] ai-astar Cycles:10 Average:123.50 Median:119.00 stddev:13.83 (11.6%) stddev-sans-first:14.64 23:14:05 INFO - PROCESS | 1690 | Values: 126.0 160.0 117.0 116.0 120.0 131.0 116.0 116.0 115.0 118.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:153.00 stddev:23.47 (15.3%) stddev-sans-first:24.85 23:14:05 INFO - PROCESS | 1690 | Values: 147.0 155.0 143.0 198.0 151.0 170.0 139.0 120.0 166.0 120.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#2] audio-dft Cycles:10 Average:195.60 Median:196.00 stddev:2.27 (1.2%) stddev-sans-first:2.40 23:14:05 INFO - PROCESS | 1690 | Values: 196.0 196.0 193.0 196.0 192.0 198.0 199.0 193.0 197.0 196.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#3] audio-fft Cycles:10 Average:77.70 Median:61.50 stddev:30.76 (50.0%) stddev-sans-first:32.43 23:14:05 INFO - PROCESS | 1690 | Values: 68.0 79.0 72.0 143.0 57.0 55.0 57.0 66.0 124.0 56.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#4] audio-oscillator Cycles:10 Average:114.30 Median:153.50 stddev:26.43 (17.2%) stddev-sans-first:26.65 23:14:05 INFO - PROCESS | 1690 | Values: 91.0 122.0 159.0 113.0 77.0 81.0 148.0 116.0 115.0 121.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:88.50 stddev:17.48 (19.7%) stddev-sans-first:18.35 23:14:05 INFO - PROCESS | 1690 | Values: 90.0 89.0 90.0 88.0 91.0 103.0 78.0 120.0 135.0 87.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#6] imaging-darkroom Cycles:10 Average:113.50 Median:95.00 stddev:21.99 (23.1%) stddev-sans-first:20.76 23:14:05 INFO - PROCESS | 1690 | Values: 142.0 138.0 95.0 96.0 95.0 97.0 98.0 119.0 150.0 105.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#7] imaging-desaturate Cycles:10 Average:106.30 Median:80.00 stddev:22.87 (28.6%) stddev-sans-first:23.66 23:14:05 INFO - PROCESS | 1690 | Values: 92.0 131.0 85.0 75.0 97.0 103.0 133.0 144.0 111.0 92.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:64.00 stddev:6.62 (10.3%) stddev-sans-first:6.59 23:14:05 INFO - PROCESS | 1690 | Values: 60.0 63.0 63.0 63.0 63.0 64.0 64.0 82.0 71.0 72.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#9] json-stringify-tinderbox Cycles:10 Average:81.70 Median:67.50 stddev:19.09 (28.3%) stddev-sans-first:19.01 23:14:05 INFO - PROCESS | 1690 | Values: 63.0 68.0 56.0 92.0 105.0 104.0 99.0 67.0 67.0 96.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:82.00 stddev:7.01 (8.5%) stddev-sans-first:7.28 23:14:05 INFO - PROCESS | 1690 | Values: 81.0 93.0 102.0 81.0 80.0 82.0 82.0 83.0 82.0 84.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:143.50 stddev:23.60 (16.4%) stddev-sans-first:24.50 23:14:05 INFO - PROCESS | 1690 | Values: 128.0 125.0 127.0 127.0 148.0 120.0 139.0 150.0 200.0 153.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.00 stddev:1.87 (1.1%) stddev-sans-first:1.99 23:14:05 INFO - PROCESS | 1690 | Values: 164.0 165.0 160.0 162.0 164.0 165.0 164.0 164.0 167.0 163.0 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - PROCESS | 1690 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:86.50 Median:92.50 stddev:14.99 (16.2%) stddev-sans-first:14.50 23:14:05 INFO - PROCESS | 1690 | Values: 69.0 92.0 91.0 93.0 97.0 60.0 68.0 95.0 104.0 96.0 23:14:05 INFO - PROCESS | 1690 | -------- Summary: end -------- 23:14:05 INFO - PROCESS | 1690 | 23:14:05 INFO - TEST-INFO | 1690: exit 0 23:14:05 INFO - TEST-OK | kraken | took 59169ms 23:14:05 INFO - SUITE-END | took 1169s 23:14:06 INFO - Completed test suite (00:19:29) 23:14:06 INFO - javascript benchmark 23:14:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3080.507118860982, 3598.8740394086194, 3657.7439579229463, 3685.0, 3696.20531585709, 5667.008273552133, 5670.432956704328, 5672.035753921833, 5674.233978412918, 5690.2887139107615, 5669.914951275724, 5671.546276297897, 5683.8579035524135, 5684.289463817023, 5719.914201286973, 5150.690958542481, 5164.715940623264, 5195.584353251731, 5219.13884209107, 5247.527722504974, 4885.193942999419, 5134.101532231844, 5224.895502089956, 5256.26412302278, 5261.76322065508, 4374.365716971038, 5128.717820544864, 5235.397929238148, 5257.737113144347, 5266.7366631668265, 5220.6084543659, 5236.188390799432, 5255.290535777678, 5319.9202011969855, 5320.042392369372, 5120.897582048338, 5185.662931909452, 5192.71440070796, 5207.0366982108435, 5277.1820367842665, 5589.944100558983, 5651.9152212716845, 5662.801801936913, 5672.319321681424, 5676.2620859288445, 4979.302897594303, 6051.697415129226, 6118.010103775246, 6143.665407145256, 6201.131841542187, 6172.07418887166, 6184.598001129957, 6192.318844927055, 6203.31763506014, 6212.223472065992, 5784.4504772046585, 5807.128930660435, 5810.941890581083, 5827.388124246978, 5846.444587764156, 6125.296497837281, 6218.9378106218355, 6221.564490485713, 6222.0, 6238.189035425366, 4954.405471343462, 6085.0, 6129.478994285536, 6132.938670613326, 6133.815985520441, 6182.536309776785, 6238.688065596702, 6251.187345645037, 6255.655938923403, 6261.592996455216, 6123.465646421213, 6183.96908015466, 6197.411245931719, 6232.750689972441, 6246.250449946036, 6220.471259942865, 6235.064740288993, 6239.282482514569, 6247.125402443661, 6249.218847644044, 4068.572799856032, 4267.933016745814, 4878.097551952829, 4936.160852655057, 4955.0, 4479.596836284685, 4485.372047913294, 4485.730856148642, 4490.146872094291, 4504.594586487232, 4852.296417019513, 5944.256967879015, 5987.251593550806, 5997.190379298826, 6013.218281623448, 6115.301188036035, 6147.73971335884, 6243.188385509855, 6253.093301471261, 6253.343398943137, 4719.646026548023, 5960.910586341209, 6005.969970150121, 6034.909476357858, 6099.664518451527, 5393.892122157535, 5424.783008679688, 5450.5367043800925, 5454.59090568201, 5465.016297066566, 5225.817096401608, 6402.0, 6417.97312430009, 6451.677416129269, 6509.641969691617, 6251.78118765841, 6278.748850045947, 6283.277423096402, 6292.276388215414, 6309.085182648399, 6460.935390646034, 6492.577982431127, 6508.414242718178, 6523.608583485006, 6543.247526534319, 6033.969830150997, 6102.115193296859, 6145.432914606747, 6213.1922850031015, 6232.8130156095385, 5854.941450585439, 6240.450582701727, 6255.40573645503, 6258.0, 6290.21372328459, 4842.757862106951, 6121.602095863755, 6129.509639228761, 6137.355577664281, 6142.570020098729, 5874.471297583238, 6077.453029227391, 6080.209572755513, 6116.914073580098, 6142.140100386039, 5928.258967629046, 5937.168796368598, 5939.07944268639, 5970.3432622412365, 5970.9701451492465, 6026.548008899263, 6131.938680613137, 6139.109829074849, 6142.170806941006, 6142.938570614237, 5831.7667293307795, 5917.556183286357, 5930.436608522184, 5988.4909782668965, 6028.75884964614], "value": 5677.3107225633485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.74157050689394, 530.9707966061867, 581.1808948026593, 601.9428154325337, 615.8891399548086, 36702.0, 38262.42606360907, 38285.85142445724, 38297.80851095744, 38323.80838095813, 41519.0, 41586.79206603971, 41676.74969750905, 41911.95220119499, 42055.789721051355, 9682.56428460721, 9849.950750246238, 9964.551595178234, 9983.650572229973, 10015.248856335804, 9911.454869982148, 9914.652987145451, 9980.953904449607, 10006.949965250164, 10043.497825108752, 9958.053984871425, 10043.497825108752, 10044.497775111251, 10059.748506287366, 10060.496975151249, 9917.256205784595, 10000.149987251092, 10001.499925003793, 10014.899851001506, 10053.246006549538, 9805.166560842337, 9806.460644664541, 9919.752006199831, 9942.701718948443, 9945.552450139761, 33854.66145338564, 39668.40497392541, 40018.59981400178, 40132.0, 40208.59791402078, 3086.2438702518025, 3226.3063441359986, 3274.607047154333, 3353.0611428799975, 3432.828358582061, 27665.723342766516, 27749.445011099866, 27749.8900043998, 27985.720142798516, 28281.0, 2661.175035738927, 2692.5018871508637, 2705.648265725463, 2733.58996150577, 2835.4470878178763, 307083.0, 307332.0, 308434.0, 309309.0, 309924.0, 35622.28755424877, 35973.28053438917, 36132.81933590315, 37383.25233495315, 37916.810415947744, 36868.26263474769, 36885.89342319735, 37108.814455928084, 37217.06957326034, 37225.25549488995, 16365.83634163667, 16548.75176872348, 16580.253888575044, 16584.0, 16588.0, 13851.376688049062, 14239.359228834728, 14254.144751314747, 14291.0, 14347.354369053417, 8441.746747597583, 8539.74380768578, 8570.4000719949, 8590.699325523577, 8643.827123457622, 3536.1513236822975, 3558.967899309171, 3587.802670853076, 3603.3153700796765, 3612.3497770401054, 972.5088830140875, 1046.1108058150662, 1086.9891301086889, 1120.2662256222188, 1149.1209224942925, 29089.0, 29169.854150729112, 29697.0, 29803.0, 29882.252943676147, 1148.9770204595861, 1161.6456980620824, 1197.257700225848, 1214.1743614342156, 1223.6818427208989, 14825.036372635745, 15129.546113616609, 15175.468858589897, 15296.464623738339, 15415.691686166438, 602302.0, 606992.0, 611877.0, 613929.0, 614520.0, 60255.0, 60936.0, 61242.77514449686, 61715.0, 61836.0, 1308.7971364438338, 1530.4643374818725, 1532.831388547259, 1539.722849887031, 1559.5789136933192, 28978.0, 29410.705892940798, 29476.55785163267, 29934.0, 30100.548491772202, 50222.2466662993, 51235.0, 51264.487355127465, 51397.22904156515, 51419.0, 476744.0, 578552.0, 579661.0, 581376.0, 593096.0, 27281.59077613798, 27433.58849617297, 27531.44937101287, 27640.170794876187, 28075.157745267676, 45594.0, 46575.30137048015, 47181.58455246348, 47461.28808067951, 47807.282890755974, 38224.23551529009, 38842.41736374013, 39066.60933390629, 40250.59749402585, 41008.58991410048, 35904.28191436209, 36966.0, 38101.0, 38970.83087507379, 39788.0, 35437.82281088578, 36450.635493644724, 36828.0, 37236.0, 37334.813325933195, 31211.687883120878, 31800.204994875312, 31866.522002169524, 32099.679003210604, 32120.35759284848, 24398.756012439648, 24416.0, 24860.0, 24873.378165546008, 25197.370065748502], "value": 18468.786006745082, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.53289391556194, 410.4540960522505, 414.297765287837, 420.0737374090132, 420.23936674618943, 20964.951752412377, 21237.893810530924, 21739.891300543495, 21773.911304434776, 22182.223622173187, 21716.674249886226, 22000.44998875029, 22640.43398915028, 22931.541369172606, 23033.193838215666, 5168.8449346519665, 5766.625169363989, 6218.191635087433, 6239.438450539451, 6256.499480041586, 5727.284089488814, 5727.799527016556, 5786.537077033827, 5839.737211825457, 5855.47300742933, 5385.892282154355, 5650.3219613646215, 6196.81409557714, 6202.069689546582, 6210.534209934251, 6092.390760923917, 6178.84552886177, 6187.226596675416, 6192.690365481708, 6206.317305096436, 5465.152901300305, 5667.489925906688, 5749.942500574983, 5750.367459579443, 5750.856228594277, 13807.723845523033, 16470.917645411817, 25410.0, 26178.952841886494, 26302.079427219956, 2120.120150137691, 2139.144342263092, 2208.4147700859285, 2450.7304196538366, 2861.2846788302923, 9965.10314071737, 10243.129334006544, 10993.61522346714, 11845.526178952832, 15004.699906001819, 1566.302995167151, 1681.2938565802392, 1821.4899828048167, 1850.2136591948367, 1857.1642760757713, 101717.4914125432, 104814.90370192629, 106444.4677776614, 107037.0, 108267.0, 11903.523859045541, 12000.519979200908, 12038.39808009596, 12144.574939877239, 12219.633410997685, 15322.23388830554, 15338.769918451233, 15343.92328038375, 15370.077795332316, 15408.383664653289, 8254.637984732572, 8600.838886750334, 9074.954625226832, 9223.262139028862, 9303.953480232556, 7923.009623797026, 8680.392372533863, 9248.722538323862, 9270.67552635668, 9280.350375473652, 3910.354791459434, 4693.413323334583, 4744.857654270378, 4758.643101767381, 4821.252705830602, 1771.5305444057335, 1958.8041195880298, 2005.9899700501696, 2053.260826102602, 2096.549241912996, 825.8265764189588, 893.8882639670041, 983.9409635421897, 1088.613542192526, 1105.237386203517, 14596.562103136923, 16562.58593535171, 19751.308704195286, 20714.275000374913, 20805.375838724864, 978.1148061004802, 998.8551660009258, 1021.2493817044466, 1085.766560189563, 1103.591671081689, 10210.744731381626, 10332.638357657446, 11197.328160310408, 11338.659840204808, 11345.716357090974, 549726.0, 611093.0, 623414.0, 623560.0, 645883.0, 104442.0, 118723.0, 125481.0, 135013.32493337666, 140394.29802851126, 1098.6264670012233, 1101.6970333158445, 1112.249231768553, 1121.3216004317433, 1125.3979121170134, 13735.587932362045, 18408.447746567625, 18888.716669250247, 19355.322563710204, 19426.902865485583, 35861.82069089638, 51789.7410512945, 52007.699807505116, 52517.0, 52609.47390526046, 72597.0, 72981.0, 73210.0, 74092.25907740854, 74200.62899685468, 8484.660613575636, 8559.358048146289, 8651.351148663749, 8776.605052772513, 8992.190702836777, 9754.219662426847, 11428.371439570903, 11836.644900652995, 13400.530981415604, 13454.058215924792, 11095.22333436684, 11439.027682647069, 11548.769024619292, 11794.99742521861, 11816.468258928197, 11043.503042363274, 11399.20205585601, 11405.429728513707, 11437.939436823155, 11673.0, 11479.483423245807, 11681.532738690357, 11729.237599556001, 11826.76346473083, 11859.0, 8540.14598540166, 8627.309815214643, 8655.394122411371, 8734.956325218332, 8765.780855478664, 8696.130386961253, 8881.24509416681, 9857.014298570086, 10537.367757934548, 10791.082757965347], "value": 9679.371286895115, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.4284828916723, 1647.2340361731797, 1883.4632129842998, 2025.4430031741279, 2055.352563942357, 7891.289783919446, 8773.60518776655, 8878.556072196381, 8926.687565935194, 9197.080291970808, 8416.789580260496, 8892.377533572653, 8969.55152242387, 9011.963624183221, 9013.143751343629, 2522.4072342999425, 2540.3903063264775, 2547.5796493578537, 2550.5536531107073, 2558.3092565007437, 2504.098524531167, 2570.910018149365, 2571.961420578693, 2576.1756238003845, 2585.133980116663, 2542.618607208924, 2568.7944964402895, 2569.100814714844, 2596.597527383249, 2596.792256619466, 2552.1705445730117, 2560.1295559509763, 2571.5885458326766, 2576.3687896465394, 2596.545604519202, 2537.9974909910484, 2558.6545816314747, 2577.510273048115, 2589.7539733725266, 2603.1409634820466, 9014.774630634221, 9154.267658587298, 9168.954155229248, 9203.033681463483, 9234.538273086318, 5586.581006424493, 5634.661920284755, 5762.596618236725, 5771.855703607402, 5783.392743761929, 3021.8489075546354, 3085.768567357457, 3387.779794313362, 3392.8464322130594, 3700.666939975415, 1058.439027315524, 1072.6835583502886, 1074.4466599701161, 1080.1412876762993, 1081.5885269723008, 10935.890641093567, 11554.306741595532, 12325.753484930341, 12351.197172183869, 12361.381930903508, 4925.236588328815, 4952.727599981963, 5043.117454445457, 5043.949560504421, 5075.695458272516, 10432.739181520523, 10436.791264174673, 10439.843402348972, 10447.94776026115, 10468.424236667057, 5586.692731899784, 5663.0, 5677.489025987681, 5731.455511726379, 5760.596758226884, 4623.190941585276, 5033.245013248042, 5128.7179205144075, 5162.0, 5174.6119041071315, 3476.0, 3702.3520883845217, 4212.978935105366, 4343.04453020335, 4368.039031413084, 1230.7139039703532, 1296.1445446005591, 1301.6420484366874, 1321.1346567997978, 1418.4680744720729, 859.2395729779065, 961.4375590279765, 978.6281213138961, 984.5603043186452, 1009.4195837393528, 5942.732577034044, 5973.701314934323, 5977.701114944235, 6146.754129834846, 6160.845978850564, 467.54881539314727, 477.2626292378284, 477.42947178122506, 483.32576056401126, 485.9368282123301, 3944.3886197639413, 4019.638232559084, 4045.3122969095325, 4052.1895620875944, 4097.323941549671, 10731.570737170427, 11154.274972126948, 11170.218084734153, 11197.72005699864, 11219.046381057702, 10615.52230149645, 10761.515731792088, 10976.286541374786, 11039.172062095215, 11079.169062320358, 8429.452085614415, 8531.146885311418, 8565.828683426422, 8579.528125953007, 8580.22777949975, 5920.940790592039, 5948.256467941507, 5981.641101533922, 6038.3357830637715, 6108.541859360477, 9375.812483750151, 9517.904820951979, 9564.378315409476, 9715.07706767856, 9810.607575696893, 9668.661596844076, 9831.360961537477, 9934.801303974025, 10058.949705251427, 10059.647912323033, 5773.393793651606, 7447.515911465737, 7656.5788881609815, 7658.655360508679, 7779.494332868346, 8326.62530186131, 8375.706850260212, 8419.242268195892, 8479.576021199038, 8492.447990880573, 7336.559806411632, 7400.851982960418, 7474.364679002346, 7511.812204694927, 7564.50830696003, 7396.519226250277, 7408.37028852532, 7468.290512401313, 7550.3582195514, 7575.924240757522, 7084.079069720903, 7099.787006389816, 7108.182559005573, 7153.713851446092, 7171.067761191011, 4896.36347274852, 4900.975495122501, 5006.324146240211, 5028.396592408934, 5029.371328583927, 4978.153713868579, 5000.399472696006, 5011.924821127759, 5086.694798312114, 5197.896042079213], "value": 4653.747935020631, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.94330992076397, 334.1812559229887, 343.37334364784243, 343.39733767238494, 343.4161924727963, 6243.56295059346, 6664.966675166629, 6805.35349141832, 6856.04602688234, 6859.348361905617, 5588.608797384185, 6781.966090169542, 6811.659417029141, 6826.385625293723, 6837.965810170955, 5086.618503612225, 5090.160123579614, 5091.185410334348, 5091.567216786559, 5112.079825631385, 5107.514786095315, 5113.821016264432, 5114.488551144875, 5126.384833819947, 5130.666506677054, 5035.471275516073, 5050.772715227805, 5064.265681476184, 5074.974625126888, 5087.0, 4758.595519380857, 4907.312976183337, 5031.3207716958295, 5063.443021267658, 5068.163752980754, 3606.459031145323, 4153.356229784388, 4185.162967406501, 4186.35111557709, 4198.454200953887, 5124.282600435942, 5139.280500729901, 6486.578372405778, 6490.578112422678, 6525.543211975164, 4708.317293992386, 5850.707464626794, 5854.853628659318, 5855.0678276168765, 5855.531557475434, 7211.314925082109, 7267.63661816907, 7270.709171633128, 7295.708171673126, 7312.451566132509, 4563.954360456386, 4694.624430045588, 4704.0, 4710.788014539354, 4711.481737008926, 7597.582132982684, 7602.277783610548, 7671.961640191819, 7687.730929417499, 7736.690532378698, 4352.39066530686, 4374.884404476838, 4775.403074615673, 4788.808447662124, 4790.880227994258, 3989.364360612135, 4864.075825593126, 4864.440589332201, 4866.0, 4878.8046928502745, 4752.405949256343, 5760.423957604206, 5809.912851307234, 5810.157527158538, 5811.389804070596, 7108.8933665995055, 7338.633068346561, 7343.412526997828, 7350.558966462029, 7411.703531858773, 1673.2888522378184, 1676.8155502894672, 1690.3830102012678, 1694.5932976085896, 1698.3376483171573, 888.5779254854021, 1003.5082809423476, 1057.1595581512715, 1058.8147074261974, 1074.387599068539, 7020.368166864904, 7087.220405755363, 7134.464915131386, 7136.536125151849, 7175.282471752887, 7613.733519326908, 7630.160682324939, 7636.50362726421, 7653.6938522459595, 7726.9613651932505, 7179.712811487482, 7195.784126476214, 7196.676149573282, 7204.639768011579, 7237.601931893796, 3909.550401703784, 4029.2747305485295, 4057.533383660858, 4060.675145988373, 4069.308217603015, 8161.63272652732, 8201.671933122727, 8255.669773209005, 8262.8347433051, 8273.627686754111, 7803.453758236982, 7866.763997079982, 7877.881831772528, 7883.329916957008, 7891.447598668153, 8229.46508476947, 8235.052968908652, 8237.093919668832, 8250.422470427013, 8283.62723677436, 7372.926270737224, 7626.466147369631, 7628.961855190689, 7652.081750190013, 7672.271134242238, 7440.962795185989, 7484.101907770884, 7497.887531687138, 7512.248775122444, 7518.962405187939, 8198.549079800669, 8217.219364160395, 8222.218889205516, 8224.218699223564, 8253.83492330162, 4920.606351491943, 5021.0, 5026.673266237683, 5027.622928280321, 5028.094942910311, 7216.3505284524645, 7497.2877576630135, 7505.849883002418, 7512.32389084985, 7543.811404714706, 7209.71161153548, 7218.783436496913, 7274.854502910018, 7283.271672832674, 7303.452241081834, 7254.492185546961, 7259.455540834353, 7278.6724597392185, 7299.598522081336, 7315.487915846053, 6887.862242755217, 7059.964700176466, 7072.5402848814665, 7095.609741464269, 7124.465665075037, 6691.929291313367, 7034.261402552863, 7337.0828646419195, 7350.264973502607, 7422.888656670047, 5554.472325129106, 5719.914201286901, 5781.4218578141845, 7074.257202993612, 7271.636418179176], "value": 5312.793986801157, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.313541755119, 692.1901375390793, 696.8536906328508, 745.6681776609411, 787.8778789287662, 15298.388064477422, 15405.768913466307, 15459.45891893784, 15468.690626187483, 15536.223188840555, 12858.71412858716, 13491.5277965271, 14573.05275157114, 14680.486182983597, 14760.778588321184, 781.1875649324695, 933.4725879877848, 937.9108984646465, 947.5688561704408, 961.2214106573698, 939.7227817793768, 947.663579429303, 958.918491928187, 962.6486332488627, 965.2663975378728, 867.1848462445281, 960.4429430930044, 980.9803803923917, 1004.9346792458504, 1005.4972513743129, 982.4792859784326, 991.1129539062534, 1001.1040119093407, 1002.5438425516372, 1003.5032658833887, 717.6089031477857, 733.050699344345, 744.3301029073823, 747.2303527366821, 749.0935967479356, 15129.924350378178, 15388.691961183313, 15444.150571718552, 15473.381064757396, 15490.922545387313, 13220.14069085516, 13340.86659133406, 13384.86615133846, 13441.0, 13441.865581344158, 8579.442336248125, 8812.07473215316, 8861.424007439497, 10317.123044541224, 10498.370097794157, 3560.12776869666, 3608.675219230282, 3626.0753507855593, 3633.291508155911, 3689.538807649044, 63128.05307920385, 83098.58450707768, 83823.5808820952, 85914.57042714747, 87327.12672873317, 9811.558479868423, 9819.75450613726, 9823.607055717692, 9842.261830362751, 9887.752806179758, 22295.108195672317, 22354.77645223527, 22890.969906354254, 22923.541529169324, 22941.19705810322, 7483.438742094365, 7625.961870190725, 7629.427792915553, 7684.884726729104, 7816.7264145754625, 4154.563770804022, 4182.1008483176265, 4194.266003449384, 4221.451211342567, 4251.319788833772, 3688.6495782900583, 3708.6847617952776, 3764.3412402829395, 3768.982374758855, 3782.8108594570162, 662.4501663619185, 684.4011489946297, 686.7596341280512, 687.8864987277143, 688.9311068893071, 519.888224031835, 592.3780030967553, 618.1623899616045, 619.2909119058653, 623.2209737827716, 8312.376571757022, 8454.661813527513, 8538.78653033679, 8545.273651739672, 8649.913500865037, 514.63975217348, 529.8834256463572, 581.5812614917253, 587.3304432946444, 596.7821745063108, 3733.346664333785, 3748.981255093762, 3936.5079365079364, 3942.5268967724055, 4112.609302116294, 86341.70487442693, 87484.5625771867, 87776.0, 88121.23757524813, 90263.54868225747, 58520.0, 77246.61376693193, 79141.60429197818, 79943.60028199939, 82776.0, 58833.11750323749, 73663.0, 73855.0, 74367.0, 75695.62152189315, 5954.940450595612, 7210.89183662256, 7415.555066696015, 8716.60775265102, 8758.562071896507, 35214.647853521135, 49501.0, 50952.0, 52393.47606523886, 52755.73622131865, 62652.373476266475, 63854.36145638484, 66111.66944165248, 69453.65273173602, 74033.0, 11057.004869561779, 11128.22102452853, 11140.275882067639, 11504.367259800456, 11557.24877882935, 11970.521179152736, 12113.212641178648, 12188.902998730156, 12288.0, 12317.753644926872, 9147.496887671243, 11793.587224447103, 11911.642650720492, 11949.820752688891, 12012.219205751948, 11719.296842189497, 11956.940215298868, 11970.281783093042, 12065.577704780291, 12123.818142728043, 11220.438978051228, 11244.493997769956, 11313.943430282796, 11390.829137562778, 11480.081593472669, 10035.648752293635, 10067.395956242648, 10202.387856728323, 10342.5345859435, 10394.636187733393, 9927.950360248153, 10139.797204056025, 10139.949300253451, 10146.695599132037, 10165.949170254102], "value": 7013.559237288348, "unit": "score"}], "name": "dromaeo_css", "value": 7486.078444777569, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 160.0, 117.0, 116.0, 120.0, 131.0, 116.0, 116.0, 115.0, 118.0], "value": 123.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 155.0, 143.0, 198.0, 151.0, 170.0, 139.0, 120.0, 166.0, 120.0], "value": 150.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 193.0, 196.0, 192.0, 198.0, 199.0, 193.0, 197.0, 196.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 79.0, 72.0, 143.0, 57.0, 55.0, 57.0, 66.0, 124.0, 56.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 122.0, 159.0, 113.0, 77.0, 81.0, 148.0, 116.0, 115.0, 121.0], "value": 114.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 88.0, 91.0, 103.0, 78.0, 120.0, 135.0, 87.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 138.0, 95.0, 96.0, 95.0, 97.0, 98.0, 119.0, 150.0, 105.0], "value": 113.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 131.0, 85.0, 75.0, 97.0, 103.0, 133.0, 144.0, 111.0, 92.0], "value": 106.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 82.0, 71.0, 72.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 56.0, 92.0, 105.0, 104.0, 99.0, 67.0, 67.0, 96.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 93.0, 102.0, 81.0, 80.0, 82.0, 82.0, 83.0, 82.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 125.0, 127.0, 127.0, 148.0, 120.0, 139.0, 150.0, 200.0, 153.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 160.0, 162.0, 164.0, 165.0, 164.0, 164.0, 167.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 92.0, 91.0, 93.0, 97.0, 60.0, 68.0, 95.0, 104.0, 96.0], "value": 86.5, "unit": "score"}], "name": "kraken", "value": 1604.1000000000001, "alertThreshold": 2.0}]} 23:14:06 INFO - Return code: 0 23:14:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:14:06 ERROR - # TBPL SUCCESS # 23:14:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:14:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:14:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:14:06 INFO - 2016-07-04 23:14:06.435 screenresolution[1695:12047] starting screenresolution argv=screenresolution get 23:14:06 INFO - 2016-07-04 23:14:06.438 screenresolution[1695:12047] Display 0: 1600x1200x32@60 23:14:06 INFO - 2016-07-04 23:14:06.443 screenresolution[1696:12050] starting screenresolution argv=screenresolution list 23:14:06 INFO - Available Modes on Display 0 23:14:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:14:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:14:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:14:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:14:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:14:06 INFO - Intel Iris: 23:14:06 INFO - Chipset Model: Intel Iris 23:14:06 INFO - Type: GPU 23:14:06 INFO - Bus: Built-In 23:14:06 INFO - VRAM (Dynamic, Max): 1536 MB 23:14:06 INFO - Vendor: Intel (0x8086) 23:14:06 INFO - Device ID: 0x0a2e 23:14:06 INFO - Revision ID: 0x0009 23:14:06 INFO - Displays: 23:14:06 INFO - Display: 23:14:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:14:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:14:06 INFO - Display Serial Number: 1600x1200 60 23:14:06 INFO - Main Display: Yes 23:14:06 INFO - Mirror: Off 23:14:06 INFO - Online: Yes 23:14:06 INFO - Rotation: Supported 23:14:06 INFO - Return code: 0 23:14:06 INFO - Running post-action listener: _resource_record_post_action 23:14:06 INFO - Running post-run listener: _resource_record_post_run 23:14:07 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 32906752; Write bytes: 499243008; Read time: 325; Write time: 1708 23:14:07 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 189260288; Write bytes: 184836096; Read time: 17531; Write time: 346 23:14:07 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 31100416; Write bytes: 299601920; Read time: 301; Write time: 1295 23:14:07 INFO - Running post-run listener: _upload_blobber_files 23:14:07 INFO - Blob upload gear active. 23:14:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:14:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:14:08 INFO - (blobuploader) - INFO - Open directory for files ... 23:14:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:14:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:14:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:09 INFO - (blobuploader) - INFO - Done attempting. 23:14:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:14:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:14:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:10 INFO - (blobuploader) - INFO - Done attempting. 23:14:10 INFO - (blobuploader) - INFO - Iteration through files over. 23:14:10 INFO - Return code: 0 23:14:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:14:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:14:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a57894e597e2336ed91d2349e9c0d54c9fd2faac0a4493b356daa354adfb1a31c071d72d8c791d6cd6ea498411c3600d85a1321124cb1fe038aa9ec3acf3aa7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/becefc685fe4e8294d545ef4a9b5f68a467393d8b44fad7a9049188b6445a1eea30be1179df9091eed71af82a0ea30c9e4481813a86df3e63464bd70f19b5813"} 23:14:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:14:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:14:10 INFO - Contents: 23:14:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a57894e597e2336ed91d2349e9c0d54c9fd2faac0a4493b356daa354adfb1a31c071d72d8c791d6cd6ea498411c3600d85a1321124cb1fe038aa9ec3acf3aa7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/becefc685fe4e8294d545ef4a9b5f68a467393d8b44fad7a9049188b6445a1eea30be1179df9091eed71af82a0ea30c9e4481813a86df3e63464bd70f19b5813"} 23:14:10 INFO - Running post-run listener: copy_logs_to_upload_dir 23:14:10 INFO - Copying logs to upload dir... 23:14:10 INFO - mkdir: /builds/slave/test/build/upload/logs 23:14:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.225120 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-07-04 23:14:10.655544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 23:14:10.658722) ========= 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.ojD0BCmQed/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.2IBgEEropy/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a57894e597e2336ed91d2349e9c0d54c9fd2faac0a4493b356daa354adfb1a31c071d72d8c791d6cd6ea498411c3600d85a1321124cb1fe038aa9ec3acf3aa7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/becefc685fe4e8294d545ef4a9b5f68a467393d8b44fad7a9049188b6445a1eea30be1179df9091eed71af82a0ea30c9e4481813a86df3e63464bd70f19b5813"} build_url:https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010308 build_url: 'https://queue.taskcluster.net/v1/task/VDDi13iVTcGzxdV8lNITuA/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a57894e597e2336ed91d2349e9c0d54c9fd2faac0a4493b356daa354adfb1a31c071d72d8c791d6cd6ea498411c3600d85a1321124cb1fe038aa9ec3acf3aa7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/becefc685fe4e8294d545ef4a9b5f68a467393d8b44fad7a9049188b6445a1eea30be1179df9091eed71af82a0ea30c9e4481813a86df3e63464bd70f19b5813"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 23:14:10.691667) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 23:14:10.692019) ========= 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.ojD0BCmQed/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.2IBgEEropy/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/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.004927 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 23:14:10.743281) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 23:14:10.743943) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 23:14:10.744736) ========= ========= Total master_lag: 0.18 =========