builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0205 starttime: 1463625078.15 results: success (0) buildid: 20160518173344 builduid: a5d9b80681244a308d4ff8ae8f21969d revision: 0723a0212f5e0b30d7532d4e35eba7759fb54507 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:18.149016) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:18.149435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-05-18 19:31:18.149725) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-05-18 19:31:20.147577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 19:31:20.147900) ========= 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.uHODASzwd7/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.eVFVNT9VHY/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-18 19:31:20-- 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% 24.7M=0s 2016-05-18 19:31:20 (24.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076205 ========= master_lag: 1.93 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 19:31:22.153464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:22.153803) ========= 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.uHODASzwd7/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.eVFVNT9VHY/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.026999 ========= master_lag: 0.96 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:23.141814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 19:31:23.142162) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0723a0212f5e0b30d7532d4e35eba7759fb54507 --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 0723a0212f5e0b30d7532d4e35eba7759fb54507 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uHODASzwd7/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.eVFVNT9VHY/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-18 19:31:23,222 truncating revision to first 12 chars 2016-05-18 19:31:23,222 Setting DEBUG logging. 2016-05-18 19:31:23,222 attempt 1/10 2016-05-18 19:31:23,222 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0723a0212f5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 19:31:24,958 unpacking tar archive at: mozilla-beta-0723a0212f5e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.984360 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 19:31:25.190442) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:25.190746) ========= script_repo_revision: 0723a0212f5e0b30d7532d4e35eba7759fb54507 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:25.191110) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:25.191382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 19:31:25.212336) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-18 19:31:25.215646) ========= /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.uHODASzwd7/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.eVFVNT9VHY/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:31:25 INFO - MultiFileLogger online at 20160518 19:31:25 in /builds/slave/test 19:31:25 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 19:31:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:31:25 INFO - {'append_to_log': False, 19:31:25 INFO - 'base_work_dir': '/builds/slave/test', 19:31:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:31:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:31:25 INFO - 'branch': 'Mozilla-Beta', 19:31:25 INFO - 'buildbot_json_path': 'buildprops.json', 19:31:25 INFO - 'config_files': ('talos/mac_config.py',), 19:31:25 INFO - 'default_actions': ('clobber', 19:31:25 INFO - 'read-buildbot-config', 19:31:25 INFO - 'download-and-extract', 19:31:25 INFO - 'populate-webroot', 19:31:25 INFO - 'create-virtualenv', 19:31:25 INFO - 'install', 19:31:25 INFO - 'run-tests'), 19:31:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:31:25 INFO - 'download_minidump_stackwalk': True, 19:31:25 INFO - 'download_symbols': 'ondemand', 19:31:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:31:25 INFO - 'tooltool.py': '/tools/tooltool.py', 19:31:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:31:25 INFO - '/tools/misc-python/virtualenv.py')}, 19:31:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:31:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:31:25 INFO - 'installer_path': 'installer.exe', 19:31:25 INFO - 'log_level': 'info', 19:31:25 INFO - 'log_name': 'talos', 19:31:25 INFO - 'log_to_console': True, 19:31:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:31:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:31:25 INFO - 'opt_config_files': (), 19:31:25 INFO - 'pip_index': False, 19:31:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:31:25 INFO - 'cmd': ('bash', 19:31:25 INFO - '-c', 19:31:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:31:25 INFO - 'enabled': True, 19:31:25 INFO - 'halt_on_failure': False, 19:31:25 INFO - 'name': 'check_screen_resolution'},), 19:31:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:31:25 INFO - 'cmd': ('bash', 19:31:25 INFO - '-c', 19:31:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:31:25 INFO - 'enabled': True, 19:31:25 INFO - 'halt_on_failure': False, 19:31:25 INFO - 'name': 'check_screen_resolution'},), 19:31:25 INFO - 'run_cmd_checks_enabled': True, 19:31:25 INFO - 'sps_profile': False, 19:31:25 INFO - 'sps_profile_interval': 0, 19:31:25 INFO - 'suite': 'dromaeojs-e10s', 19:31:25 INFO - 'system_bits': '32', 19:31:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:31:25 INFO - 'title': 't-yosemite-r7-0205', 19:31:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:31:25 INFO - 'use_talos_json': True, 19:31:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:31:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:31:25 INFO - 'work_dir': 'build'} 19:31:25 INFO - ##### 19:31:25 INFO - ##### Running clobber step. 19:31:25 INFO - ##### 19:31:25 INFO - Running pre-action listener: _resource_record_pre_action 19:31:25 INFO - Running main action method: clobber 19:31:25 INFO - rmtree: /builds/slave/test/build 19:31:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:31:27 INFO - Running post-action listener: _resource_record_post_action 19:31:27 INFO - ##### 19:31:27 INFO - ##### Running read-buildbot-config step. 19:31:27 INFO - ##### 19:31:27 INFO - Running pre-action listener: _resource_record_pre_action 19:31:27 INFO - Running main action method: read_buildbot_config 19:31:27 INFO - Using buildbot properties: 19:31:27 INFO - { 19:31:27 INFO - "project": "", 19:31:27 INFO - "product": "firefox", 19:31:27 INFO - "script_repo_revision": "production", 19:31:27 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:31:27 INFO - "repository": "", 19:31:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:31:27 INFO - "buildid": "20160518173344", 19:31:27 INFO - "pgo_build": "False", 19:31:27 INFO - "basedir": "/builds/slave/test", 19:31:27 INFO - "buildnumber": 83, 19:31:27 INFO - "slavename": "t-yosemite-r7-0205", 19:31:27 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:31:27 INFO - "platform": "yosemite_r7", 19:31:27 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:31:27 INFO - "branch": "mozilla-beta", 19:31:27 INFO - "repo_path": "releases/mozilla-beta", 19:31:27 INFO - "revision": "0723a0212f5e0b30d7532d4e35eba7759fb54507", 19:31:27 INFO - "stage_platform": "macosx64", 19:31:27 INFO - "builduid": "a5d9b80681244a308d4ff8ae8f21969d", 19:31:27 INFO - "slavebuilddir": "test" 19:31:27 INFO - } 19:31:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 19:31:27 INFO - Running post-action listener: _resource_record_post_action 19:31:27 INFO - ##### 19:31:27 INFO - ##### Running download-and-extract step. 19:31:27 INFO - ##### 19:31:27 INFO - Running pre-action listener: _resource_record_pre_action 19:31:27 INFO - Running main action method: download_and_extract 19:31:27 INFO - mkdir: /builds/slave/test/build/tests 19:31: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')]} 19:31:27 INFO - https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 19:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 19:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 19:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 19:31:31 INFO - Downloaded 1241 bytes. 19:31:31 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 19:31:31 INFO - Using the following test package requirements: 19:31:31 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 19:31:31 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 19:31:31 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 19:31:31 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:31:31 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 19:31:31 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 19:31:31 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 19:31:31 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 19:31:31 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 19:31:31 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 19:31:31 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 19:31:31 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 19:31:31 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 19:31:31 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')]} 19:31:31 INFO - https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:31:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 19:31:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 19:31:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 19:31:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 19:31:32 INFO - Downloaded 17747765 bytes. 19:31:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:31:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:31:35 INFO - Return code: 0 19:31:35 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:31: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')]} 19:31:35 INFO - https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:31:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 19:31:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 19:31:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 19:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 19:31:36 INFO - Downloaded 17747765 bytes. 19:31:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:31:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:31:39 INFO - Return code: 0 19:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:39 INFO - https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:31:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 19:31:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 19:31:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 19:31:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 19:31:40 INFO - Downloaded 10845194 bytes. 19:31:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:31:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:31:40 INFO - Return code: 0 19:31:40 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')]} 19:31:40 INFO - https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 19:31:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 19:31:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 19:31:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 19:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 19:31:44 INFO - Downloaded 88422256 bytes. 19:31:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 19:31:44 INFO - mkdir: /builds/slave/test/properties 19:31:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:31:44 INFO - Writing to file /builds/slave/test/properties/build_url 19:31:44 INFO - Contents: 19:31:44 INFO - build_url:https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 19:31:45 INFO - Running post-action listener: _resource_record_post_action 19:31:45 INFO - Running post-action listener: set_extra_try_arguments 19:31:45 INFO - ##### 19:31:45 INFO - ##### Running populate-webroot step. 19:31:45 INFO - ##### 19:31:45 INFO - Running pre-action listener: _resource_record_pre_action 19:31:45 INFO - Running main action method: populate_webroot 19:31:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:31:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:31:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:31:45 INFO - '--tppagecycles', 19:31:45 INFO - '7'], 19:31:45 INFO - 'tests': ['tsvgm']}}, 19:31:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:31:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:31:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 19:31:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:31:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:31:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:31:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'tests': ['damp', 'tps']}, 19:31:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['damp', 'tps']}, 19:31:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:31:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:31:45 INFO - 'other': {'tests': ['a11yr', 19:31:45 INFO - 'ts_paint', 19:31:45 INFO - 'tpaint', 19:31:45 INFO - 'sessionrestore', 19:31:45 INFO - 'sessionrestore_no_auto_restore', 19:31:45 INFO - 'tabpaint']}, 19:31:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['a11yr', 19:31:45 INFO - 'ts_paint', 19:31:45 INFO - 'tpaint', 19:31:45 INFO - 'sessionrestore', 19:31:45 INFO - 'sessionrestore_no_auto_restore', 19:31:45 INFO - 'tabpaint']}, 19:31:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['a11yr', 19:31:45 INFO - 'ts_paint', 19:31:45 INFO - 'tpaint', 19:31:45 INFO - 'sessionrestore', 19:31:45 INFO - 'sessionrestore_no_auto_restore', 19:31:45 INFO - 'tabpaint']}, 19:31:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['a11yr', 19:31:45 INFO - 'ts_paint', 19:31:45 INFO - 'tpaint', 19:31:45 INFO - 'sessionrestore', 19:31:45 INFO - 'sessionrestore_no_auto_restore', 19:31:45 INFO - 'tabpaint']}, 19:31:45 INFO - 'other_l64': {'tests': ['a11yr', 19:31:45 INFO - 'ts_paint', 19:31:45 INFO - 'tpaint', 19:31:45 INFO - 'sessionrestore', 19:31:45 INFO - 'sessionrestore_no_auto_restore', 19:31:45 INFO - 'tabpaint']}, 19:31:45 INFO - 'other_nol64': {'tests': ['a11yr', 19:31:45 INFO - 'ts_paint', 19:31:45 INFO - 'tpaint', 19:31:45 INFO - 'sessionrestore', 19:31:45 INFO - 'sessionrestore_no_auto_restore', 19:31:45 INFO - 'tabpaint']}, 19:31:45 INFO - 'svgr': {'tests': ['tsvgx', 19:31:45 INFO - 'tsvgr_opacity', 19:31:45 INFO - 'tart', 19:31:45 INFO - 'tscrollx', 19:31:45 INFO - 'cart']}, 19:31:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['tsvgx', 19:31:45 INFO - 'tsvgr_opacity', 19:31:45 INFO - 'tart', 19:31:45 INFO - 'tscrollx', 19:31:45 INFO - 'cart']}, 19:31:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'tests': ['tp5o']}, 19:31:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'talos_options': ['--e10s'], 19:31:45 INFO - 'tests': ['tp5o']}, 19:31:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'talos_options': ['--xperf_path', 19:31:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:31:45 INFO - 'tests': ['tp5n']}, 19:31:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:31:45 INFO - 'talos_options': ['--e10s', 19:31:45 INFO - '--xperf_path', 19:31:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:31:45 INFO - 'tests': ['tp5n']}}, 19:31:45 INFO - 'talos.zip': {'path': '', 19:31:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:31:45 INFO - Running post-action listener: _resource_record_post_action 19:31:45 INFO - ##### 19:31:45 INFO - ##### Running create-virtualenv step. 19:31:45 INFO - ##### 19:31:45 INFO - Running pre-action listener: _resource_record_pre_action 19:31:45 INFO - Running main action method: create_virtualenv 19:31:45 INFO - Creating virtualenv /builds/slave/test/build/venv 19:31:45 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 19:31:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:31:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:31:46 INFO - Using real prefix '/tools/python27' 19:31:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:31:46 INFO - Installing distribute.............................................................................................................................................................................................done. 19:31:49 INFO - Installing pip.................done. 19:31:49 INFO - Return code: 0 19:31:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31:49 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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:31:49 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 19:31:49 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 19:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:31:49 INFO - 'HOME': '/Users/cltbld', 19:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:31:49 INFO - 'LOGNAME': 'cltbld', 19:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:49 INFO - 'MOZ_NO_REMOTE': '1', 19:31:49 INFO - 'NO_EM_RESTART': '1', 19:31:49 INFO - 'PAGER': '/bin/cat', 19:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:49 INFO - 'PWD': '/builds/slave/test', 19:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:31:49 INFO - 'SHELL': '/bin/bash', 19:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:31:49 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:31:49 INFO - 'USER': 'cltbld', 19:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:49 INFO - 'XPC_FLAGS': '0x0', 19:31:49 INFO - 'XPC_SERVICE_NAME': '0', 19:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:49 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 19:31:49 INFO - Cleaning up... 19:31:50 INFO - Return code: 0 19:31:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:31: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')]} 19:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31: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')]} 19:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31: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', '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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:31: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', 'psutil>=0.7.1'] in /builds/slave/test/build 19:31: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 psutil>=0.7.1 19:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:31:50 INFO - 'HOME': '/Users/cltbld', 19:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:31:50 INFO - 'LOGNAME': 'cltbld', 19:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:50 INFO - 'MOZ_NO_REMOTE': '1', 19:31:50 INFO - 'NO_EM_RESTART': '1', 19:31:50 INFO - 'PAGER': '/bin/cat', 19:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:50 INFO - 'PWD': '/builds/slave/test', 19:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:31:50 INFO - 'SHELL': '/bin/bash', 19:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:31:50 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:31:50 INFO - 'USER': 'cltbld', 19:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:50 INFO - 'XPC_FLAGS': '0x0', 19:31:50 INFO - 'XPC_SERVICE_NAME': '0', 19:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:50 INFO - Downloading/unpacking psutil>=0.7.1 19:31: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 19:31: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 19:31: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 19:31: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 19:31: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 19:31: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 19:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:31:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:31:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:31:52 INFO - Installing collected packages: psutil 19:31:52 INFO - Running setup.py install for psutil 19:31:52 INFO - building 'psutil._psutil_osx' extension 19:31:52 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 19:31:53 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 19:31:53 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 19:31:53 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 19:31:53 INFO - building 'psutil._psutil_posix' extension 19:31:53 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 19:31:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:31:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:31:53 INFO - ^ 19:31:53 INFO - 1 warning generated. 19:31:53 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 19:31:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:31:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:31:54 INFO - Successfully installed psutil 19:31:54 INFO - Cleaning up... 19:31:54 INFO - Return code: 0 19:31:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:31: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')]} 19:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31: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')]} 19:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 19:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 19:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:31:54 INFO - 'HOME': '/Users/cltbld', 19:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:31:54 INFO - 'LOGNAME': 'cltbld', 19:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:54 INFO - 'MOZ_NO_REMOTE': '1', 19:31:54 INFO - 'NO_EM_RESTART': '1', 19:31:54 INFO - 'PAGER': '/bin/cat', 19:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:54 INFO - 'PWD': '/builds/slave/test', 19:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:31:54 INFO - 'SHELL': '/bin/bash', 19:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:31:54 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:31:54 INFO - 'USER': 'cltbld', 19:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:54 INFO - 'XPC_FLAGS': '0x0', 19:31:54 INFO - 'XPC_SERVICE_NAME': '0', 19:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:31:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:31:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:31:56 INFO - Installing collected packages: mozsystemmonitor 19:31:56 INFO - Running setup.py install for mozsystemmonitor 19:31:56 INFO - Successfully installed mozsystemmonitor 19:31:56 INFO - Cleaning up... 19:31:57 INFO - Return code: 0 19:31:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:31:57 INFO - 'HOME': '/Users/cltbld', 19:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:31:57 INFO - 'LOGNAME': 'cltbld', 19:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:57 INFO - 'MOZ_NO_REMOTE': '1', 19:31:57 INFO - 'NO_EM_RESTART': '1', 19:31:57 INFO - 'PAGER': '/bin/cat', 19:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:57 INFO - 'PWD': '/builds/slave/test', 19:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:31:57 INFO - 'SHELL': '/bin/bash', 19:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:31:57 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:31:57 INFO - 'USER': 'cltbld', 19:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:57 INFO - 'XPC_FLAGS': '0x0', 19:31:57 INFO - 'XPC_SERVICE_NAME': '0', 19:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:31:57 INFO - Downloading/unpacking blobuploader==1.2.4 19:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:31:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:31:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:31:59 INFO - Downloading blobuploader-1.2.4.tar.gz 19:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:31:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:32:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:00 INFO - Downloading docopt-0.6.1.tar.gz 19:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:32:01 INFO - Installing collected packages: blobuploader, requests, docopt 19:32:01 INFO - Running setup.py install for blobuploader 19:32:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:32:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:32:01 INFO - Running setup.py install for requests 19:32:01 INFO - Running setup.py install for docopt 19:32:01 INFO - Successfully installed blobuploader requests docopt 19:32:01 INFO - Cleaning up... 19:32:01 INFO - Return code: 0 19:32:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:01 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')]} 19:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:02 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')]} 19:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:02 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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:32:02 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 19:32:02 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 19:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:02 INFO - 'HOME': '/Users/cltbld', 19:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:02 INFO - 'LOGNAME': 'cltbld', 19:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:02 INFO - 'MOZ_NO_REMOTE': '1', 19:32:02 INFO - 'NO_EM_RESTART': '1', 19:32:02 INFO - 'PAGER': '/bin/cat', 19:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:02 INFO - 'PWD': '/builds/slave/test', 19:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:02 INFO - 'SHELL': '/bin/bash', 19:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:32:02 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:02 INFO - 'USER': 'cltbld', 19:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:02 INFO - 'XPC_FLAGS': '0x0', 19:32:02 INFO - 'XPC_SERVICE_NAME': '0', 19:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:02 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-rBhAh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:02 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Vx5PKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:02 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-rL5oBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:02 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-59Sl8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-9Hh_Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-X7zpIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-X6VVmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-jsDEGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-mc3EvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-OlyjSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-n5rgBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-hTDnKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Mw0oiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:32:03 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-21h0hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:04 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-2NnEXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:32:04 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-hDk1dV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:32:04 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-SgIfTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:32:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:32:04 INFO - Running setup.py install for manifestparser 19:32:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:32:04 INFO - Running setup.py install for mozcrash 19:32:04 INFO - Running setup.py install for mozdebug 19:32:04 INFO - Running setup.py install for mozdevice 19:32:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:32:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:32:04 INFO - Running setup.py install for mozfile 19:32:05 INFO - Running setup.py install for mozhttpd 19:32:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Running setup.py install for mozinfo 19:32:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Running setup.py install for mozInstall 19:32:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Running setup.py install for mozleak 19:32:05 INFO - Running setup.py install for mozlog 19:32:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Running setup.py install for moznetwork 19:32:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:32:05 INFO - Running setup.py install for mozprocess 19:32:06 INFO - Running setup.py install for mozprofile 19:32:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:32:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:32:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:32:06 INFO - Running setup.py install for mozrunner 19:32:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:32:06 INFO - Running setup.py install for mozscreenshot 19:32:06 INFO - Running setup.py install for moztest 19:32:06 INFO - Running setup.py install for mozversion 19:32:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:32:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:32:06 INFO - Cleaning up... 19:32:06 INFO - Return code: 0 19:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:06 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')]} 19:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:06 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')]} 19:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:06 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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:32:06 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 19:32:06 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 19:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:06 INFO - 'HOME': '/Users/cltbld', 19:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:06 INFO - 'LOGNAME': 'cltbld', 19:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:06 INFO - 'MOZ_NO_REMOTE': '1', 19:32:06 INFO - 'NO_EM_RESTART': '1', 19:32:06 INFO - 'PAGER': '/bin/cat', 19:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:06 INFO - 'PWD': '/builds/slave/test', 19:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:06 INFO - 'SHELL': '/bin/bash', 19:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:32:06 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:06 INFO - 'USER': 'cltbld', 19:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:06 INFO - 'XPC_FLAGS': '0x0', 19:32:06 INFO - 'XPC_SERVICE_NAME': '0', 19:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-lJP153-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ZwqnOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-1dvQ5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-GxMccV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-PWf6oR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-9wVwVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-RuZirs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:07 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)) 19:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:07 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-yPBjCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-wRoqFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Zkkim9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Id8aMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-a59_oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-IH24vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-XrLZkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-sSl7pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-VegLwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:32:08 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)) 19:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:32:08 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-hT_mqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:32:09 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)) 19:32:09 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)) 19:32:09 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)) 19:32:09 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)) 19:32:09 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)) 19:32:09 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)) 19:32:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:32:09 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 19:32:09 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 19:32:09 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 19:32:09 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 19:32:09 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 19:32:09 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 19:32:11 INFO - Downloading blessings-1.6.tar.gz 19:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:32:11 INFO - Installing collected packages: blessings 19:32:11 INFO - Running setup.py install for blessings 19:32:12 INFO - Successfully installed blessings 19:32:12 INFO - Cleaning up... 19:32:12 INFO - Return code: 0 19:32:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:32:12 INFO - Reading from file tmpfile_stdout 19:32:12 INFO - Current package versions: 19:32:12 INFO - blessings == 1.6 19:32:12 INFO - blobuploader == 1.2.4 19:32:12 INFO - docopt == 0.6.1 19:32:12 INFO - manifestparser == 1.1 19:32:12 INFO - mozInstall == 1.12 19:32:12 INFO - mozcrash == 0.17 19:32:12 INFO - mozdebug == 0.1 19:32:12 INFO - mozdevice == 0.48 19:32:12 INFO - mozfile == 1.2 19:32:12 INFO - mozhttpd == 0.7 19:32:12 INFO - mozinfo == 0.9 19:32:12 INFO - mozleak == 0.1 19:32:12 INFO - mozlog == 3.1 19:32:12 INFO - moznetwork == 0.27 19:32:12 INFO - mozprocess == 0.22 19:32:12 INFO - mozprofile == 0.28 19:32:12 INFO - mozrunner == 6.11 19:32:12 INFO - mozscreenshot == 0.1 19:32:12 INFO - mozsystemmonitor == 0.0 19:32:12 INFO - moztest == 0.7 19:32:12 INFO - mozversion == 1.4 19:32:12 INFO - psutil == 3.1.1 19:32:12 INFO - requests == 1.2.3 19:32:12 INFO - wsgiref == 0.1.2 19:32:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:12 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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:32:12 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 19:32:12 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 19:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:12 INFO - 'HOME': '/Users/cltbld', 19:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:12 INFO - 'LOGNAME': 'cltbld', 19:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:12 INFO - 'MOZ_NO_REMOTE': '1', 19:32:12 INFO - 'NO_EM_RESTART': '1', 19:32:12 INFO - 'PAGER': '/bin/cat', 19:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:12 INFO - 'PWD': '/builds/slave/test', 19:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:12 INFO - 'SHELL': '/bin/bash', 19:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:32:12 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:12 INFO - 'USER': 'cltbld', 19:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:12 INFO - 'XPC_FLAGS': '0x0', 19:32:12 INFO - 'XPC_SERVICE_NAME': '0', 19:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 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)) 19:32:12 INFO - Cleaning up... 19:32:12 INFO - Return code: 0 19:32:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:12 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 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:32:12 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 19:32:12 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 19:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:12 INFO - 'HOME': '/Users/cltbld', 19:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:12 INFO - 'LOGNAME': 'cltbld', 19:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:12 INFO - 'MOZ_NO_REMOTE': '1', 19:32:12 INFO - 'NO_EM_RESTART': '1', 19:32:12 INFO - 'PAGER': '/bin/cat', 19:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:12 INFO - 'PWD': '/builds/slave/test', 19:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:12 INFO - 'SHELL': '/bin/bash', 19:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:32:12 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:12 INFO - 'USER': 'cltbld', 19:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:12 INFO - 'XPC_FLAGS': '0x0', 19:32:12 INFO - 'XPC_SERVICE_NAME': '0', 19:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:13 INFO - Downloading/unpacking jsonschema 19:32:13 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 19:32:13 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 19:32:13 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 19:32:13 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 19:32:13 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 19:32:13 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 19:32:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:32:15 INFO - Downloading/unpacking functools32 (from jsonschema) 19:32:15 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 19:32:15 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 19:32:15 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 19:32:15 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 19:32:15 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 19:32:15 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 19:32:16 INFO - Downloading functools32-3.2.3-2.tar.gz 19:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:32:16 INFO - warning: no files found matching '*.txt' 19:32:16 INFO - no previously-included directories found matching 'build' 19:32:16 INFO - no previously-included directories found matching 'dist' 19:32:16 INFO - no previously-included directories found matching '.git*' 19:32:16 INFO - Installing collected packages: jsonschema, functools32 19:32:16 INFO - Running setup.py install for functools32 19:32:16 INFO - warning: no files found matching '*.txt' 19:32:16 INFO - no previously-included directories found matching 'build' 19:32:16 INFO - no previously-included directories found matching 'dist' 19:32:16 INFO - no previously-included directories found matching '.git*' 19:32:16 INFO - Successfully installed jsonschema functools32 19:32:16 INFO - Cleaning up... 19:32:16 INFO - Return code: 0 19:32:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10889fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa311590e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, '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.uHODASzwd7/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.eVFVNT9VHY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 19:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:32:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:32:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:16 INFO - 'HOME': '/Users/cltbld', 19:32:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:16 INFO - 'LOGNAME': 'cltbld', 19:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:16 INFO - 'MOZ_NO_REMOTE': '1', 19:32:16 INFO - 'NO_EM_RESTART': '1', 19:32:16 INFO - 'PAGER': '/bin/cat', 19:32:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:16 INFO - 'PWD': '/builds/slave/test', 19:32:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:16 INFO - 'SHELL': '/bin/bash', 19:32:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:32:16 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:32:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:16 INFO - 'USER': 'cltbld', 19:32:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:16 INFO - 'XPC_FLAGS': '0x0', 19:32:16 INFO - 'XPC_SERVICE_NAME': '0', 19:32:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:16 INFO - Downloading/unpacking flake8 19:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:32:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:32:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:19 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 19:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:32:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:32:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:32:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:32:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:32:20 INFO - no previously-included directories found matching 'docs/_build' 19:32:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:32:20 INFO - Running setup.py install for pep8 19:32:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:32:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:32:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:32:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:32:20 INFO - no previously-included directories found matching 'docs/_build' 19:32:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:32:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:32:20 INFO - Cleaning up... 19:32:20 INFO - Return code: 0 19:32:20 INFO - Running post-action listener: _resource_record_post_action 19:32:20 INFO - Running post-action listener: _start_resource_monitoring 19:32:20 INFO - Starting resource monitoring. 19:32:20 INFO - ##### 19:32:20 INFO - ##### Running install step. 19:32:20 INFO - ##### 19:32:20 INFO - Running pre-action listener: _resource_record_pre_action 19:32:20 INFO - Running main action method: install 19:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:32:20 INFO - Reading from file tmpfile_stdout 19:32:20 INFO - Detecting whether we're running mozinstall >=1.0... 19:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:32:21 INFO - Reading from file tmpfile_stdout 19:32:21 INFO - Output received: 19:32:21 INFO - Usage: mozinstall [options] installer 19:32:21 INFO - Options: 19:32:21 INFO - -h, --help show this help message and exit 19:32:21 INFO - -d DEST, --destination=DEST 19:32:21 INFO - Directory to install application into. [default: 19:32:21 INFO - "/builds/slave/test"] 19:32:21 INFO - --app=APP Application being installed. [default: firefox] 19:32:21 INFO - mkdir: /builds/slave/test/build/application 19:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:32:43 INFO - Reading from file tmpfile_stdout 19:32:43 INFO - Output received: 19:32:43 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:32:43 INFO - Running post-action listener: _resource_record_post_action 19:32:43 INFO - ##### 19:32:43 INFO - ##### Running run-tests step. 19:32:43 INFO - ##### 19:32:43 INFO - Running pre-action listener: _resource_record_pre_action 19:32:43 INFO - Running main action method: run_tests 19:32:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:32:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:32:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:32:43 INFO - 2016-05-18 19:32:43.628 screenresolution[1644:7440] starting screenresolution argv=screenresolution get 19:32:43 INFO - 2016-05-18 19:32:43.630 screenresolution[1644:7440] Display 0: 1600x1200x32@60 19:32:43 INFO - 2016-05-18 19:32:43.636 screenresolution[1645:7442] starting screenresolution argv=screenresolution list 19:32:43 INFO - Available Modes on Display 0 19:32:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:32:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:32:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:32:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:32:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:32:43 INFO - Intel Iris: 19:32:43 INFO - Chipset Model: Intel Iris 19:32:43 INFO - Type: GPU 19:32:43 INFO - Bus: Built-In 19:32:43 INFO - VRAM (Dynamic, Max): 1536 MB 19:32:43 INFO - Vendor: Intel (0x8086) 19:32:43 INFO - Device ID: 0x0a2e 19:32:43 INFO - Revision ID: 0x0009 19:32:43 INFO - Displays: 19:32:43 INFO - Display: 19:32:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:32:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:32:43 INFO - Display Serial Number: 1600x1200 60 19:32:43 INFO - Main Display: Yes 19:32:43 INFO - Mirror: Off 19:32:43 INFO - Online: Yes 19:32:43 INFO - Rotation: Supported 19:32:43 INFO - Return code: 0 19:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:32:43 INFO - Python 2.7.3 19:32:43 INFO - Return code: 0 19:32:43 INFO - grabbing minidump binary from tooltool 19:32:43 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')]} 19:32:43 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 0x108743d30>, '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 0x1085a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10873ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:32:43 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 19:32:43 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 19:32:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:32:43 INFO - Return code: 0 19:32:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:32:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:32:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:32:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:32:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:32:45 INFO - Return code: 0 19:32:45 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/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 19:32:45 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/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --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 19:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uHODASzwd7/Render', 19:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:45 INFO - 'HOME': '/Users/cltbld', 19:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:45 INFO - 'LOGNAME': 'cltbld', 19:32:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:32:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:45 INFO - 'MOZ_NO_REMOTE': '1', 19:32:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:32:45 INFO - 'NO_EM_RESTART': '1', 19:32:45 INFO - 'PAGER': '/bin/cat', 19:32:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:45 INFO - 'PWD': '/builds/slave/test', 19:32:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:45 INFO - 'SHELL': '/bin/bash', 19:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVFVNT9VHY/Listeners', 19:32:45 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 19:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:45 INFO - 'USER': 'cltbld', 19:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:45 INFO - 'XPC_FLAGS': '0x0', 19:32:45 INFO - 'XPC_SERVICE_NAME': '0', 19:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:45 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/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 19:32:45 INFO - mozversion application_buildid: 20160518173344 19:32:45 INFO - mozversion application_changeset: 0723a0212f5e0b30d7532d4e35eba7759fb54507 19:32:45 INFO - mozversion application_display_name: Firefox 19:32:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:32:45 INFO - mozversion application_name: Firefox 19:32:45 INFO - mozversion application_remotingname: firefox 19:32:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:32:45 INFO - mozversion application_vendor: Mozilla 19:32:45 INFO - mozversion application_version: 47.0 19:32:45 INFO - mozversion platform_buildid: 20160518173344 19:32:45 INFO - mozversion platform_changeset: 0723a0212f5e0b30d7532d4e35eba7759fb54507 19:32:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:32:45 INFO - mozversion platform_version: 47.0 19:32:45 INFO - using testdate: 1463625165 19:32:45 INFO - actual date: 1463625165 19:32:45 INFO - starting webserver on 'localhost:49277' 19:32:45 INFO - SUITE-START | Running 2 tests 19:32:45 INFO - TEST-START | dromaeo_css 19:32:45 INFO - operating with platform_type : mac_ 19:32:45 INFO - Initialising browser for dromaeo_css test... 19:32:45 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpfe7Nh_/profile http://localhost:49277/getInfo.html) 19:32:48 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 19:32:48 INFO - PROCESS | 1663 | colorDepth:24 19:32:48 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 19:32:48 INFO - PROCESS | 1663 | __metrics 19:32:48 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 19:32:48 INFO - TEST-INFO | 1663: exit 0 19:32:48 INFO - Browser initialized. 19:32:48 INFO - Running cycle 1/1 for dromaeo_css test... 19:32:48 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpfe7Nh_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:00 INFO - PROCESS | 1667 | RSS: Main: 168448000 19:33:00 INFO - PROCESS | 1667 | 19:33:50 INFO - PROCESS | 1667 | 1463625230389 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] 19:36:00 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 19:36:01 INFO - PROCESS | 1667 | RSS: Main: 178782208 19:36:01 INFO - PROCESS | 1667 | 19:39:03 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 19:39:04 INFO - PROCESS | 1667 | RSS: Main: 190177280 19:39:04 INFO - PROCESS | 1667 | 19:42:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 19:42:06 INFO - PROCESS | 1667 | RSS: Main: 193056768 19:42:06 INFO - PROCESS | 1667 | 19:45:08 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 19:45:08 INFO - PROCESS | 1667 | RSS: Main: 195981312 19:45:08 INFO - PROCESS | 1667 | 19:48:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 19:48:11 INFO - PROCESS | 1667 | RSS: Main: 195481600 19:48:11 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 19:51:14 INFO - PROCESS | 1667 | RSS: Main: 200077312 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | __start_tp_report 19:51:14 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 19:51:14 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 19:51:14 INFO - PROCESS | 1667 | |i|pagename|runs| 19:51:14 INFO - PROCESS | 1667 | |0;dojo.html;3633.8364773585186;3683.907902302442;3704.8144593730017;3727.944080838787;3741.326561218981;5964.582479226456;5966.5226781857455;5971.522278217743;6010.57925945184;6017.638941663492;4506.414166158394;5155.639105262633;5209.036328279261;5692.57305702072;5918.260217472816;5313.442088580701;5384.91922621159;5427.267318911956;5430.429804870491;5456.672599644015;5361.115415956382;5461.754221060042;5469.179623056553;5474.698891560963;5477.315335583052;5422.674639521621;5436.864078398052;5439.075357189287;5464.26232458617;5474.069408200596;5498.642588231752;5499.1476321169885;5500.697461639608;5503.284573005483;5516.531094856922;5372.435894231127;5424.484673955968;5434.728263586805;5472.50747432733;5540.390557038722;5359.678419294854;5798.797042103506;5808.361080281165;5976.820695379102;6150.261968563756;6253.593516421418;6277.246730392382;6296.96851515744;6298.937010629881;6302.18071650687;6322.272938612073;6327.873442531123;6334.936650633481;6354.078658594524;6358.87282254357;5970.492508136814;6047.274327080779;6114.266954506099;6119.1739115219325;6129.632222066601;6481.513886458534;6481.967590162019;6485.902711459332;6496.285408605049;6497.610143391412;6288.654124023223;6302.464290535356;6303.842403939847;6327.892618791693;6344.460720838781;6246.781362652286;6258.655773932386;6286.18279623646;6329.873402531924;6333.556651034475;6206.875862482725;6258.311585725566;6284.842878928063;6286.277078135981;6298;6190.597611155261;6236.563440559208;6299.149614802035;6303.338149494329;6309.936900631027;4963.230699241623;5028.969061342416;5077.1876499759865;5087.4149472811105;5103.234514822806;4547.727136371828;4571.382863313476;4571.725696458223;4573.588377046015;4618.745968971672;6009.278886533557;6135.570510064342;6135.754569817157;6158.661273629993;6205.410486003822;5244.213367994831;5585.106382978704;6331.493480521547;6391.392817682313;6426.260979987268;6150.7539698411565;6182.319944806068;6202.56582039262;6227.159333489921;6231.532635052389;5464.508194262536;5512.641678290898;5567.638103523258;5574.136008918624;5595.608307418523;6571.6057036577;6629.734810607618;6648.966755166193;6661.766838160641;6683.7660681875905;6402.167718196604;6403.807885763435;6414.871702565829;6424.51816113784;6457.096006558992;6337.302896681454;6475.546711730133;6587.505937054644;6653.268140504444;6660;5267.683938963674;6019.578629495893;6325.987841945267;6347.984322508377;6349.746010159542;6382.170317858649;6384.042393640805;6388.329225431263;6401.167848179707;6407.551471396958;6067.696615169312;6156.5998210116195;6280.905786413299;6290.685465726787;6316.873662526632;6238.513783459775;6264.655443950444;6272.933601287701;6274.560780745304;6275.403836635513;6076.088586711851;6182.9798083315745;6195.4424101831055;6196.504279657706;6240.219972503437;6183.443490085914;6209.0996805462055;6254.437100660963;6271.4669253114;6312.400321969406;6208.379162083756;6241.563090583615;6247.5001999839;6274.247090349005;6278.901192291422 19:51:14 INFO - PROCESS | 1667 | |1;ext.html;513.5814311336261;513.6661170239346;566.7506297229216;585.3765739487444;671.9752377624122;35050.82474587626;40364.78905632836;40456.39315410271;40471.98820029501;40557.78326650198;42021.789891050576;42308.36537451933;42610.57389426113;42619.57380426195;42693.35959960603;9687.467189304603;9990.800183996316;10004.299699021072;10006.399616023025;10065.899341006607;9827.312088153833;9978.650747223848;9996.700098997006;9999.550020249106;10006.949965250164;9933.552990115426;9967.202623790079;9986.300958932878;10008.39949603026;10016.799664006716;9878;9935.105840474356;9939.50302484873;9946.502674866228;9949.602015919354;9759.219262458986;9920.751981200456;9942.403455792675;9946.005399459997;9980.750481237954;36115.27769444596;41278.58721412777;41294.1741165178;41429.79285103555;41536.58463415357;3451.879184228565;3568;3589.210373717778;3602.6217247189193;3624.7100231981376;27769.444611107865;28492.572611410844;28916.566251506243;30151.547726784116;30246.212121212207;2777.2084955787705;2799.0623141247706;2865.8423786691724;2917.9562306565417;2928.6192794936524;522023;522432;529027.354863231;529084;530003;48386.75806620944;49145.50854491481;74154.25845741473;75571;75725.62137189279;48203.75898120487;48316.516834831906;48375.274370884465;48466.03067938622;48495.5150448498;16981.575460613338;17060.829391705924;17163.828361716474;17179;17345.132743363032;13654.52209172694;13657.521986730615;13678.97407694407;13744.106633068819;13829.654258643413;15014;15261.923690381476;15370.924035317645;15371.92314038445;15676.372945082068;6677.332266773284;6801.1158549388265;6841.726330946806;6853.800584897623;6855.725770969106;969.1084202533686;1048.8035998670366;1090.4711215060688;1128.5598616539555;1161.3322339654733;30463.847680761453;30477.390452190833;30480.695193047784;30978.53532197019;31201;1118.5246270335076;1166.5243467014234;1191.9642410727693;1198.8501437320335;1278.4630455208835;14691.265436728121;14868.759675645191;14961;15017.399304027935;15045.699086018438;522355;526209;529559;530547;530562.3471882616;55407.722961384934;55814.88370232654;56133.43866561364;56191.87616247734;56434.87130257454;1550.8991915525455;1560.11073687999;1583.2479572203342;1584.4454440945808;1590.4989928172588;25718.485630286916;25929.74070259273;25956.61065084063;26067.217983460527;26469.073582424524;50807;51468.48531514637;51599.742001291255;51601;51703.74148129235;510090;512546;514835;516379;516469;31774.36451271008;33598.15210163367;34733.8263308692;34872;35039;33963.830180848934;45106;46801;46889;47322;37670.62329376781;38462.230755385295;38502.80748596351;38579.6142038576;38797.80601096976;30869;33661.3267734639;35960.28079438449;38046.8097659521;38208.254222044125;38624.03439914025;39934;40780.18439631131;41667.58332416637;41713;29628;31547;31581.210469738442;33967.81112661045;33992.83003584966;20934.639248448802;20984.580308394052;24603.630945535475;25382.873085634452;25488.470691758554 19:51:14 INFO - PROCESS | 1667 | |2;jquery.html;383.73905744094003;388.0957369329462;395.8475986745103;396.90474286171326;398.5038626909498;19008.954507502098;19859.900700496495;21505.677414838752;21867.343979680616;22006.339809805693;18072.4578262652;18389;20660.690089648666;21994;22229.2219772308;5886.587938844283;5906.173135760997;5962.135490353896;6231.71957261922;6236.469900058501;6053.545984051192;6112.633242005494;6114.052321890092;6119.1739115219325;6154.846128846792;5751.309842818868;6038.637681739087;6088.178095957033;6091.543134264925;6102.328743838174;5944.346121926585;5953.761849526014;5978.880422391528;5994.670293133876;5997.370276121032;4937.728424936627;5495.395506494282;5721.227634269362;5821.097729851837;5975.581709280352;13671.384787684578;14021.228832414214;14515.766159876423;25208;25507;2128.0729283419296;2218.1681869299014;2245.7754224577575;2269.137727663494;2844.99002853988;10533.578656853717;13040.673983150402;14228.786568201485;15137.924310378377;15237.771433428508;1798.9370372037047;1825.917833697487;1886.7735871695484;1886.9433916982512;1897.2600685732575;112116.43941780321;113033.86966130315;114248;114746.99999999917;115531;11668.416579171007;11734.354610496504;11737.647870563897;11759;11907.940460297643;14100;14294.285285735672;14340.641483962776;14349;14385.928070359581;9035.909640903506;9041.683541076163;9087;9104.908950910538;9116.08839116083;9260.629574817067;9287.349885507949;9341.486218258062;9358.672446464341;9432.292578056673;4534.77326133692;4572.0855828834365;4584.289435137559;4620.976895115503;4651.697639653479;1597.5127586086123;1950.5221220800995;1971.5366888781125;1975.1111999600237;2003.459066052187;970.4225985538593;992.3797626483448;996.4320337407609;1050.5784167493848;1093.6664317383115;16140.112293823717;20604.38186854397;21181.15275388998;21448.24931127403;21890.45273868166;923.6490133749267;1028.6091285311684;1044.2272718188485;1058.2909450668071;1066.1097983183972;9622.951885240669;10260.538275777608;11590.478428470738;11618.94190529042;11845.289282643069;672367;674430;698948;699192.0589801931;702680;124838.75161248453;127489;129410.35294823465;132399;136443.31778341046;1088.9945550272357;1096.3093251251819;1103.586155191803;1108.4070022537903;1109.4674556212901;18771.906140469757;19180;19374.90312548428;19609.803901961368;19739.210431582575;34676.65323346734;48708.5129148704;53712.73143634257;54817.177742334694;56557.71721141533;77021.45957080714;77870;79497.60251198939;80422.5978870102;82572;5960.195373624505;7878.133405325524;7996.680132794623;8148.674053037812;8630.136986301572;11943.940280298542;13518.121322114031;13613.387397566936;13710.451581936612;13886.236257005557;8840.469571825712;10322.638707645196;11490;11570.710732231762;11911.702207444883;11563.942180289045;11690.824637630272;11753.059755219732;11918.761624767283;11984.40077996114;11420.543178272776;11713.1800773945;12124.333161676192;12130.575429859913;12213.38933053326;8517.168808706158;9561.665341713007;10181.625480560326;11694.824577631172;11876.881231187577;11204.775904482029;11335.093192544411;11893.345865977455;12165.20926139798;12333 19:51:14 INFO - PROCESS | 1667 | |3;mootools.html;1555.727747644162;1574.6142195162183;1700.4473546097522;1923.9134238959246;2090.1952748191943;8456.746297611082;9149.359544831865;9293.163615274623;9466.290028247884;9514.476703781298;7756.844863102735;9188.724338269845;9219.861702074475;9281.582328795204;9308.674196403128;2540.2633236361435;2552.706438759548;2571.3571607098224;2578.3296342950875;2580.406506503505;2519.1560827122935;2583.0184529878716;2587.1074479304702;2601.998230681192;2608.4522250327454;2565.3458368116103;2582.1091723355507;2611.5690910999756;2612.020492315382;2615.973840261592;2547.312225699051;2550.846949183055;2573.4981678572685;2583.935401614956;2598.649182360376;2537.936551586207;2563.474487730011;2571.1643715792443;2600.8699565021675;2607.79137668986;9367.812643747155;9409.153176214173;9418.717438476857;9442.622295108187;9454.90545094547;5757.65454072757;5821.476067153976;5830.4461076197695;5864.794732184353;5912.970435147797;3067.5552044953356;3157.1949152966076;3644.4168932970597;3801.486799282089;3811.9809400953095;980.2990861534038;1018.638383373899;1103.5254840418618;1111.2110401614864;1114.8885111488867;12460.501579936792;12568.685782855411;12600.36998150098;12612.306323152223;12625.873741262561;4992.151334273182;5073.746312684351;5122.974385128051;5138.665986710852;5162.1224391853475;10810.189235807346;10923.508442120125;10927.617533386294;10942.890571094187;10978.615748448767;5755.050416681201;5764.308283006066;5811.854703632443;5827.679477628772;5860.941390586039;4736.455307639597;5106.770195341219;5189.299444575009;5196.714180720097;5248.9737551312;4143.56492568276;4158.979205104016;4180.331147016463;4195.58044195578;4205.915881682349;1359.6532884114488;1367.931603419845;1371.814805001332;1372.2521225931891;1377.4490203918513;1190.5773450425147;1435.5047508609514;1448.1455940994863;1464.2751837840124;1479.7558402863408;7341.522801017917;8582.442141260799;8677.045524992245;8687.34844886636;8720.694775682821;529.5498825997871;532.9706866122323;534.9037173308842;538.4158188365667;549.5493695170002;6333.524985626004;6436.098946147543;6452.903206451907;6464.709088091047;6499.5775274607;11223;11229.382383968798;11345.94327028376;11370.488328025258;11566.19036667442;10803.243772935975;11106.055985241182;11222.438878056064;11313.604023859125;11346.94326528362;8747.125287471203;8861.64553417856;8879.778005549913;8968;9002.414843035182;8405.789855253668;8476.830463390574;8541.273991710774;8577.8713319299;8578.142185781371;9840.917499074953;9949.154321882721;10033.648822291185;10036.447995360033;10039.899601004188;10070.04334588213;10442.947785261329;10560.313579617608;10647.680569582924;10673.306235094695;7104.3961263293195;7677.808054798675;7872.212778722082;8399.580020998803;8621.180987806149;8725;8752.64989400417;8791.384603077979;8827.955860220658;8836.779080522781;7562.508436951581;7641.961790191013;7761.573113478813;7789.104253010976;7867;7561.168271490022;7630.2751238632245;7636.31273185416;7659.808504787425;7674.731384401519;8066.032076150899;8090.595470226583;8096.230858068474;8182.140875208018;8189.50862948225;7229.132504099542;7362.595057271687;7380.409567234501;7385;7394.482386232912;7544.471886968079;7551.962240188984;7564.621768911643;7572.962135189288;7590.468667193243 19:51:14 INFO - PROCESS | 1667 | |4;prototype.html;348.2269362016324;350.94034014217596;353.1453881606512;353.854919483012;355.6763345355726;6998.790036298907;7116.430685545157;7143.107111611055;7164.319389657987;7190;6994.8950765738555;7001.719931202746;7007.964960175192;7026.824329391755;7047.154341479017;4929.334539837112;5017.9247311290355;5114.820981265657;5121.359830021247;5209.400918894319;5056.241563765428;5080.110980578403;5090.338256026711;5093.159628661267;5135.178371460567;4991.351124353829;5033.370828646419;5043.949560504385;5045.520675535837;5054.545090941814;5044.369453818273;5070.923936140961;5078.669886457387;5094.745262736848;5096.872578185538;3578.5347904772475;4417.160739459525;4422.071365013351;4433.179861725559;4441.689081764278;6478.708458119396;6671.432928201109;6713.664316784141;6723.092382528345;6784.525083244175;5943.435373639497;5972.492338151263;5979.983402821487;5991.430814072656;6011.098335249748;7461.291177338144;7472.813179670497;7479.775606731807;7492.962535187344;7508.361789247921;4776.80892764289;4783.832565860178;4789.161896668069;4790.065937142259;4793.089313030548;7107.751228707022;7836.960815195887;7931.881021784678;7941.36469082472;7948.483348582324;3917.471141395932;4723.409573803275;4783.019481006385;4799.400074990626;4803.78382972767;4873.8546441586195;4875.293082503017;4882.145624515695;4889.706617602955;4901.828436004723;5283.365996080495;5377.7311134443125;5927.3776253493625;5942.584019118706;5957.880842383128;6919.446444284546;7465.589392583352;7469.551826890404;7478.700851965969;7514.6618402172035;1512.7428337182484;1657.395050806447;1712.161041089857;1715.9656806863793;1717.785276840395;979.8677178580942;1082.5182793656904;1091.879893211746;1092.8688557373166;1094.9835752463719;7006.0892084029765;7061.682224299919;7063.82340441493;7107.8933815992805;7133.500654954103;7766.883496747553;7777.883331750028;7787.260210280015;7787.844243115106;7820.257075577811;6985.266547012592;7054.894176587355;7057.752978645723;7067.2579379164445;7082.185548661867;3916.3342231820657;4051.5745846686264;4071.592840715964;4090.427340172378;4100.7334523255895;7056.858862822714;7487.625618719042;8083.434159608771;8148.185181481923;8194.22154895284;7938.682452701827;7979.202079791974;7994.720184793504;8006.119326874039;8019.799505012421;8076.3942704297415;8077.596120193967;8089.555074470843;8099.339635374734;8102.918970810217;7453.925460745323;7729.574873382018;7731.304182623591;7774.222577742181;7798.376129909707;6210.906836397367;7509.3617042552;7515.887261690969;7565.810854728676;7594.658240379085;8106.9189308108525;8110.878336825071;8123.228293312126;8158.83682326362;8190.385721070825;4881.193958235333;4883.096627123993;4883.780229889592;4896.192128298933;4897.069556784199;7423.109226892808;7573.810654733676;7737.3423259021365;7854.960725196566;7867.803304917423;7503.062117235346;7561.470697051153;7575.128860181149;7612.505187162817;7749.651265692944;7529.510581812165;7539.095308563007;7544.622768861425;7563.810904727426;7573.772786816405;7035.1909530404655;7291.125064992235;7293.671784769592;7304.890426643711;7377.483576149618;7507.849843003;7539.886901696588;7575.734849280248;7576.24237576238;7584.241575842371;7377.852442951218;7394.963025184839;7400.703971841066;7409.888851667121;7415.480916335805 19:51:14 INFO - PROCESS | 1667 | |5;yui.html;717.6842189436649;772.1622040086506;779.2558107007804;791.2917938445088;791.5962858941938;15245.31396087176;15490.380384784596;15698;15705;15716.842831571681;14509.984301098928;14534.927325363358;14561.92719036406;14567.490137845178;14600.488982885601;795.6101510259989;958.5399008475936;973.6397532912832;999.3104757717189;1003.7289931718432;977.9657711980082;984.1585444445004;984.7833476635129;985.8767654043245;986.6004268271325;1036.0364860679604;1049.0244073012095;1054.19880890523;1058.2750815691277;1065;1014.5586669798661;1037.3205550364523;1049.0244073012057;1051.332403923514;1054.5360041581719;782.5695867273034;783.4515838912783;790.7034861926777;794.789380814079;800.6237068577759;13495.730085398336;15335.23323833815;15430.459933902372;15479.380824767108;15534.61163470911;13161.473541058345;13184.934075329658;13216.405261763244;13341;13382;8309.37679674027;10273.794524109477;10337.483125843753;10392.324498907547;10486.790264194673;3538.097785064818;3571.089372210096;3628;3669.1377526281303;3835.347990841564;76255.47489050188;76560.61719691366;76836;76847;76864;9497.857532137023;9815.852762208573;9860.211183105333;9936.950315248378;10100.393976361294;23679.644805327935;23904.40238994039;23937.52124957491;23970.40073998164;24041.639375409384;7736.149023607399;7940.325072368913;7987.800304992305;8021.358291336679;8112.959435202904;3946.6645335146836;3956.624120708528;4143.19207754491;4315.546867578859;4329.09089091288;3719;3748.5314335708035;3751.324761542894;3770.321342158383;3777.395616701315;696.1298377028714;698.2319448606594;700.8948657701284;702.301210295759;702.6908160409404;563.1355868741463;584.7139078379535;612.6752821004875;614.8585825260216;617.2253821454046;8200.507969521848;8439.451435656663;8484;8608.913910860936;8870.024297327289;549.4532939725;565.2312854517852;580.1269090019493;580.7996241296746;585.4233579923772;3311.1729795603014;3928;3941.7608326147474;3967.8809635710977;4035.7982100894837;93542;95185.5240723792;95567.52216238875;95598;97092;82234.3553128934;83096.58451707703;83113.58443207866;83346;84618.15381846103;67974;69503;70231;71200;71530.64234678794;8604.827903442021;8611.655533778829;8707.738767836721;8749.91250087491;8755.12448755145;49167.50832491629;50721.239181413046;51400.228996564336;51883.96232075262;52466.737666312954;68931.3106868925;69304;70581.29418705747;70864;75336.62331688307;11413.315201087962;11516;11585.478653460783;11635.011024062711;11726.472308745957;12045.09661775388;12048.156629036233;12110.75778484443;12238.265704057429;12710.618681439571;11711.063114950613;11721.706957325794;11810;11879.406029698308;12431.440585173508;11679.76640467203;11703.941480292544;11735.589254376056;12187.695307617381;12272.570460033856;11374.886251137383;11473.196876218584;11480.483378248166;11792.174547781573;11809.291442513477;9615.070021068286;9733.270004749531;9830.606775728891;9933.801323973625;10222.795544089226;9058.592363343798;9749.927507973975;9843.36018158846;10021.39871607706;10122.89877101249 19:51:14 INFO - PROCESS | 1667 | __end_tp_report 19:51:14 INFO - PROCESS | 1667 | __start_cc_report 19:51:14 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,357 19:51:14 INFO - PROCESS | 1667 | __end_cc_report 19:51:14 INFO - PROCESS | 1667 | __startTimestamp1463626274384__endTimestamp 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | ------- Summary: start ------- 19:51:14 INFO - PROCESS | 1667 | Number of tests: 6 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5911.97 Median:6196.50 stddev:632.86 (10.2%) stddev-sans-first:609.05 19:51:14 INFO - PROCESS | 1667 | Values: 3633.8 3683.9 3704.8 3727.9 3741.3 5964.6 5966.5 5971.5 6010.6 6017.6 4506.4 5155.6 5209.0 5692.6 5918.3 5313.4 5384.9 5427.3 5430.4 5456.7 5361.1 5461.8 5469.2 5474.7 5477.3 5422.7 5436.9 5439.1 5464.3 5474.1 5498.6 5499.1 5500.7 5503.3 5516.5 5372.4 5424.5 5434.7 5472.5 5540.4 5359.7 5798.8 5808.4 5976.8 6150.3 6253.6 6277.2 6297.0 6298.9 6302.2 6322.3 6327.9 6334.9 6354.1 6358.9 5970.5 6047.3 6114.3 6119.2 6129.6 6481.5 6482.0 6485.9 6496.3 6497.6 6288.7 6302.5 6303.8 6327.9 6344.5 6246.8 6258.7 6286.2 6329.9 6333.6 6206.9 6258.3 6284.8 6286.3 6298.0 6190.6 6236.6 6299.1 6303.3 6309.9 4963.2 5029.0 5077.2 5087.4 5103.2 4547.7 4571.4 4571.7 4573.6 4618.7 6009.3 6135.6 6135.8 6158.7 6205.4 5244.2 5585.1 6331.5 6391.4 6426.3 6150.8 6182.3 6202.6 6227.2 6231.5 5464.5 5512.6 5567.6 5574.1 5595.6 6571.6 6629.7 6649.0 6661.8 6683.8 6402.2 6403.8 6414.9 6424.5 6457.1 6337.3 6475.5 6587.5 6653.3 6660.0 5267.7 6019.6 6326.0 6348.0 6349.7 6382.2 6384.0 6388.3 6401.2 6407.6 6067.7 6156.6 6280.9 6290.7 6316.9 6238.5 6264.7 6272.9 6274.6 6275.4 6076.1 6183.0 6195.4 6196.5 6240.2 6183.4 6209.1 6254.4 6271.5 6312.4 6208.4 6241.6 6247.5 6274.2 6278.9 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:65924.54 Median:38541.21 stddev:139250.63 (361.3%) stddev-sans-first:139554.66 19:51:14 INFO - PROCESS | 1667 | Values: 513.6 513.7 566.8 585.4 672.0 35050.8 40364.8 40456.4 40472.0 40557.8 42021.8 42308.4 42610.6 42619.6 42693.4 9687.5 9990.8 10004.3 10006.4 10065.9 9827.3 9978.7 9996.7 9999.6 10006.9 9933.6 9967.2 9986.3 10008.4 10016.8 9878.0 9935.1 9939.5 9946.5 9949.6 9759.2 9920.8 9942.4 9946.0 9980.8 36115.3 41278.6 41294.2 41429.8 41536.6 3451.9 3568.0 3589.2 3602.6 3624.7 27769.4 28492.6 28916.6 30151.5 30246.2 2777.2 2799.1 2865.8 2918.0 2928.6 522023.0 522432.0 529027.4 529084.0 530003.0 48386.8 49145.5 74154.3 75571.0 75725.6 48203.8 48316.5 48375.3 48466.0 48495.5 16981.6 17060.8 17163.8 17179.0 17345.1 13654.5 13657.5 13679.0 13744.1 13829.7 15014.0 15261.9 15370.9 15371.9 15676.4 6677.3 6801.1 6841.7 6853.8 6855.7 969.1 1048.8 1090.5 1128.6 1161.3 30463.8 30477.4 30480.7 30978.5 31201.0 1118.5 1166.5 1192.0 1198.9 1278.5 14691.3 14868.8 14961.0 15017.4 15045.7 522355.0 526209.0 529559.0 530547.0 530562.3 55407.7 55814.9 56133.4 56191.9 56434.9 1550.9 1560.1 1583.2 1584.4 1590.5 25718.5 25929.7 25956.6 26067.2 26469.1 50807.0 51468.5 51599.7 51601.0 51703.7 510090.0 512546.0 514835.0 516379.0 516469.0 31774.4 33598.2 34733.8 34872.0 35039.0 33963.8 45106.0 46801.0 46889.0 47322.0 37670.6 38462.2 38502.8 38579.6 38797.8 30869.0 33661.3 35960.3 38046.8 38208.3 38624.0 39934.0 40780.2 41667.6 41713.0 29628.0 31547.0 31581.2 33967.8 33992.8 20934.6 20984.6 24603.6 25382.9 25488.5 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:37578.74 Median:20632.54 stddev:114274.56 (553.9%) stddev-sans-first:114561.00 19:51:14 INFO - PROCESS | 1667 | Values: 383.7 388.1 395.8 396.9 398.5 19009.0 19859.9 21505.7 21867.3 22006.3 18072.5 18389.0 20660.7 21994.0 22229.2 5886.6 5906.2 5962.1 6231.7 6236.5 6053.5 6112.6 6114.1 6119.2 6154.8 5751.3 6038.6 6088.2 6091.5 6102.3 5944.3 5953.8 5978.9 5994.7 5997.4 4937.7 5495.4 5721.2 5821.1 5975.6 13671.4 14021.2 14515.8 25208.0 25507.0 2128.1 2218.2 2245.8 2269.1 2845.0 10533.6 13040.7 14228.8 15137.9 15237.8 1798.9 1825.9 1886.8 1886.9 1897.3 112116.4 113033.9 114248.0 114747.0 115531.0 11668.4 11734.4 11737.6 11759.0 11907.9 14100.0 14294.3 14340.6 14349.0 14385.9 9035.9 9041.7 9087.0 9104.9 9116.1 9260.6 9287.3 9341.5 9358.7 9432.3 4534.8 4572.1 4584.3 4621.0 4651.7 1597.5 1950.5 1971.5 1975.1 2003.5 970.4 992.4 996.4 1050.6 1093.7 16140.1 20604.4 21181.2 21448.2 21890.5 923.6 1028.6 1044.2 1058.3 1066.1 9623.0 10260.5 11590.5 11618.9 11845.3 672367.0 674430.0 698948.0 699192.1 702680.0 124838.8 127489.0 129410.4 132399.0 136443.3 1089.0 1096.3 1103.6 1108.4 1109.5 18771.9 19180.0 19374.9 19609.8 19739.2 34676.7 48708.5 53712.7 54817.2 56557.7 77021.5 77870.0 79497.6 80422.6 82572.0 5960.2 7878.1 7996.7 8148.7 8630.1 11943.9 13518.1 13613.4 13710.5 13886.2 8840.5 10322.6 11490.0 11570.7 11911.7 11563.9 11690.8 11753.1 11918.8 11984.4 11420.5 11713.2 12124.3 12130.6 12213.4 8517.2 9561.7 10181.6 11694.8 11876.9 11204.8 11335.1 11893.3 12165.2 12333.0 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:6386.35 Median:5222.84 stddev:3409.33 (65.3%) stddev-sans-first:3399.56 19:51:14 INFO - PROCESS | 1667 | Values: 1555.7 1574.6 1700.4 1923.9 2090.2 8456.7 9149.4 9293.2 9466.3 9514.5 7756.8 9188.7 9219.9 9281.6 9308.7 2540.3 2552.7 2571.4 2578.3 2580.4 2519.2 2583.0 2587.1 2602.0 2608.5 2565.3 2582.1 2611.6 2612.0 2616.0 2547.3 2550.8 2573.5 2583.9 2598.6 2537.9 2563.5 2571.2 2600.9 2607.8 9367.8 9409.2 9418.7 9442.6 9454.9 5757.7 5821.5 5830.4 5864.8 5913.0 3067.6 3157.2 3644.4 3801.5 3812.0 980.3 1018.6 1103.5 1111.2 1114.9 12460.5 12568.7 12600.4 12612.3 12625.9 4992.2 5073.7 5123.0 5138.7 5162.1 10810.2 10923.5 10927.6 10942.9 10978.6 5755.1 5764.3 5811.9 5827.7 5860.9 4736.5 5106.8 5189.3 5196.7 5249.0 4143.6 4159.0 4180.3 4195.6 4205.9 1359.7 1367.9 1371.8 1372.3 1377.4 1190.6 1435.5 1448.1 1464.3 1479.8 7341.5 8582.4 8677.0 8687.3 8720.7 529.5 533.0 534.9 538.4 549.5 6333.5 6436.1 6452.9 6464.7 6499.6 11223.0 11229.4 11345.9 11370.5 11566.2 10803.2 11106.1 11222.4 11313.6 11346.9 8747.1 8861.6 8879.8 8968.0 9002.4 8405.8 8476.8 8541.3 8577.9 8578.1 9840.9 9949.2 10033.6 10036.4 10039.9 10070.0 10442.9 10560.3 10647.7 10673.3 7104.4 7677.8 7872.2 8399.6 8621.2 8725.0 8752.6 8791.4 8828.0 8836.8 7562.5 7642.0 7761.6 7789.1 7867.0 7561.2 7630.3 7636.3 7659.8 7674.7 8066.0 8090.6 8096.2 8182.1 8189.5 7229.1 7362.6 7380.4 7385.0 7394.5 7544.5 7552.0 7564.6 7573.0 7590.5 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:6107.96 Median:7055.88 stddev:1989.89 (28.2%) stddev-sans-first:1947.94 19:51:14 INFO - PROCESS | 1667 | Values: 348.2 350.9 353.1 353.9 355.7 6998.8 7116.4 7143.1 7164.3 7190.0 6994.9 7001.7 7008.0 7026.8 7047.2 4929.3 5017.9 5114.8 5121.4 5209.4 5056.2 5080.1 5090.3 5093.2 5135.2 4991.4 5033.4 5043.9 5045.5 5054.5 5044.4 5070.9 5078.7 5094.7 5096.9 3578.5 4417.2 4422.1 4433.2 4441.7 6478.7 6671.4 6713.7 6723.1 6784.5 5943.4 5972.5 5980.0 5991.4 6011.1 7461.3 7472.8 7479.8 7493.0 7508.4 4776.8 4783.8 4789.2 4790.1 4793.1 7107.8 7837.0 7931.9 7941.4 7948.5 3917.5 4723.4 4783.0 4799.4 4803.8 4873.9 4875.3 4882.1 4889.7 4901.8 5283.4 5377.7 5927.4 5942.6 5957.9 6919.4 7465.6 7469.6 7478.7 7514.7 1512.7 1657.4 1712.2 1716.0 1717.8 979.9 1082.5 1091.9 1092.9 1095.0 7006.1 7061.7 7063.8 7107.9 7133.5 7766.9 7777.9 7787.3 7787.8 7820.3 6985.3 7054.9 7057.8 7067.3 7082.2 3916.3 4051.6 4071.6 4090.4 4100.7 7056.9 7487.6 8083.4 8148.2 8194.2 7938.7 7979.2 7994.7 8006.1 8019.8 8076.4 8077.6 8089.6 8099.3 8102.9 7453.9 7729.6 7731.3 7774.2 7798.4 6210.9 7509.4 7515.9 7565.8 7594.7 8106.9 8110.9 8123.2 8158.8 8190.4 4881.2 4883.1 4883.8 4896.2 4897.1 7423.1 7573.8 7737.3 7855.0 7867.8 7503.1 7561.5 7575.1 7612.5 7749.7 7529.5 7539.1 7544.6 7563.8 7573.8 7035.2 7291.1 7293.7 7304.9 7377.5 7507.8 7539.9 7575.7 7576.2 7584.2 7377.9 7395.0 7400.7 7409.9 7415.5 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18793.36 Median:51060.73 stddev:26200.71 (51.3%) stddev-sans-first:26239.05 19:51:14 INFO - PROCESS | 1667 | Values: 717.7 772.2 779.3 791.3 791.6 15245.3 15490.4 15698.0 15705.0 15716.8 14510.0 14534.9 14561.9 14567.5 14600.5 795.6 958.5 973.6 999.3 1003.7 978.0 984.2 984.8 985.9 986.6 1036.0 1049.0 1054.2 1058.3 1065.0 1014.6 1037.3 1049.0 1051.3 1054.5 782.6 783.5 790.7 794.8 800.6 13495.7 15335.2 15430.5 15479.4 15534.6 13161.5 13184.9 13216.4 13341.0 13382.0 8309.4 10273.8 10337.5 10392.3 10486.8 3538.1 3571.1 3628.0 3669.1 3835.3 76255.5 76560.6 76836.0 76847.0 76864.0 9497.9 9815.9 9860.2 9937.0 10100.4 23679.6 23904.4 23937.5 23970.4 24041.6 7736.1 7940.3 7987.8 8021.4 8113.0 3946.7 3956.6 4143.2 4315.5 4329.1 3719.0 3748.5 3751.3 3770.3 3777.4 696.1 698.2 700.9 702.3 702.7 563.1 584.7 612.7 614.9 617.2 8200.5 8439.5 8484.0 8608.9 8870.0 549.5 565.2 580.1 580.8 585.4 3311.2 3928.0 3941.8 3967.9 4035.8 93542.0 95185.5 95567.5 95598.0 97092.0 82234.4 83096.6 83113.6 83346.0 84618.2 67974.0 69503.0 70231.0 71200.0 71530.6 8604.8 8611.7 8707.7 8749.9 8755.1 49167.5 50721.2 51400.2 51884.0 52466.7 68931.3 69304.0 70581.3 70864.0 75336.6 11413.3 11516.0 11585.5 11635.0 11726.5 12045.1 12048.2 12110.8 12238.3 12710.6 11711.1 11721.7 11810.0 11879.4 12431.4 11679.8 11703.9 11735.6 12187.7 12272.6 11374.9 11473.2 11480.5 11792.2 11809.3 9615.1 9733.3 9830.6 9933.8 10222.8 9058.6 9749.9 9843.4 10021.4 10122.9 19:51:14 INFO - PROCESS | 1667 | -------- Summary: end -------- 19:51:14 INFO - PROCESS | 1667 | 19:51:14 INFO - TEST-INFO | 1667: exit 0 19:51:14 INFO - TEST-OK | dromaeo_css | took 1109510ms 19:51:14 INFO - TEST-START | kraken 19:51:14 INFO - operating with platform_type : mac_ 19:51:14 INFO - Initialising browser for kraken test... 19:51:14 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpMyc5bp/profile http://localhost:49277/getInfo.html) 19:51:17 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 19:51:17 INFO - PROCESS | 1675 | colorDepth:24 19:51:17 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 19:51:17 INFO - PROCESS | 1675 | __metrics 19:51:17 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 19:51:18 INFO - TEST-INFO | 1675: exit 0 19:51:18 INFO - Browser initialized. 19:51:18 INFO - Running cycle 1/1 for kraken test... 19:51:18 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpMyc5bp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:51:30 INFO - PROCESS | 1679 | RSS: Main: 167915520 19:51:30 INFO - PROCESS | 1679 | 19:51:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 19:51:33 INFO - PROCESS | 1679 | RSS: Main: 179793920 19:51:33 INFO - PROCESS | 1679 | 19:51:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 19:51:36 INFO - PROCESS | 1679 | RSS: Main: 188760064 19:51:36 INFO - PROCESS | 1679 | 19:51:40 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 19:51:40 INFO - PROCESS | 1679 | RSS: Main: 192208896 19:51:40 INFO - PROCESS | 1679 | 19:51:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 19:51:43 INFO - PROCESS | 1679 | RSS: Main: 193859584 19:51:43 INFO - PROCESS | 1679 | 19:51:45 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:51:46 INFO - PROCESS | 1679 | RSS: Main: 194252800 19:51:46 INFO - PROCESS | 1679 | 19:51:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 19:51:49 INFO - PROCESS | 1679 | RSS: Main: 194940928 19:51:49 INFO - PROCESS | 1679 | 19:51:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 19:51:53 INFO - PROCESS | 1679 | RSS: Main: 194605056 19:51:53 INFO - PROCESS | 1679 | 19:51:56 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 19:51:56 INFO - PROCESS | 1679 | RSS: Main: 194686976 19:51:56 INFO - PROCESS | 1679 | 19:51:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:51:58 INFO - PROCESS | 1679 | RSS: Main: 194703360 19:51:58 INFO - PROCESS | 1679 | 19:52:00 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:52:01 INFO - PROCESS | 1679 | RSS: Main: 194342912 19:52:01 INFO - PROCESS | 1679 | 19:52:03 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:52:04 INFO - PROCESS | 1679 | RSS: Main: 194433024 19:52:04 INFO - PROCESS | 1679 | 19:52:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:52:07 INFO - PROCESS | 1679 | RSS: Main: 194637824 19:52:07 INFO - PROCESS | 1679 | 19:52:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:52:10 INFO - PROCESS | 1679 | RSS: Main: 194498560 19:52:10 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 19:52:12 INFO - PROCESS | 1679 | RSS: Main: 196087808 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | __start_tp_report 19:52:12 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 19:52:12 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 19:52:12 INFO - PROCESS | 1679 | |i|pagename|runs| 19:52:12 INFO - PROCESS | 1679 | |0;ai-astar;115;112;168;118;118;128;115;115;116;116 19:52:12 INFO - PROCESS | 1679 | |1;audio-beat-detection;154;119;149;149;126;173;156;185;148;116 19:52:12 INFO - PROCESS | 1679 | |2;audio-dft;197;198;200;203;193;195;196;192;204;199 19:52:12 INFO - PROCESS | 1679 | |3;audio-fft;62;67;77;110;60;73;85;86;162;58 19:52:12 INFO - PROCESS | 1679 | |4;audio-oscillator;99;80;114;105;121;120;162;99;79;117 19:52:12 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;111;129;112;85;87;86;81;132;135;88 19:52:12 INFO - PROCESS | 1679 | |6;imaging-darkroom;138;95;97;135;94;95;96;95;95;94 19:52:12 INFO - PROCESS | 1679 | |7;imaging-desaturate;93;92;91;89;134;134;82;144;140;128 19:52:12 INFO - PROCESS | 1679 | |8;json-parse-financial;56;60;57;58;59;63;66;83;90;85 19:52:12 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;60;77;100;96;65;95;103;97;102;80 19:52:12 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;82;83;96;109;86;83;103;110;77;101 19:52:12 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;143;138;119;119;120;123;117;120;126;120 19:52:12 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;133;140;130;153;130;150;181;176;136;130 19:52:12 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;63;69;64;65;65;67;68;69;63;68 19:52:12 INFO - PROCESS | 1679 | __end_tp_report 19:52:12 INFO - PROCESS | 1679 | __start_cc_report 19:52:12 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,914 19:52:12 INFO - PROCESS | 1679 | __end_cc_report 19:52:12 INFO - PROCESS | 1679 | __startTimestamp1463626332616__endTimestamp 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | ------- Summary: start ------- 19:52:12 INFO - PROCESS | 1679 | Number of tests: 14 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:122.10 Median:117.00 stddev:16.68 (14.3%) stddev-sans-first:17.49 19:52:12 INFO - PROCESS | 1679 | Values: 115.0 112.0 168.0 118.0 118.0 128.0 115.0 115.0 116.0 116.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:147.50 Median:151.50 stddev:22.22 (14.7%) stddev-sans-first:23.44 19:52:12 INFO - PROCESS | 1679 | Values: 154.0 119.0 149.0 149.0 126.0 173.0 156.0 185.0 148.0 116.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:197.70 Median:198.50 stddev:3.95 (2.0%) stddev-sans-first:4.18 19:52:12 INFO - PROCESS | 1679 | Values: 197.0 198.0 200.0 203.0 193.0 195.0 196.0 192.0 204.0 199.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:84.00 Median:70.00 stddev:31.55 (45.1%) stddev-sans-first:32.45 19:52:12 INFO - PROCESS | 1679 | Values: 62.0 67.0 77.0 110.0 60.0 73.0 85.0 86.0 162.0 58.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:120.50 stddev:23.84 (19.8%) stddev-sans-first:24.98 19:52:12 INFO - PROCESS | 1679 | Values: 99.0 80.0 114.0 105.0 121.0 120.0 162.0 99.0 79.0 117.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:83.00 stddev:21.70 (26.1%) stddev-sans-first:22.89 19:52:12 INFO - PROCESS | 1679 | Values: 111.0 129.0 112.0 85.0 87.0 86.0 81.0 132.0 135.0 88.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:95.00 stddev:17.48 (18.4%) stddev-sans-first:13.32 19:52:12 INFO - PROCESS | 1679 | Values: 138.0 95.0 97.0 135.0 94.0 95.0 96.0 95.0 95.0 94.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:112.70 Median:85.50 stddev:25.07 (29.3%) stddev-sans-first:25.56 19:52:12 INFO - PROCESS | 1679 | Values: 93.0 92.0 91.0 89.0 134.0 134.0 82.0 144.0 140.0 128.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:64.50 stddev:13.06 (20.3%) stddev-sans-first:13.15 19:52:12 INFO - PROCESS | 1679 | Values: 56.0 60.0 57.0 58.0 59.0 63.0 66.0 83.0 90.0 85.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:87.50 Median:78.50 stddev:15.83 (20.2%) stddev-sans-first:13.30 19:52:12 INFO - PROCESS | 1679 | Values: 60.0 77.0 100.0 96.0 65.0 95.0 103.0 97.0 102.0 80.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:82.50 stddev:12.22 (14.8%) stddev-sans-first:12.30 19:52:12 INFO - PROCESS | 1679 | Values: 82.0 83.0 96.0 109.0 86.0 83.0 103.0 110.0 77.0 101.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:121.50 stddev:8.86 (7.3%) stddev-sans-first:6.39 19:52:12 INFO - PROCESS | 1679 | Values: 143.0 138.0 119.0 119.0 120.0 123.0 117.0 120.0 126.0 120.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.90 Median:145.00 stddev:19.04 (13.1%) stddev-sans-first:19.62 19:52:12 INFO - PROCESS | 1679 | Values: 133.0 140.0 130.0 153.0 130.0 150.0 181.0 176.0 136.0 130.0 19:52:12 INFO - PROCESS | 1679 | 19:52:12 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.50 stddev:2.38 (3.5%) stddev-sans-first:2.24 19:52:12 INFO - PROCESS | 1679 | Values: 63.0 69.0 64.0 65.0 65.0 67.0 68.0 69.0 63.0 68.0 19:52:12 INFO - PROCESS | 1679 | -------- Summary: end -------- 19:52:12 INFO - PROCESS | 1679 | 19:52:13 INFO - TEST-INFO | 1679: exit 0 19:52:13 INFO - TEST-OK | kraken | took 58444ms 19:52:13 INFO - SUITE-END | took 1167s 19:52:13 INFO - Completed test suite (00:19:28) 19:52:13 INFO - javascript benchmark 19:52:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3633.8364773585186, 3683.907902302442, 3704.8144593730017, 3727.944080838787, 3741.326561218981, 5964.582479226456, 5966.5226781857455, 5971.522278217743, 6010.57925945184, 6017.638941663492, 4506.414166158394, 5155.639105262633, 5209.036328279261, 5692.57305702072, 5918.260217472816, 5313.442088580701, 5384.91922621159, 5427.267318911956, 5430.429804870491, 5456.672599644015, 5361.115415956382, 5461.754221060042, 5469.179623056553, 5474.698891560963, 5477.315335583052, 5422.674639521621, 5436.864078398052, 5439.075357189287, 5464.26232458617, 5474.069408200596, 5498.642588231752, 5499.1476321169885, 5500.697461639608, 5503.284573005483, 5516.531094856922, 5372.435894231127, 5424.484673955968, 5434.728263586805, 5472.50747432733, 5540.390557038722, 5359.678419294854, 5798.797042103506, 5808.361080281165, 5976.820695379102, 6150.261968563756, 6253.593516421418, 6277.246730392382, 6296.96851515744, 6298.937010629881, 6302.18071650687, 6322.272938612073, 6327.873442531123, 6334.936650633481, 6354.078658594524, 6358.87282254357, 5970.492508136814, 6047.274327080779, 6114.266954506099, 6119.1739115219325, 6129.632222066601, 6481.513886458534, 6481.967590162019, 6485.902711459332, 6496.285408605049, 6497.610143391412, 6288.654124023223, 6302.464290535356, 6303.842403939847, 6327.892618791693, 6344.460720838781, 6246.781362652286, 6258.655773932386, 6286.18279623646, 6329.873402531924, 6333.556651034475, 6206.875862482725, 6258.311585725566, 6284.842878928063, 6286.277078135981, 6298.0, 6190.597611155261, 6236.563440559208, 6299.149614802035, 6303.338149494329, 6309.936900631027, 4963.230699241623, 5028.969061342416, 5077.1876499759865, 5087.4149472811105, 5103.234514822806, 4547.727136371828, 4571.382863313476, 4571.725696458223, 4573.588377046015, 4618.745968971672, 6009.278886533557, 6135.570510064342, 6135.754569817157, 6158.661273629993, 6205.410486003822, 5244.213367994831, 5585.106382978704, 6331.493480521547, 6391.392817682313, 6426.260979987268, 6150.7539698411565, 6182.319944806068, 6202.56582039262, 6227.159333489921, 6231.532635052389, 5464.508194262536, 5512.641678290898, 5567.638103523258, 5574.136008918624, 5595.608307418523, 6571.6057036577, 6629.734810607618, 6648.966755166193, 6661.766838160641, 6683.7660681875905, 6402.167718196604, 6403.807885763435, 6414.871702565829, 6424.51816113784, 6457.096006558992, 6337.302896681454, 6475.546711730133, 6587.505937054644, 6653.268140504444, 6660.0, 5267.683938963674, 6019.578629495893, 6325.987841945267, 6347.984322508377, 6349.746010159542, 6382.170317858649, 6384.042393640805, 6388.329225431263, 6401.167848179707, 6407.551471396958, 6067.696615169312, 6156.5998210116195, 6280.905786413299, 6290.685465726787, 6316.873662526632, 6238.513783459775, 6264.655443950444, 6272.933601287701, 6274.560780745304, 6275.403836635513, 6076.088586711851, 6182.9798083315745, 6195.4424101831055, 6196.504279657706, 6240.219972503437, 6183.443490085914, 6209.0996805462055, 6254.437100660963, 6271.4669253114, 6312.400321969406, 6208.379162083756, 6241.563090583615, 6247.5001999839, 6274.247090349005, 6278.901192291422], "unit": "score", "name": "dojo.html", "value": 5875.186314013766}, {"lowerIsBetter": false, "replicates": [513.5814311336261, 513.6661170239346, 566.7506297229216, 585.3765739487444, 671.9752377624122, 35050.82474587626, 40364.78905632836, 40456.39315410271, 40471.98820029501, 40557.78326650198, 42021.789891050576, 42308.36537451933, 42610.57389426113, 42619.57380426195, 42693.35959960603, 9687.467189304603, 9990.800183996316, 10004.299699021072, 10006.399616023025, 10065.899341006607, 9827.312088153833, 9978.650747223848, 9996.700098997006, 9999.550020249106, 10006.949965250164, 9933.552990115426, 9967.202623790079, 9986.300958932878, 10008.39949603026, 10016.799664006716, 9878.0, 9935.105840474356, 9939.50302484873, 9946.502674866228, 9949.602015919354, 9759.219262458986, 9920.751981200456, 9942.403455792675, 9946.005399459997, 9980.750481237954, 36115.27769444596, 41278.58721412777, 41294.1741165178, 41429.79285103555, 41536.58463415357, 3451.879184228565, 3568.0, 3589.210373717778, 3602.6217247189193, 3624.7100231981376, 27769.444611107865, 28492.572611410844, 28916.566251506243, 30151.547726784116, 30246.212121212207, 2777.2084955787705, 2799.0623141247706, 2865.8423786691724, 2917.9562306565417, 2928.6192794936524, 522023.0, 522432.0, 529027.354863231, 529084.0, 530003.0, 48386.75806620944, 49145.50854491481, 74154.25845741473, 75571.0, 75725.62137189279, 48203.75898120487, 48316.516834831906, 48375.274370884465, 48466.03067938622, 48495.5150448498, 16981.575460613338, 17060.829391705924, 17163.828361716474, 17179.0, 17345.132743363032, 13654.52209172694, 13657.521986730615, 13678.97407694407, 13744.106633068819, 13829.654258643413, 15014.0, 15261.923690381476, 15370.924035317645, 15371.92314038445, 15676.372945082068, 6677.332266773284, 6801.1158549388265, 6841.726330946806, 6853.800584897623, 6855.725770969106, 969.1084202533686, 1048.8035998670366, 1090.4711215060688, 1128.5598616539555, 1161.3322339654733, 30463.847680761453, 30477.390452190833, 30480.695193047784, 30978.53532197019, 31201.0, 1118.5246270335076, 1166.5243467014234, 1191.9642410727693, 1198.8501437320335, 1278.4630455208835, 14691.265436728121, 14868.759675645191, 14961.0, 15017.399304027935, 15045.699086018438, 522355.0, 526209.0, 529559.0, 530547.0, 530562.3471882616, 55407.722961384934, 55814.88370232654, 56133.43866561364, 56191.87616247734, 56434.87130257454, 1550.8991915525455, 1560.11073687999, 1583.2479572203342, 1584.4454440945808, 1590.4989928172588, 25718.485630286916, 25929.74070259273, 25956.61065084063, 26067.217983460527, 26469.073582424524, 50807.0, 51468.48531514637, 51599.742001291255, 51601.0, 51703.74148129235, 510090.0, 512546.0, 514835.0, 516379.0, 516469.0, 31774.36451271008, 33598.15210163367, 34733.8263308692, 34872.0, 35039.0, 33963.830180848934, 45106.0, 46801.0, 46889.0, 47322.0, 37670.62329376781, 38462.230755385295, 38502.80748596351, 38579.6142038576, 38797.80601096976, 30869.0, 33661.3267734639, 35960.28079438449, 38046.8097659521, 38208.254222044125, 38624.03439914025, 39934.0, 40780.18439631131, 41667.58332416637, 41713.0, 29628.0, 31547.0, 31581.210469738442, 33967.81112661045, 33992.83003584966, 20934.639248448802, 20984.580308394052, 24603.630945535475, 25382.873085634452, 25488.470691758554], "unit": "score", "name": "ext.html", "value": 19854.68024703829}, {"lowerIsBetter": false, "replicates": [383.73905744094003, 388.0957369329462, 395.8475986745103, 396.90474286171326, 398.5038626909498, 19008.954507502098, 19859.900700496495, 21505.677414838752, 21867.343979680616, 22006.339809805693, 18072.4578262652, 18389.0, 20660.690089648666, 21994.0, 22229.2219772308, 5886.587938844283, 5906.173135760997, 5962.135490353896, 6231.71957261922, 6236.469900058501, 6053.545984051192, 6112.633242005494, 6114.052321890092, 6119.1739115219325, 6154.846128846792, 5751.309842818868, 6038.637681739087, 6088.178095957033, 6091.543134264925, 6102.328743838174, 5944.346121926585, 5953.761849526014, 5978.880422391528, 5994.670293133876, 5997.370276121032, 4937.728424936627, 5495.395506494282, 5721.227634269362, 5821.097729851837, 5975.581709280352, 13671.384787684578, 14021.228832414214, 14515.766159876423, 25208.0, 25507.0, 2128.0729283419296, 2218.1681869299014, 2245.7754224577575, 2269.137727663494, 2844.99002853988, 10533.578656853717, 13040.673983150402, 14228.786568201485, 15137.924310378377, 15237.771433428508, 1798.9370372037047, 1825.917833697487, 1886.7735871695484, 1886.9433916982512, 1897.2600685732575, 112116.43941780321, 113033.86966130315, 114248.0, 114746.99999999917, 115531.0, 11668.416579171007, 11734.354610496504, 11737.647870563897, 11759.0, 11907.940460297643, 14100.0, 14294.285285735672, 14340.641483962776, 14349.0, 14385.928070359581, 9035.909640903506, 9041.683541076163, 9087.0, 9104.908950910538, 9116.08839116083, 9260.629574817067, 9287.349885507949, 9341.486218258062, 9358.672446464341, 9432.292578056673, 4534.77326133692, 4572.0855828834365, 4584.289435137559, 4620.976895115503, 4651.697639653479, 1597.5127586086123, 1950.5221220800995, 1971.5366888781125, 1975.1111999600237, 2003.459066052187, 970.4225985538593, 992.3797626483448, 996.4320337407609, 1050.5784167493848, 1093.6664317383115, 16140.112293823717, 20604.38186854397, 21181.15275388998, 21448.24931127403, 21890.45273868166, 923.6490133749267, 1028.6091285311684, 1044.2272718188485, 1058.2909450668071, 1066.1097983183972, 9622.951885240669, 10260.538275777608, 11590.478428470738, 11618.94190529042, 11845.289282643069, 672367.0, 674430.0, 698948.0, 699192.0589801931, 702680.0, 124838.75161248453, 127489.0, 129410.35294823465, 132399.0, 136443.31778341046, 1088.9945550272357, 1096.3093251251819, 1103.586155191803, 1108.4070022537903, 1109.4674556212901, 18771.906140469757, 19180.0, 19374.90312548428, 19609.803901961368, 19739.210431582575, 34676.65323346734, 48708.5129148704, 53712.73143634257, 54817.177742334694, 56557.71721141533, 77021.45957080714, 77870.0, 79497.60251198939, 80422.5978870102, 82572.0, 5960.195373624505, 7878.133405325524, 7996.680132794623, 8148.674053037812, 8630.136986301572, 11943.940280298542, 13518.121322114031, 13613.387397566936, 13710.451581936612, 13886.236257005557, 8840.469571825712, 10322.638707645196, 11490.0, 11570.710732231762, 11911.702207444883, 11563.942180289045, 11690.824637630272, 11753.059755219732, 11918.761624767283, 11984.40077996114, 11420.543178272776, 11713.1800773945, 12124.333161676192, 12130.575429859913, 12213.38933053326, 8517.168808706158, 9561.665341713007, 10181.625480560326, 11694.824577631172, 11876.881231187577, 11204.775904482029, 11335.093192544411, 11893.345865977455, 12165.20926139798, 12333.0], "unit": "score", "name": "jquery.html", "value": 9880.87627545854}, {"lowerIsBetter": false, "replicates": [1555.727747644162, 1574.6142195162183, 1700.4473546097522, 1923.9134238959246, 2090.1952748191943, 8456.746297611082, 9149.359544831865, 9293.163615274623, 9466.290028247884, 9514.476703781298, 7756.844863102735, 9188.724338269845, 9219.861702074475, 9281.582328795204, 9308.674196403128, 2540.2633236361435, 2552.706438759548, 2571.3571607098224, 2578.3296342950875, 2580.406506503505, 2519.1560827122935, 2583.0184529878716, 2587.1074479304702, 2601.998230681192, 2608.4522250327454, 2565.3458368116103, 2582.1091723355507, 2611.5690910999756, 2612.020492315382, 2615.973840261592, 2547.312225699051, 2550.846949183055, 2573.4981678572685, 2583.935401614956, 2598.649182360376, 2537.936551586207, 2563.474487730011, 2571.1643715792443, 2600.8699565021675, 2607.79137668986, 9367.812643747155, 9409.153176214173, 9418.717438476857, 9442.622295108187, 9454.90545094547, 5757.65454072757, 5821.476067153976, 5830.4461076197695, 5864.794732184353, 5912.970435147797, 3067.5552044953356, 3157.1949152966076, 3644.4168932970597, 3801.486799282089, 3811.9809400953095, 980.2990861534038, 1018.638383373899, 1103.5254840418618, 1111.2110401614864, 1114.8885111488867, 12460.501579936792, 12568.685782855411, 12600.36998150098, 12612.306323152223, 12625.873741262561, 4992.151334273182, 5073.746312684351, 5122.974385128051, 5138.665986710852, 5162.1224391853475, 10810.189235807346, 10923.508442120125, 10927.617533386294, 10942.890571094187, 10978.615748448767, 5755.050416681201, 5764.308283006066, 5811.854703632443, 5827.679477628772, 5860.941390586039, 4736.455307639597, 5106.770195341219, 5189.299444575009, 5196.714180720097, 5248.9737551312, 4143.56492568276, 4158.979205104016, 4180.331147016463, 4195.58044195578, 4205.915881682349, 1359.6532884114488, 1367.931603419845, 1371.814805001332, 1372.2521225931891, 1377.4490203918513, 1190.5773450425147, 1435.5047508609514, 1448.1455940994863, 1464.2751837840124, 1479.7558402863408, 7341.522801017917, 8582.442141260799, 8677.045524992245, 8687.34844886636, 8720.694775682821, 529.5498825997871, 532.9706866122323, 534.9037173308842, 538.4158188365667, 549.5493695170002, 6333.524985626004, 6436.098946147543, 6452.903206451907, 6464.709088091047, 6499.5775274607, 11223.0, 11229.382383968798, 11345.94327028376, 11370.488328025258, 11566.19036667442, 10803.243772935975, 11106.055985241182, 11222.438878056064, 11313.604023859125, 11346.94326528362, 8747.125287471203, 8861.64553417856, 8879.778005549913, 8968.0, 9002.414843035182, 8405.789855253668, 8476.830463390574, 8541.273991710774, 8577.8713319299, 8578.142185781371, 9840.917499074953, 9949.154321882721, 10033.648822291185, 10036.447995360033, 10039.899601004188, 10070.04334588213, 10442.947785261329, 10560.313579617608, 10647.680569582924, 10673.306235094695, 7104.3961263293195, 7677.808054798675, 7872.212778722082, 8399.580020998803, 8621.180987806149, 8725.0, 8752.64989400417, 8791.384603077979, 8827.955860220658, 8836.779080522781, 7562.508436951581, 7641.961790191013, 7761.573113478813, 7789.104253010976, 7867.0, 7561.168271490022, 7630.2751238632245, 7636.31273185416, 7659.808504787425, 7674.731384401519, 8066.032076150899, 8090.595470226583, 8096.230858068474, 8182.140875208018, 8189.50862948225, 7229.132504099542, 7362.595057271687, 7380.409567234501, 7385.0, 7394.482386232912, 7544.471886968079, 7551.962240188984, 7564.621768911643, 7572.962135189288, 7590.468667193243], "unit": "score", "name": "mootools.html", "value": 5104.110882060975}, {"lowerIsBetter": false, "replicates": [348.2269362016324, 350.94034014217596, 353.1453881606512, 353.854919483012, 355.6763345355726, 6998.790036298907, 7116.430685545157, 7143.107111611055, 7164.319389657987, 7190.0, 6994.8950765738555, 7001.719931202746, 7007.964960175192, 7026.824329391755, 7047.154341479017, 4929.334539837112, 5017.9247311290355, 5114.820981265657, 5121.359830021247, 5209.400918894319, 5056.241563765428, 5080.110980578403, 5090.338256026711, 5093.159628661267, 5135.178371460567, 4991.351124353829, 5033.370828646419, 5043.949560504385, 5045.520675535837, 5054.545090941814, 5044.369453818273, 5070.923936140961, 5078.669886457387, 5094.745262736848, 5096.872578185538, 3578.5347904772475, 4417.160739459525, 4422.071365013351, 4433.179861725559, 4441.689081764278, 6478.708458119396, 6671.432928201109, 6713.664316784141, 6723.092382528345, 6784.525083244175, 5943.435373639497, 5972.492338151263, 5979.983402821487, 5991.430814072656, 6011.098335249748, 7461.291177338144, 7472.813179670497, 7479.775606731807, 7492.962535187344, 7508.361789247921, 4776.80892764289, 4783.832565860178, 4789.161896668069, 4790.065937142259, 4793.089313030548, 7107.751228707022, 7836.960815195887, 7931.881021784678, 7941.36469082472, 7948.483348582324, 3917.471141395932, 4723.409573803275, 4783.019481006385, 4799.400074990626, 4803.78382972767, 4873.8546441586195, 4875.293082503017, 4882.145624515695, 4889.706617602955, 4901.828436004723, 5283.365996080495, 5377.7311134443125, 5927.3776253493625, 5942.584019118706, 5957.880842383128, 6919.446444284546, 7465.589392583352, 7469.551826890404, 7478.700851965969, 7514.6618402172035, 1512.7428337182484, 1657.395050806447, 1712.161041089857, 1715.9656806863793, 1717.785276840395, 979.8677178580942, 1082.5182793656904, 1091.879893211746, 1092.8688557373166, 1094.9835752463719, 7006.0892084029765, 7061.682224299919, 7063.82340441493, 7107.8933815992805, 7133.500654954103, 7766.883496747553, 7777.883331750028, 7787.260210280015, 7787.844243115106, 7820.257075577811, 6985.266547012592, 7054.894176587355, 7057.752978645723, 7067.2579379164445, 7082.185548661867, 3916.3342231820657, 4051.5745846686264, 4071.592840715964, 4090.427340172378, 4100.7334523255895, 7056.858862822714, 7487.625618719042, 8083.434159608771, 8148.185181481923, 8194.22154895284, 7938.682452701827, 7979.202079791974, 7994.720184793504, 8006.119326874039, 8019.799505012421, 8076.3942704297415, 8077.596120193967, 8089.555074470843, 8099.339635374734, 8102.918970810217, 7453.925460745323, 7729.574873382018, 7731.304182623591, 7774.222577742181, 7798.376129909707, 6210.906836397367, 7509.3617042552, 7515.887261690969, 7565.810854728676, 7594.658240379085, 8106.9189308108525, 8110.878336825071, 8123.228293312126, 8158.83682326362, 8190.385721070825, 4881.193958235333, 4883.096627123993, 4883.780229889592, 4896.192128298933, 4897.069556784199, 7423.109226892808, 7573.810654733676, 7737.3423259021365, 7854.960725196566, 7867.803304917423, 7503.062117235346, 7561.470697051153, 7575.128860181149, 7612.505187162817, 7749.651265692944, 7529.510581812165, 7539.095308563007, 7544.622768861425, 7563.810904727426, 7573.772786816405, 7035.1909530404655, 7291.125064992235, 7293.671784769592, 7304.890426643711, 7377.483576149618, 7507.849843003, 7539.886901696588, 7575.734849280248, 7576.24237576238, 7584.241575842371, 7377.852442951218, 7394.963025184839, 7400.703971841066, 7409.888851667121, 7415.480916335805], "unit": "score", "name": "prototype.html", "value": 5434.511146514924}, {"lowerIsBetter": false, "replicates": [717.6842189436649, 772.1622040086506, 779.2558107007804, 791.2917938445088, 791.5962858941938, 15245.31396087176, 15490.380384784596, 15698.0, 15705.0, 15716.842831571681, 14509.984301098928, 14534.927325363358, 14561.92719036406, 14567.490137845178, 14600.488982885601, 795.6101510259989, 958.5399008475936, 973.6397532912832, 999.3104757717189, 1003.7289931718432, 977.9657711980082, 984.1585444445004, 984.7833476635129, 985.8767654043245, 986.6004268271325, 1036.0364860679604, 1049.0244073012095, 1054.19880890523, 1058.2750815691277, 1065.0, 1014.5586669798661, 1037.3205550364523, 1049.0244073012057, 1051.332403923514, 1054.5360041581719, 782.5695867273034, 783.4515838912783, 790.7034861926777, 794.789380814079, 800.6237068577759, 13495.730085398336, 15335.23323833815, 15430.459933902372, 15479.380824767108, 15534.61163470911, 13161.473541058345, 13184.934075329658, 13216.405261763244, 13341.0, 13382.0, 8309.37679674027, 10273.794524109477, 10337.483125843753, 10392.324498907547, 10486.790264194673, 3538.097785064818, 3571.089372210096, 3628.0, 3669.1377526281303, 3835.347990841564, 76255.47489050188, 76560.61719691366, 76836.0, 76847.0, 76864.0, 9497.857532137023, 9815.852762208573, 9860.211183105333, 9936.950315248378, 10100.393976361294, 23679.644805327935, 23904.40238994039, 23937.52124957491, 23970.40073998164, 24041.639375409384, 7736.149023607399, 7940.325072368913, 7987.800304992305, 8021.358291336679, 8112.959435202904, 3946.6645335146836, 3956.624120708528, 4143.19207754491, 4315.546867578859, 4329.09089091288, 3719.0, 3748.5314335708035, 3751.324761542894, 3770.321342158383, 3777.395616701315, 696.1298377028714, 698.2319448606594, 700.8948657701284, 702.301210295759, 702.6908160409404, 563.1355868741463, 584.7139078379535, 612.6752821004875, 614.8585825260216, 617.2253821454046, 8200.507969521848, 8439.451435656663, 8484.0, 8608.913910860936, 8870.024297327289, 549.4532939725, 565.2312854517852, 580.1269090019493, 580.7996241296746, 585.4233579923772, 3311.1729795603014, 3928.0, 3941.7608326147474, 3967.8809635710977, 4035.7982100894837, 93542.0, 95185.5240723792, 95567.52216238875, 95598.0, 97092.0, 82234.3553128934, 83096.58451707703, 83113.58443207866, 83346.0, 84618.15381846103, 67974.0, 69503.0, 70231.0, 71200.0, 71530.64234678794, 8604.827903442021, 8611.655533778829, 8707.738767836721, 8749.91250087491, 8755.12448755145, 49167.50832491629, 50721.239181413046, 51400.228996564336, 51883.96232075262, 52466.737666312954, 68931.3106868925, 69304.0, 70581.29418705747, 70864.0, 75336.62331688307, 11413.315201087962, 11516.0, 11585.478653460783, 11635.011024062711, 11726.472308745957, 12045.09661775388, 12048.156629036233, 12110.75778484443, 12238.265704057429, 12710.618681439571, 11711.063114950613, 11721.706957325794, 11810.0, 11879.406029698308, 12431.440585173508, 11679.76640467203, 11703.941480292544, 11735.589254376056, 12187.695307617381, 12272.570460033856, 11374.886251137383, 11473.196876218584, 11480.483378248166, 11792.174547781573, 11809.291442513477, 9615.070021068286, 9733.270004749531, 9830.606775728891, 9933.801323973625, 10222.795544089226, 9058.592363343798, 9749.927507973975, 9843.36018158846, 10021.39871607706, 10122.89877101249], "unit": "score", "name": "yui.html", "value": 7193.888570106871}], "name": "dromaeo_css", "value": 7827.554217528509}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 112.0, 168.0, 118.0, 118.0, 128.0, 115.0, 115.0, 116.0, 116.0], "unit": "score", "name": "ai-astar", "value": 122.1}, {"lowerIsBetter": true, "replicates": [154.0, 119.0, 149.0, 149.0, 126.0, 173.0, 156.0, 185.0, 148.0, 116.0], "unit": "score", "name": "audio-beat-detection", "value": 147.5}, {"lowerIsBetter": true, "replicates": [197.0, 198.0, 200.0, 203.0, 193.0, 195.0, 196.0, 192.0, 204.0, 199.0], "unit": "score", "name": "audio-dft", "value": 197.7}, {"lowerIsBetter": true, "replicates": [62.0, 67.0, 77.0, 110.0, 60.0, 73.0, 85.0, 86.0, 162.0, 58.0], "unit": "score", "name": "audio-fft", "value": 84.0}, {"lowerIsBetter": true, "replicates": [99.0, 80.0, 114.0, 105.0, 121.0, 120.0, 162.0, 99.0, 79.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 109.6}, {"lowerIsBetter": true, "replicates": [111.0, 129.0, 112.0, 85.0, 87.0, 86.0, 81.0, 132.0, 135.0, 88.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 104.6}, {"lowerIsBetter": true, "replicates": [138.0, 95.0, 97.0, 135.0, 94.0, 95.0, 96.0, 95.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 103.4}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 91.0, 89.0, 134.0, 134.0, 82.0, 144.0, 140.0, 128.0], "unit": "score", "name": "imaging-desaturate", "value": 112.7}, {"lowerIsBetter": true, "replicates": [56.0, 60.0, 57.0, 58.0, 59.0, 63.0, 66.0, 83.0, 90.0, 85.0], "unit": "score", "name": "json-parse-financial", "value": 67.7}, {"lowerIsBetter": true, "replicates": [60.0, 77.0, 100.0, 96.0, 65.0, 95.0, 103.0, 97.0, 102.0, 80.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 87.5}, {"lowerIsBetter": true, "replicates": [82.0, 83.0, 96.0, 109.0, 86.0, 83.0, 103.0, 110.0, 77.0, 101.0], "unit": "score", "name": "stanford-crypto-aes", "value": 93.0}, {"lowerIsBetter": true, "replicates": [143.0, 138.0, 119.0, 119.0, 120.0, 123.0, 117.0, 120.0, 126.0, 120.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.5}, {"lowerIsBetter": true, "replicates": [133.0, 140.0, 130.0, 153.0, 130.0, 150.0, 181.0, 176.0, 136.0, 130.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.9}, {"lowerIsBetter": true, "replicates": [63.0, 69.0, 64.0, 65.0, 65.0, 67.0, 68.0, 69.0, 63.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.1}], "name": "kraken", "value": 1566.3}]} 19:52:13 INFO - Return code: 0 19:52:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:52:13 ERROR - # TBPL SUCCESS # 19:52:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:52:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:52:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:52:13 INFO - 2016-05-18 19:52:13.920 screenresolution[1684:10175] starting screenresolution argv=screenresolution get 19:52:13 INFO - 2016-05-18 19:52:13.923 screenresolution[1684:10175] Display 0: 1600x1200x32@60 19:52:13 INFO - 2016-05-18 19:52:13.929 screenresolution[1685:10178] starting screenresolution argv=screenresolution list 19:52:13 INFO - Available Modes on Display 0 19:52:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:52:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:52:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:52:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:52:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:52:14 INFO - Intel Iris: 19:52:14 INFO - Chipset Model: Intel Iris 19:52:14 INFO - Type: GPU 19:52:14 INFO - Bus: Built-In 19:52:14 INFO - VRAM (Dynamic, Max): 1536 MB 19:52:14 INFO - Vendor: Intel (0x8086) 19:52:14 INFO - Device ID: 0x0a2e 19:52:14 INFO - Revision ID: 0x0009 19:52:14 INFO - Displays: 19:52:14 INFO - Display: 19:52:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:52:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:52:14 INFO - Display Serial Number: 1600x1200 60 19:52:14 INFO - Main Display: Yes 19:52:14 INFO - Mirror: Off 19:52:14 INFO - Online: Yes 19:52:14 INFO - Rotation: Supported 19:52:14 INFO - Return code: 0 19:52:14 INFO - Running post-action listener: _resource_record_post_action 19:52:14 INFO - Running post-run listener: _resource_record_post_run 19:52:15 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 36238848; Write bytes: 509498880; Read time: 244; Write time: 1581 19:52:15 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 201349632; Write bytes: 195260416; Read time: 17965; Write time: 417 19:52:15 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 30064128; Write bytes: 303273472; Read time: 185; Write time: 1124 19:52:15 INFO - Running post-run listener: _upload_blobber_files 19:52:15 INFO - Blob upload gear active. 19:52:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:52:15 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 19:52:15 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'] 19:52:15 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 19:52:15 INFO - (blobuploader) - INFO - Open directory for files ... 19:52:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:52:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:52:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:52:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:52:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:52:17 INFO - (blobuploader) - INFO - Done attempting. 19:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:52:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:52:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:52:18 INFO - (blobuploader) - INFO - Done attempting. 19:52:18 INFO - (blobuploader) - INFO - Iteration through files over. 19:52:18 INFO - Return code: 0 19:52:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:52:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:52:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3db7cd39a7e1f1f1233b45105e69faef65951cb5329fa4578840f5901150791c48a9abdcafc2fc5b26e0a7076c4e194449fb2a820bc202d72c8a3da82611b1c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf3a4c83c646fd7138c55c78431111a6f3668d91e558f1784f33e34155bdd143360c5ea2282763d64489401570ba9b1a475c1a9ef637703c9dffed14bf0fe3e"} 19:52:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:52:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:52:18 INFO - Contents: 19:52:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3db7cd39a7e1f1f1233b45105e69faef65951cb5329fa4578840f5901150791c48a9abdcafc2fc5b26e0a7076c4e194449fb2a820bc202d72c8a3da82611b1c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf3a4c83c646fd7138c55c78431111a6f3668d91e558f1784f33e34155bdd143360c5ea2282763d64489401570ba9b1a475c1a9ef637703c9dffed14bf0fe3e"} 19:52:18 INFO - Running post-run listener: copy_logs_to_upload_dir 19:52:18 INFO - Copying logs to upload dir... 19:52:18 INFO - mkdir: /builds/slave/test/build/upload/logs 19:52:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.545999 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-18 19:52:18.826734) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 19:52:18.829785) ========= 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.uHODASzwd7/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.eVFVNT9VHY/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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/3db7cd39a7e1f1f1233b45105e69faef65951cb5329fa4578840f5901150791c48a9abdcafc2fc5b26e0a7076c4e194449fb2a820bc202d72c8a3da82611b1c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf3a4c83c646fd7138c55c78431111a6f3668d91e558f1784f33e34155bdd143360c5ea2282763d64489401570ba9b1a475c1a9ef637703c9dffed14bf0fe3e"} build_url:https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/LFDtppM0SViC8ScxpvdrsA/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3db7cd39a7e1f1f1233b45105e69faef65951cb5329fa4578840f5901150791c48a9abdcafc2fc5b26e0a7076c4e194449fb2a820bc202d72c8a3da82611b1c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ebf3a4c83c646fd7138c55c78431111a6f3668d91e558f1784f33e34155bdd143360c5ea2282763d64489401570ba9b1a475c1a9ef637703c9dffed14bf0fe3e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 19:52:18.858381) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:52:18.858750) ========= 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.uHODASzwd7/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.eVFVNT9VHY/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005153 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:52:18.907356) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 19:52:18.907691) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 19:52:18.908046) ========= ========= Total master_lag: 3.08 =========