builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0176 starttime: 1472694790.27 results: success (0) buildid: 20160831164321 builduid: 13a41e3711a548a986a521e4a3d9a5d6 revision: 04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.274004) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.274583) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.275060) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.335399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.335904) ========= 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.8zZGPJls3C/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.6NhPxmW3zo/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-08-31 18:53:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 136M=0s 2016-08-31 18:53:10 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097649 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.458084) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.458467) ========= 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.8zZGPJls3C/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.6NhPxmW3zo/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.027821 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.508656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:10.509063) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8zZGPJls3C/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.6NhPxmW3zo/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-08-31 18:53:10,596 truncating revision to first 12 chars 2016-08-31 18:53:10,597 Setting DEBUG logging. 2016-08-31 18:53:10,597 attempt 1/10 2016-08-31 18:53:10,597 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/04d9b7160ac9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 18:53:11,294 unpacking tar archive at: autoland-04d9b7160ac9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.957043 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:11.492697) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:11.493525) ========= script_repo_revision: 04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:11.494503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:11.495129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 18:53:11.515923) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-31 18:53:11.516967) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8zZGPJls3C/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.6NhPxmW3zo/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:11 INFO - MultiFileLogger online at 20160831 18:53:11 in /builds/slave/test 18:53:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:53:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:53:11 INFO - {'append_to_log': False, 18:53:11 INFO - 'base_work_dir': '/builds/slave/test', 18:53:11 INFO - 'blob_upload_branch': 'Autoland', 18:53:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:53:11 INFO - 'branch': 'Autoland', 18:53:11 INFO - 'buildbot_json_path': 'buildprops.json', 18:53:11 INFO - 'config_files': ('talos/mac_config.py',), 18:53:11 INFO - 'default_actions': ('clobber', 18:53:11 INFO - 'read-buildbot-config', 18:53:11 INFO - 'download-and-extract', 18:53:11 INFO - 'populate-webroot', 18:53:11 INFO - 'create-virtualenv', 18:53:11 INFO - 'install', 18:53:11 INFO - 'run-tests'), 18:53:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:53:11 INFO - 'download_minidump_stackwalk': True, 18:53:11 INFO - 'download_symbols': 'ondemand', 18:53:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:53:11 INFO - 'tooltool.py': '/tools/tooltool.py', 18:53:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:53:11 INFO - '/tools/misc-python/virtualenv.py')}, 18:53:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:53:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:53:11 INFO - 'installer_path': 'installer.exe', 18:53:11 INFO - 'log_level': 'info', 18:53:11 INFO - 'log_name': 'talos', 18:53:11 INFO - 'log_to_console': True, 18:53:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:53:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:53:11 INFO - 'opt_config_files': (), 18:53:11 INFO - 'pip_index': False, 18:53:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:53:11 INFO - 'cmd': ('bash', 18:53:11 INFO - '-c', 18:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:53:11 INFO - 'enabled': True, 18:53:11 INFO - 'halt_on_failure': False, 18:53:11 INFO - 'name': 'check_screen_resolution'},), 18:53:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:53:11 INFO - 'cmd': ('bash', 18:53:11 INFO - '-c', 18:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:53:11 INFO - 'enabled': True, 18:53:11 INFO - 'halt_on_failure': False, 18:53:11 INFO - 'name': 'check_screen_resolution'},), 18:53:11 INFO - 'run_cmd_checks_enabled': True, 18:53:11 INFO - 'sps_profile': False, 18:53:11 INFO - 'sps_profile_interval': 0, 18:53:11 INFO - 'suite': 'dromaeojs-e10s', 18:53:11 INFO - 'system_bits': '32', 18:53:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:53:11 INFO - 'title': 't-yosemite-r7-0176', 18:53:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:53:11 INFO - 'use_talos_json': True, 18:53:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:53:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:53:11 INFO - 'work_dir': 'build'} 18:53:11 INFO - [mozharness: 2016-09-01 01:53:11.747603Z] Running clobber step. 18:53:11 INFO - Running pre-action listener: _resource_record_pre_action 18:53:11 INFO - Running main action method: clobber 18:53:11 INFO - rmtree: /builds/slave/test/build 18:53:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:53:14 INFO - Running post-action listener: _resource_record_post_action 18:53:14 INFO - [mozharness: 2016-09-01 01:53:14.032018Z] Finished clobber step (success) 18:53:14 INFO - [mozharness: 2016-09-01 01:53:14.032238Z] Running read-buildbot-config step. 18:53:14 INFO - Running pre-action listener: _resource_record_pre_action 18:53:14 INFO - Running main action method: read_buildbot_config 18:53:14 INFO - Using buildbot properties: 18:53:14 INFO - { 18:53:14 INFO - "project": "", 18:53:14 INFO - "product": "firefox", 18:53:14 INFO - "script_repo_revision": "production", 18:53:14 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:53:14 INFO - "repository": "", 18:53:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:53:14 INFO - "buildid": "20160831164321", 18:53:14 INFO - "pgo_build": "False", 18:53:14 INFO - "basedir": "/builds/slave/test", 18:53:14 INFO - "buildnumber": 175, 18:53:14 INFO - "slavename": "t-yosemite-r7-0176", 18:53:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:53:14 INFO - "platform": "yosemite_r7", 18:53:14 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 18:53:14 INFO - "branch": "autoland", 18:53:14 INFO - "repo_path": "integration/autoland", 18:53:14 INFO - "revision": "04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac", 18:53:14 INFO - "stage_platform": "macosx64", 18:53:14 INFO - "builduid": "13a41e3711a548a986a521e4a3d9a5d6", 18:53:14 INFO - "slavebuilddir": "test" 18:53:14 INFO - } 18:53:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:53:14 INFO - Running post-action listener: _resource_record_post_action 18:53:14 INFO - [mozharness: 2016-09-01 01:53:14.038008Z] Finished read-buildbot-config step (success) 18:53:14 INFO - [mozharness: 2016-09-01 01:53:14.038162Z] Running download-and-extract step. 18:53:14 INFO - Running pre-action listener: _resource_record_pre_action 18:53:14 INFO - Running main action method: download_and_extract 18:53:14 INFO - mkdir: /builds/slave/test/build/tests 18:53:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:14 INFO - trying https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:53:14 INFO - Downloading https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:53:14 INFO - Downloaded 1199 bytes. 18:53:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:53:14 INFO - Using the following test package requirements: 18:53:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:53:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:53:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:53:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'jsshell-mac.zip'], 18:53:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:53:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:53:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:53:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:53:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:53:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:53:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:53:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:53:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:14 INFO - trying https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:53:14 INFO - Downloading https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:53:15 INFO - Downloaded 16394698 bytes. 18:53:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:53:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:22 INFO - trying https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:53:22 INFO - Downloading https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:53:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:53:23 INFO - Downloaded 16394698 bytes. 18:53:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:30 INFO - trying https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:53:30 INFO - Downloading https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:53:31 INFO - Downloaded 14413998 bytes. 18:53:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:31 INFO - trying https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:53:31 INFO - Downloading https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:53:34 INFO - Downloaded 111573511 bytes. 18:53:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:53:34 INFO - mkdir: /builds/slave/test/properties 18:53:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:53:34 INFO - Writing to file /builds/slave/test/properties/build_url 18:53:34 INFO - Contents: 18:53:34 INFO - build_url:https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:53:35 INFO - Running post-action listener: _resource_record_post_action 18:53:35 INFO - Running post-action listener: set_extra_try_arguments 18:53:35 INFO - [mozharness: 2016-09-01 01:53:35.773777Z] Finished download-and-extract step (success) 18:53:35 INFO - [mozharness: 2016-09-01 01:53:35.773904Z] Running populate-webroot step. 18:53:35 INFO - Running pre-action listener: _resource_record_pre_action 18:53:35 INFO - Running main action method: populate_webroot 18:53:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:53:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:53:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:53:35 INFO - '--tppagecycles', 18:53:35 INFO - '7'], 18:53:35 INFO - 'tests': ['tsvgm']}}, 18:53:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:53:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:53:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:53:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:53:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:53:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:53:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['damp', 'tps']}, 18:53:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'tests': ['damp', 'tps']}, 18:53:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['dromaeo_dom']}, 18:53:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:53:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['basic_compositor_video']}, 18:53:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:53:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['a11yr', 18:53:35 INFO - 'ts_paint', 18:53:35 INFO - 'tpaint', 18:53:35 INFO - 'sessionrestore', 18:53:35 INFO - 'sessionrestore_no_auto_restore', 18:53:35 INFO - 'tabpaint']}, 18:53:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:53:35 INFO - 'ts_paint', 18:53:35 INFO - 'tpaint', 18:53:35 INFO - 'sessionrestore', 18:53:35 INFO - 'sessionrestore_no_auto_restore', 18:53:35 INFO - 'tabpaint']}, 18:53:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:53:35 INFO - 'ts_paint', 18:53:35 INFO - 'tpaint', 18:53:35 INFO - 'sessionrestore', 18:53:35 INFO - 'sessionrestore_no_auto_restore', 18:53:35 INFO - 'tabpaint']}, 18:53:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:53:35 INFO - 'ts_paint', 18:53:35 INFO - 'tpaint', 18:53:35 INFO - 'sessionrestore', 18:53:35 INFO - 'sessionrestore_no_auto_restore', 18:53:35 INFO - 'tabpaint']}, 18:53:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['a11yr', 18:53:35 INFO - 'ts_paint', 18:53:35 INFO - 'tpaint', 18:53:35 INFO - 'sessionrestore', 18:53:35 INFO - 'sessionrestore_no_auto_restore', 18:53:35 INFO - 'tabpaint']}, 18:53:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['a11yr', 18:53:35 INFO - 'ts_paint', 18:53:35 INFO - 'tpaint', 18:53:35 INFO - 'sessionrestore', 18:53:35 INFO - 'sessionrestore_no_auto_restore', 18:53:35 INFO - 'tabpaint']}, 18:53:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['tsvgx', 18:53:35 INFO - 'tsvgr_opacity', 18:53:35 INFO - 'tart', 18:53:35 INFO - 'tscrollx', 18:53:35 INFO - 'cart']}, 18:53:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:53:35 INFO - 'tsvgr_opacity', 18:53:35 INFO - 'tart', 18:53:35 INFO - 'tscrollx', 18:53:35 INFO - 'cart']}, 18:53:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'talos_options': ['--disable-e10s'], 18:53:35 INFO - 'tests': ['tp5o']}, 18:53:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'tests': ['tp5o']}, 18:53:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'talos_options': ['--disable-e10s', 18:53:35 INFO - '--xperf_path', 18:53:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:53:35 INFO - 'tests': ['tp5n']}, 18:53:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:35 INFO - 'talos_options': ['--xperf_path', 18:53:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:53:35 INFO - 'tests': ['tp5n']}}, 18:53:35 INFO - 'talos.zip': {'path': '', 18:53:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:53:35 INFO - Running post-action listener: _resource_record_post_action 18:53:35 INFO - [mozharness: 2016-09-01 01:53:35.793122Z] Finished populate-webroot step (success) 18:53:35 INFO - [mozharness: 2016-09-01 01:53:35.793252Z] Running create-virtualenv step. 18:53:35 INFO - Running pre-action listener: _resource_record_pre_action 18:53:35 INFO - Running main action method: create_virtualenv 18:53:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:53:35 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 18:53:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:53:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:53:35 INFO - Using real prefix '/tools/python27' 18:53:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:53:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:53:39 INFO - Installing pip.................done. 18:53:39 INFO - Return code: 0 18:53:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:39 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 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:39 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 18:53:39 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 18:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:39 INFO - 'HOME': '/Users/cltbld', 18:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:39 INFO - 'LOGNAME': 'cltbld', 18:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:39 INFO - 'MOZ_NO_REMOTE': '1', 18:53:39 INFO - 'NO_EM_RESTART': '1', 18:53:39 INFO - 'PAGER': '/bin/cat', 18:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:39 INFO - 'PWD': '/builds/slave/test', 18:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:39 INFO - 'SHELL': '/bin/bash', 18:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:39 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:39 INFO - 'USER': 'cltbld', 18:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:39 INFO - 'XPC_FLAGS': '0x0', 18:53:39 INFO - 'XPC_SERVICE_NAME': '0', 18:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:39 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 18:53:39 INFO - Cleaning up... 18:53:39 INFO - Return code: 0 18:53:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:39 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:39 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>=3.1.1'] in /builds/slave/test/build 18:53:39 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>=3.1.1 18:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:39 INFO - 'HOME': '/Users/cltbld', 18:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:39 INFO - 'LOGNAME': 'cltbld', 18:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:39 INFO - 'MOZ_NO_REMOTE': '1', 18:53:39 INFO - 'NO_EM_RESTART': '1', 18:53:39 INFO - 'PAGER': '/bin/cat', 18:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:39 INFO - 'PWD': '/builds/slave/test', 18:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:39 INFO - 'SHELL': '/bin/bash', 18:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:39 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:39 INFO - 'USER': 'cltbld', 18:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:39 INFO - 'XPC_FLAGS': '0x0', 18:53:39 INFO - 'XPC_SERVICE_NAME': '0', 18:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:40 INFO - Downloading/unpacking psutil>=3.1.1 18:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:53:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:53:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:53:43 INFO - Installing collected packages: psutil 18:53:43 INFO - Running setup.py install for psutil 18:53:43 INFO - building 'psutil._psutil_osx' extension 18:53:43 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 18:53:44 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 18:53:44 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 18:53:44 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 18:53:44 INFO - building 'psutil._psutil_posix' extension 18:53:44 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 18:53:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:53:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:53:44 INFO - ^ 18:53:44 INFO - 1 warning generated. 18:53:44 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 18:53:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:53:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:53:44 INFO - Successfully installed psutil 18:53:44 INFO - Cleaning up... 18:53:44 INFO - Return code: 0 18:53:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:53:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:44 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:44 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.3'] in /builds/slave/test/build 18:53:44 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.3 18:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:44 INFO - 'HOME': '/Users/cltbld', 18:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:44 INFO - 'LOGNAME': 'cltbld', 18:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:44 INFO - 'MOZ_NO_REMOTE': '1', 18:53:44 INFO - 'NO_EM_RESTART': '1', 18:53:44 INFO - 'PAGER': '/bin/cat', 18:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:44 INFO - 'PWD': '/builds/slave/test', 18:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:44 INFO - 'SHELL': '/bin/bash', 18:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:44 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:44 INFO - 'USER': 'cltbld', 18:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:44 INFO - 'XPC_FLAGS': '0x0', 18:53:44 INFO - 'XPC_SERVICE_NAME': '0', 18:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:53:47 INFO - Installing collected packages: mozsystemmonitor 18:53:47 INFO - Running setup.py install for mozsystemmonitor 18:53:47 INFO - Successfully installed mozsystemmonitor 18:53:47 INFO - Cleaning up... 18:53:47 INFO - Return code: 0 18:53:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:47 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:47 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==2.5.1'] in /builds/slave/test/build 18:53:47 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==2.5.1 18:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:47 INFO - 'HOME': '/Users/cltbld', 18:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:47 INFO - 'LOGNAME': 'cltbld', 18:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:47 INFO - 'MOZ_NO_REMOTE': '1', 18:53:47 INFO - 'NO_EM_RESTART': '1', 18:53:47 INFO - 'PAGER': '/bin/cat', 18:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:47 INFO - 'PWD': '/builds/slave/test', 18:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:47 INFO - 'SHELL': '/bin/bash', 18:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:47 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:47 INFO - 'USER': 'cltbld', 18:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:47 INFO - 'XPC_FLAGS': '0x0', 18:53:47 INFO - 'XPC_SERVICE_NAME': '0', 18:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:53:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:50 INFO - Downloading functools32-3.2.3-2.tar.gz 18:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:53:50 INFO - warning: no files found matching '*.txt' 18:53:50 INFO - no previously-included directories found matching 'build' 18:53:50 INFO - no previously-included directories found matching 'dist' 18:53:50 INFO - no previously-included directories found matching '.git*' 18:53:51 INFO - Installing collected packages: jsonschema, functools32 18:53:51 INFO - Running setup.py install for functools32 18:53:51 INFO - warning: no files found matching '*.txt' 18:53:51 INFO - no previously-included directories found matching 'build' 18:53:51 INFO - no previously-included directories found matching 'dist' 18:53:51 INFO - no previously-included directories found matching '.git*' 18:53:51 INFO - Successfully installed jsonschema functools32 18:53:51 INFO - Cleaning up... 18:53:51 INFO - Return code: 0 18:53:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:51 INFO - 'HOME': '/Users/cltbld', 18:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:51 INFO - 'LOGNAME': 'cltbld', 18:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:51 INFO - 'MOZ_NO_REMOTE': '1', 18:53:51 INFO - 'NO_EM_RESTART': '1', 18:53:51 INFO - 'PAGER': '/bin/cat', 18:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:51 INFO - 'PWD': '/builds/slave/test', 18:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:51 INFO - 'SHELL': '/bin/bash', 18:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:51 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:51 INFO - 'USER': 'cltbld', 18:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:51 INFO - 'XPC_FLAGS': '0x0', 18:53:51 INFO - 'XPC_SERVICE_NAME': '0', 18:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:53:51 INFO - Cleaning up... 18:53:51 INFO - Return code: 0 18:53:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:51 INFO - 'HOME': '/Users/cltbld', 18:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:51 INFO - 'LOGNAME': 'cltbld', 18:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:51 INFO - 'MOZ_NO_REMOTE': '1', 18:53:51 INFO - 'NO_EM_RESTART': '1', 18:53:51 INFO - 'PAGER': '/bin/cat', 18:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:51 INFO - 'PWD': '/builds/slave/test', 18:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:51 INFO - 'SHELL': '/bin/bash', 18:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:51 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:51 INFO - 'USER': 'cltbld', 18:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:51 INFO - 'XPC_FLAGS': '0x0', 18:53:51 INFO - 'XPC_SERVICE_NAME': '0', 18:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:51 INFO - Downloading/unpacking blobuploader==1.2.4 18:53:51 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 18:53:51 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 18:53:51 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 18:53:51 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 18:53:51 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 18:53:51 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 18:53:54 INFO - Downloading blobuploader-1.2.4.tar.gz 18:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:53:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:53: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 18:53: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 18:53: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 18:53: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 18:53: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 18:53: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 18:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:53:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:53: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 18:53: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 18:53: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 18:53: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 18:53: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 18:53: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 18:53:55 INFO - Downloading docopt-0.6.1.tar.gz 18:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:53:55 INFO - Installing collected packages: blobuploader, requests, docopt 18:53:55 INFO - Running setup.py install for blobuploader 18:53:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:53:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:53:55 INFO - Running setup.py install for requests 18:53:56 INFO - Running setup.py install for docopt 18:53:56 INFO - Successfully installed blobuploader requests docopt 18:53:56 INFO - Cleaning up... 18:53:56 INFO - Return code: 0 18:53:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:56 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 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:53:56 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 18:53:56 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 18:53:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:53:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:56 INFO - 'HOME': '/Users/cltbld', 18:53:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:56 INFO - 'LOGNAME': 'cltbld', 18:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:56 INFO - 'MOZ_NO_REMOTE': '1', 18:53:56 INFO - 'NO_EM_RESTART': '1', 18:53:56 INFO - 'PAGER': '/bin/cat', 18:53:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:56 INFO - 'PWD': '/builds/slave/test', 18:53:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:56 INFO - 'SHELL': '/bin/bash', 18:53:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:53:56 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:53:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:56 INFO - 'USER': 'cltbld', 18:53:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:56 INFO - 'XPC_FLAGS': '0x0', 18:53:56 INFO - 'XPC_SERVICE_NAME': '0', 18:53:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:53:56 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-MPXHpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:53:56 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-xZFQQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:53:56 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-C_hPtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:53:56 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-vLEb5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-I2721y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-nkjaZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-EvctiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-_0giiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-4efAuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Qu5ZrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-gzpcn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-HXvs2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:53:57 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-aVWpiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:53:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-AKUVZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-7vYQoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:53:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-X_5xIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:53:58 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-fmB_oH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:53:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:53:58 INFO - Running setup.py install for manifestparser 18:53:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:53:58 INFO - Running setup.py install for mozcrash 18:53:58 INFO - Running setup.py install for mozdebug 18:53:58 INFO - Running setup.py install for mozdevice 18:53:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Running setup.py install for mozfile 18:53:59 INFO - Running setup.py install for mozhttpd 18:53:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Running setup.py install for mozinfo 18:53:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Running setup.py install for mozInstall 18:53:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Running setup.py install for mozleak 18:53:59 INFO - Running setup.py install for mozlog 18:53:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:53:59 INFO - Running setup.py install for moznetwork 18:54:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:54:00 INFO - Running setup.py install for mozprocess 18:54:00 INFO - Running setup.py install for mozprofile 18:54:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:54:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:54:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:54:00 INFO - Running setup.py install for mozrunner 18:54:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:54:00 INFO - Running setup.py install for mozscreenshot 18:54:00 INFO - Running setup.py install for moztest 18:54:00 INFO - Running setup.py install for mozversion 18:54:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:54:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:54:00 INFO - Cleaning up... 18:54:00 INFO - Return code: 0 18:54:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:54:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:00 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 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:54:00 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 18:54:00 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 18:54:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:54:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:00 INFO - 'HOME': '/Users/cltbld', 18:54:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:00 INFO - 'LOGNAME': 'cltbld', 18:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:00 INFO - 'MOZ_NO_REMOTE': '1', 18:54:00 INFO - 'NO_EM_RESTART': '1', 18:54:00 INFO - 'PAGER': '/bin/cat', 18:54:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:00 INFO - 'PWD': '/builds/slave/test', 18:54:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:00 INFO - 'SHELL': '/bin/bash', 18:54:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:54:00 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:54:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:00 INFO - 'USER': 'cltbld', 18:54:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:00 INFO - 'XPC_FLAGS': '0x0', 18:54:00 INFO - 'XPC_SERVICE_NAME': '0', 18:54:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-YP7KN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:54:01 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)) 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-n7YHfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:54:01 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)) 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-yGeWke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:54:01 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)) 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PN0I0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:54:01 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)) 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JkOd_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:54:01 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)) 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-dcMnlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:54:01 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)) 18:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:54:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ilYwM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:54:01 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5Q_nCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:54:02 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-mn8QpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:54:02 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-OD82I_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-SF469N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:54:02 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-CX54l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-zNtFuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:54:02 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-DOA86O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-AIDV6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:54:02 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-i8Oe9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:54:02 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)) 18:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:54:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-y4Snop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:54:03 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)) 18:54:03 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)) 18:54:03 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)) 18:54:03 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)) 18:54:03 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)) 18:54:03 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)) 18:54:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:54:03 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 18:54:03 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 18:54:03 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 18:54:03 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 18:54:03 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 18:54:03 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 18:54:05 INFO - Downloading blessings-1.6.tar.gz 18:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:54:05 INFO - Installing collected packages: blessings 18:54:05 INFO - Running setup.py install for blessings 18:54:05 INFO - Successfully installed blessings 18:54:05 INFO - Cleaning up... 18:54:05 INFO - Return code: 0 18:54:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:54:06 INFO - Reading from file tmpfile_stdout 18:54:06 INFO - Current package versions: 18:54:06 INFO - blessings == 1.6 18:54:06 INFO - blobuploader == 1.2.4 18:54:06 INFO - docopt == 0.6.1 18:54:06 INFO - functools32 == 3.2.3-2 18:54:06 INFO - jsonschema == 2.5.1 18:54:06 INFO - manifestparser == 1.1 18:54:06 INFO - mozInstall == 1.12 18:54:06 INFO - mozcrash == 0.17 18:54:06 INFO - mozdebug == 0.1 18:54:06 INFO - mozdevice == 0.48 18:54:06 INFO - mozfile == 1.2 18:54:06 INFO - mozhttpd == 0.7 18:54:06 INFO - mozinfo == 0.9 18:54:06 INFO - mozleak == 0.1 18:54:06 INFO - mozlog == 3.3 18:54:06 INFO - moznetwork == 0.27 18:54:06 INFO - mozprocess == 0.23 18:54:06 INFO - mozprofile == 0.28 18:54:06 INFO - mozrunner == 6.12 18:54:06 INFO - mozscreenshot == 0.1 18:54:06 INFO - mozsystemmonitor == 0.3 18:54:06 INFO - moztest == 0.7 18:54:06 INFO - mozversion == 1.4 18:54:06 INFO - psutil == 3.1.1 18:54:06 INFO - requests == 1.2.3 18:54:06 INFO - wsgiref == 0.1.2 18:54:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:06 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 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:54:06 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 18:54:06 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 18:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:06 INFO - 'HOME': '/Users/cltbld', 18:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:06 INFO - 'LOGNAME': 'cltbld', 18:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:06 INFO - 'MOZ_NO_REMOTE': '1', 18:54:06 INFO - 'NO_EM_RESTART': '1', 18:54:06 INFO - 'PAGER': '/bin/cat', 18:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:06 INFO - 'PWD': '/builds/slave/test', 18:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:06 INFO - 'SHELL': '/bin/bash', 18:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:54:06 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:06 INFO - 'USER': 'cltbld', 18:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:06 INFO - 'XPC_FLAGS': '0x0', 18:54:06 INFO - 'XPC_SERVICE_NAME': '0', 18:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 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)) 18:54:06 INFO - Cleaning up... 18:54:06 INFO - Return code: 0 18:54:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:07 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 0x109822a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098b7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab1bd97710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, '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.8zZGPJls3C/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.6NhPxmW3zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 18:54:07 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 18:54:07 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 18:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:07 INFO - 'HOME': '/Users/cltbld', 18:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:07 INFO - 'LOGNAME': 'cltbld', 18:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:07 INFO - 'MOZ_NO_REMOTE': '1', 18:54:07 INFO - 'NO_EM_RESTART': '1', 18:54:07 INFO - 'PAGER': '/bin/cat', 18:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:07 INFO - 'PWD': '/builds/slave/test', 18:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:07 INFO - 'SHELL': '/bin/bash', 18:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:54:07 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:07 INFO - 'USER': 'cltbld', 18:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:07 INFO - 'XPC_FLAGS': '0x0', 18:54:07 INFO - 'XPC_SERVICE_NAME': '0', 18:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:54:07 INFO - Cleaning up... 18:54:07 INFO - Return code: 0 18:54:07 INFO - Running post-action listener: _resource_record_post_action 18:54:07 INFO - Running post-action listener: _start_resource_monitoring 18:54:07 INFO - Starting resource monitoring. 18:54:07 INFO - [mozharness: 2016-09-01 01:54:07.568744Z] Finished create-virtualenv step (success) 18:54:07 INFO - [mozharness: 2016-09-01 01:54:07.569789Z] Running install step. 18:54:07 INFO - Running pre-action listener: _resource_record_pre_action 18:54:07 INFO - Running main action method: install 18:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:54:07 INFO - Reading from file tmpfile_stdout 18:54:07 INFO - Detecting whether we're running mozinstall >=1.0... 18:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:54:07 INFO - Reading from file tmpfile_stdout 18:54:07 INFO - Output received: 18:54:07 INFO - Usage: mozinstall [options] installer 18:54:07 INFO - Options: 18:54:07 INFO - -h, --help show this help message and exit 18:54:07 INFO - -d DEST, --destination=DEST 18:54:07 INFO - Directory to install application into. [default: 18:54:07 INFO - "/builds/slave/test"] 18:54:07 INFO - --app=APP Application being installed. [default: firefox] 18:54:07 INFO - mkdir: /builds/slave/test/build/application 18:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:54:42 INFO - Reading from file tmpfile_stdout 18:54:42 INFO - Output received: 18:54:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:54:42 INFO - Running post-action listener: _resource_record_post_action 18:54:42 INFO - [mozharness: 2016-09-01 01:54:42.751692Z] Finished install step (success) 18:54:42 INFO - [mozharness: 2016-09-01 01:54:42.751860Z] Running run-tests step. 18:54:42 INFO - Running pre-action listener: _resource_record_pre_action 18:54:42 INFO - Running main action method: run_tests 18:54:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:54:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:54:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:54:42 INFO - 2016-08-31 18:54:42.766 screenresolution[1638:7376] starting screenresolution argv=screenresolution get 18:54:42 INFO - 2016-08-31 18:54:42.769 screenresolution[1638:7376] Display 0: 1600x1200x32@60 18:54:42 INFO - 2016-08-31 18:54:42.774 screenresolution[1639:7378] starting screenresolution argv=screenresolution list 18:54:42 INFO - Available Modes on Display 0 18:54:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:54:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:54:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:54:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:54:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:54:43 INFO - Intel Iris: 18:54:43 INFO - Chipset Model: Intel Iris 18:54:43 INFO - Type: GPU 18:54:43 INFO - Bus: Built-In 18:54:43 INFO - VRAM (Dynamic, Max): 1536 MB 18:54:43 INFO - Vendor: Intel (0x8086) 18:54:43 INFO - Device ID: 0x0a2e 18:54:43 INFO - Revision ID: 0x0009 18:54:43 INFO - Displays: 18:54:43 INFO - Display: 18:54:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:54:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:54:43 INFO - Display Serial Number: 1600x1200 60 18:54:43 INFO - Main Display: Yes 18:54:43 INFO - Mirror: Off 18:54:43 INFO - Online: Yes 18:54:43 INFO - Rotation: Supported 18:54:43 INFO - Return code: 0 18:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:54:43 INFO - Python 2.7.3 18:54:43 INFO - Return code: 0 18:54:43 INFO - grabbing minidump binary from tooltool 18:54: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54: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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109786d30>, '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 0x1094ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10977eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:54: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 18:54: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 18:54:43 INFO - Calling ['/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'] with output_timeout 600 18:54:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:54:43 INFO - Return code: 0 18:54:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:54:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:54:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:54:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:54:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 18:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --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 18:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zZGPJls3C/Render', 18:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:43 INFO - 'HOME': '/Users/cltbld', 18:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:43 INFO - 'LOGNAME': 'cltbld', 18:54:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:54:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:43 INFO - 'MOZ_NO_REMOTE': '1', 18:54:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:54:43 INFO - 'NO_EM_RESTART': '1', 18:54:43 INFO - 'PAGER': '/bin/cat', 18:54:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:43 INFO - 'PWD': '/builds/slave/test', 18:54:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:43 INFO - 'SHELL': '/bin/bash', 18:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6NhPxmW3zo/Listeners', 18:54:43 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 18:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:43 INFO - 'USER': 'cltbld', 18:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:43 INFO - 'XPC_FLAGS': '0x0', 18:54:43 INFO - 'XPC_SERVICE_NAME': '0', 18:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 18:54:43 INFO - mozversion application_buildid: 20160831164321 18:54:43 INFO - mozversion application_changeset: 04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac 18:54:43 INFO - mozversion application_display_name: Nightly 18:54:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:54:43 INFO - mozversion application_name: Firefox 18:54:43 INFO - mozversion application_remotingname: firefox 18:54:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:54:43 INFO - mozversion application_vendor: Mozilla 18:54:43 INFO - mozversion application_version: 51.0a1 18:54:43 INFO - mozversion platform_buildid: 20160831164321 18:54:43 INFO - mozversion platform_changeset: 04d9b7160ac9a3751124c2c98d1ae8eadc65f0ac 18:54:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:54:43 INFO - mozversion platform_version: 51.0a1 18:54:43 INFO - using testdate: 1472694883 18:54:43 INFO - actual date: 1472694883 18:54:43 INFO - starting webserver on 'localhost:49267' 18:54:43 INFO - SUITE-START | Running 2 tests 18:54:43 INFO - TEST-START | dromaeo_css 18:54:43 INFO - operating with platform_type : mac_ 18:54:43 INFO - Initialising browser for dromaeo_css test... 18:54:43 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpbbmT94/profile http://localhost:49267/getInfo.html) 18:54:46 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 18:54:46 INFO - PROCESS | 1649 | colorDepth:24 18:54:46 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 18:54:46 INFO - PROCESS | 1649 | __metrics 18:54:46 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 18:54:46 INFO - TEST-INFO | 1649: exit 0 18:54:46 INFO - Browser initialized. 18:54:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:54:46 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpbbmT94/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:54:58 INFO - PROCESS | 1652 | RSS: Main: 244039680 18:54:58 INFO - PROCESS | 1652 | 18:55:48 INFO - PROCESS | 1652 | 1472694948407 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] 18:58:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:58:02 INFO - PROCESS | 1652 | RSS: Main: 267378688 18:58:02 INFO - PROCESS | 1652 | 19:01:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 19:01:05 INFO - PROCESS | 1652 | RSS: Main: 274599936 19:01:05 INFO - PROCESS | 1652 | 19:04:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 19:04:08 INFO - PROCESS | 1652 | RSS: Main: 276369408 19:04:08 INFO - PROCESS | 1652 | 19:07:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 19:07:10 INFO - PROCESS | 1652 | RSS: Main: 271417344 19:07:10 INFO - PROCESS | 1652 | 19:10:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 19:10:14 INFO - PROCESS | 1652 | RSS: Main: 277655552 19:10:14 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 19:13:17 INFO - PROCESS | 1652 | RSS: Main: 279216128 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | __start_tp_report 19:13:17 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 19:13:17 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 19:13:17 INFO - PROCESS | 1652 | |i|pagename|runs| 19:13:17 INFO - PROCESS | 1652 | |0;dojo.html;3267.787593806404;3295.6704329567046;3412.641672624373;3431.245126072264;3487.5466189395383;5510.448955104487;5658.547316214698;5844.883102337951;5957.374475680056;6023;5371.301730775003;5583.715745378566;5975.910361344583;5981.0729336952845;6038.66787326698;5295.8146464873735;5347.064263753847;5462.20797984294;5473;5515.7242137893145;5480.534154596864;5495.917561236564;5514.80698175564;5545.639533430334;5574.86062848428;4705.800020994659;5094.286799848024;5278.287431196816;5424.186372044225;5436.3476382833915;5277.419483856773;5341.626086173969;5374.5700343972385;5390.51485366319;5415.512603865671;5322.946770532322;5332.626716129834;5354.437784032699;5417.972910135463;5457.536109430703;5335.359756829166;5997.520198384119;6029.095635654687;6047.032474804054;6048.123022161806;6133.576326128218;6196.938030619726;6229.563930524865;6233.844153896143;6259.874802503924;6271.843203919893;6302.086197501337;6303.369663033705;6304.211973503312;6313.242410910674;5913.05391137416;6032.728527216286;6081.665508397081;6084.5132389408745;6134.938650613437;6359;6408.391202835724;6411.070394792729;6421.454176395058;6470.320616335312;6214.937850621526;6232.065190221412;6234.3453937336835;6248.718807653667;6254.655993920287;6113.141862489121;6157.1072194532435;6176.073588961692;6219.906701399483;6227.875442491125;6118.040554665226;6143.450468055797;6191.102290167901;6202.945499265045;6207.410296021871;6138.877222455526;6229.1590635264565;6237.96881015592;6256.124142620037;6264;4939.234418665113;4987.177115775857;5025.3718285214345;5036.84889453317;5075.390953085654;4461.486929003207;4470.575295346936;4508.706934049342;4529.818807247739;4530.637548996073;5909.261342332208;6022.247219097613;6029.819105426756;6037.909431358533;6051.909221361683;6130.398136742002;6175.6912154393;6191.102290167901;6284.088807123033;6289.685515724195;6015.699215039318;6063.605865618721;6069.696515174223;6124.418180272867;6136.478399336017;5532.948739739437;5552.91670624941;5559.916601250984;5568.49883510486;5577.386487486373;6307.24313082433;6405.615663060231;6420.133282006869;6489.772857949949;6491.188601424822;6277.843053923689;6287.497000240061;6299.716512756845;6300.968495157678;6326.905096423466;6511.544191906711;6529.902051469327;6532.314107018695;6541.901871472027;6552.410283074546;6133.640713961282;6144.678894037625;6167.84580385494;6225.128482012612;6232.158658581215;5113.926075524181;6075.605085669417;6141.969290153521;6154.692265386802;6198.163247961467;5272.525472707474;5590;5863.765449382147;6108.75564977396;6187.535934804818;6098.069176274567;6140.416660417253;6161.753529858755;6163.876722465615;6234.407731265523;5982.252218472691;6143.539234557336;6146.862830376216;6152.723127459186;6175.660338681236;6128.4409139008485;6129.908988904127;6190.659513726789;6194.628322300677;6215.223097112861;4737.3130896020575;5210.14032684618;5635.154726791015;5681.204631351531;6169.907451388322 19:13:17 INFO - PROCESS | 1652 | |1;ext.html;484.3533882267173;505.9190529515278;536.3938749213388;536.745046103101;562.4937556199418;37158;39850.80074599623;39919.600803991954;39954.40068398973;40135.59864401359;40571.79714101433;40720.18559628813;41066.38400423989;41082;41261.58738412615;9601.567929443174;9757.853632195523;9809.852852207223;9812.950935245313;9814.803703925918;9725.465099419529;9797.461139637317;9815.607375704963;9839.311248212627;9872.703818885446;9657.661981830637;9772.413655180711;9791.314607977445;9791.804163916717;9794.510274486283;9696.224302055854;9758.365706229144;9765.316427850054;9769.169620582259;9786.853197201977;7797.142314345418;7869.331106855924;7892.408069394761;7894.368450523944;7895.131535531087;38470;38709.419358709645;38759;38914.80542597297;39009.02477438058;2982.5824384586176;3144.418282617723;3320.96679033209;3358.932821343584;3425.794452332868;24574.385640358956;30161.698383016108;30192;30259.78960841564;30932.381352372828;3229.2088438332526;3242.2866969266724;3292.37444885471;3373.595168579762;3412.692857642799;350326;354204;358472;359069;363847.18076409714;49500.50499495031;58208.7089564558;58241.4175858236;58459.4154058454;58559.70720146372;33827.32345353128;34329.02229176756;34652.65347346545;35397.82301088478;60970;16296.34814607426;16370.099644519665;16405;16411.91794041046;16434.260458279405;12532.874671253354;12534.686632834251;12611.180273282207;12635.368231588567;12637.368131593383;15855.048697078213;18946.242150313832;19015.33446329372;19087.8091219086;19174;6891.241963384024;7263.673134708951;7864.9382333384265;7998.48009879356;8145.185481451926;849.6176720475811;941.6468824190929;960.8058499322186;1046.586598293685;1063.122923588043;29680.851595741882;29904;30369.544456833166;30582.541261881088;31657;988.663854289545;1004.914582260516;1047.402980301221;1075.7310672331917;1099.571167244775;11893.04855611549;14362.784558231635;15051.623709407133;15139.848601513844;15398.769018464733;611861;621611;627377;628118;632526;54410.72794636001;54549.72701637915;54867.725661371434;54885.451145488034;55019.449805502234;1329.8736620021082;1358.4701966233176;1383.6402535340883;1383.951561695356;1393.3312010235145;24764.38089047788;28933.710662893944;28967.986120485682;29113;29683.554746678386;49546.25680614721;50187.99624007572;50285.99428011492;50331.49668503268;50441.495585045144;513647;514514;519913;520935;522047;25298.74701252964;25446.872765636053;25892.093776717724;26197.869010654824;26231.213063608124;44261.33607995818;44825.77587112044;45163.77418112889;45200.77399612981;46014.079718406116;36667.44998825073;37258.441123383716;37348;38519.0370240735;38519.614803851604;36735;37048.81475592605;37273.411075522345;38017;38350.61649383471;31336.68663313429;33026.83486582552;35935.64064359323;35995.46006809847;37224.441633374976;31062.757489700158;31794.6820531801;32782;33176.33647327088;34256.314873701886;24570.75429245756;24729.87635061813;25032.749672503043;25056.874715626305;25194.496110078064 19:13:17 INFO - PROCESS | 1652 | |2;jquery.html;370.4184430444202;371.072319201995;373.26280595823243;382.31183869035726;397.82641208435103;19074.141663625134;21023.684644730303;21047.2633457829;21173.94130293485;21244.787552124475;16127.516174514754;20233.089510972004;20776;20799.376018719424;21107.47231319218;5306.204069389584;5871.735771890254;6069.484093852028;6138.263408391;6165.938340616603;6038.96980515099;6093.482054025413;6098.115773212881;6107.4197951194565;6114.327423983358;5867.76528938842;5946.970265148668;5976.910346344808;6014.789482368118;6017.308009578911;4874.2444921037295;6019.765947980675;6034.63792172471;6035.517158627299;6039.5772295939305;5632.324121105453;5738.713064346808;5739.942600573982;5760.732638819453;5764.423557644244;13153.473861045546;24425.877870610533;24907.750922490723;25008.49983000348;25576.849041793164;2096.7693553709078;2117.1743020222125;2179.978200217993;2203.911843526257;2269.75163659988;10073.24450666203;10477.528511217013;15023.474178403812;15425.460108896246;15950.521484355488;1841.521204486843;1885.9905700471547;1890.6691329017367;1899.905004749757;1928.758905136858;95654;118186;122831;124172.37913810463;126124.36937815252;10924.563017479371;11288.322700637988;11559.537618495335;11592;11794.587189448328;13841.377138028813;13995.720085598232;14022.368993395321;14038.438462461387;14052.946029047862;7021.648917554102;8131;8444.155584441629;8444.324454043784;8564.057953625097;8261.917380826115;8301;8330.916690833013;8488.363372747068;8497.235248827636;4358.280883654225;4367.563243675607;4439.866803995886;4521.570450807168;4527.456705195332;1881.7647794025747;1937.6899696048565;1969.212315073982;1970.4482694910125;1972.3491247888162;777.6111944027986;822.596927505518;869.8952330540178;920.2040235196507;929;18264.63470730578;19805.10877010538;20588.38234852957;20591.794082058987;20905;885.5306687455659;909.6134142989204;924.3760461688333;936.6347124621403;1005.808117380906;10286.2799604027;10713.204982562927;10773.51519181623;10851.457427128613;10866.836997445045;562642;591723;601629;612777;622664;101449;135761;137650.3117484406;145525;147263.26368368088;860.8278344331159;944.8204841080172;969.9321047526605;994.3039872089566;1010.1867996263079;17940.641187176443;18718.53203669919;18888.905555472684;18962.620747584697;19042.23831046798;39847;40742.59257407388;45014.549854501034;49396;51445.97108057743;50966.74516627393;79105.60447197728;79285.60357198177;79611.80582291154;80378.39243214986;7267.873479610457;7640.465167438226;7647.694092236248;7862.9606851967665;8170.142135075732;12320.137590368588;12765.744685106432;12881.742365152832;13207.27359995171;13257.138286011379;9324.88101427833;9972.551235194289;10639.882812304597;11027;11092.445377731243;9534.904650953678;9597.856032159663;10784.838227426751;11151.275167114112;11344.716382090513;11295.661130166109;11310.773784524428;11355.261907975955;11355.829662554903;11483.885161148282;7346.375558077623;7679.347255483123;7768.689252430066;8033.6786528538205;8147.714829980922;9925.602975881173;11123.165762567678;11123.777524449304;11295.717607059889;11446.713832154262 19:13:17 INFO - PROCESS | 1652 | |3;mootools.html;1544.6833399153172;1584.1128967778043;1807.737878007689;1883.0867029490687;1915.3870761356363;8097.8785318220125;8725.171108744667;8890.511021893793;9315.627374905012;9341.859872101915;9023.917129944399;9108.817823643523;9112.90887091129;9113.635454581825;9136.497492637902;2073.295079672911;2396.7243766966803;2435.5372479228977;2439.731629520751;2440.6583078369044;2397.6883005209384;2428.1986944308337;2446.2538925627678;2447.412620970972;2457.6682147910074;2420.225527831095;2428.4657375377387;2432.4770174412497;2444.792192663626;2452.6934133233344;2391.270662447953;2406.2660888429023;2411.9035238590436;2416.2871952773976;2417.069428270111;2379.6787433696577;2407.374082738483;2408.421978725093;2408.506256217471;2420.201333559921;9032.50321232332;9053.411528250643;9067.183953444155;9067.773305667346;9103.908960910372;5324.254604355393;5409.188621706736;5434.673919564799;5496.7526461309335;5499.340079190482;3516.7010804081683;3649.233660931181;3654.3604869147794;3711.6288371162937;3717.052151701326;1070.7323169207698;1109.057301293902;1111.2221444988459;1112.8497652816848;1123.702218911989;10332.328398654063;11057.11543076552;11382.60160894373;11845.348505832177;11998.58004969822;4741.509799153335;4756.6432517561325;4800.279958006327;4895.975520122377;4944.90110197794;10022.949885250528;10129.898701013042;10169.491525423698;10181.490925453698;10196.592136314614;5424.077906755861;5442.972785136049;5454.154606036071;5461.563074953994;5478.671279723229;4470.731756094645;4895.73073480962;4953.479884612064;4967.975160124249;5004.374453193351;3962.821673024721;4019.1157945252;4068.572799856032;4101.91796164081;4145.2124096422185;1313.9868601313865;1332.367125615325;1347.4677502386503;1351.1555277951281;1355.153029356652;804.9597520123971;822.7572866004543;904.5838914099456;941.5198248892976;957.6600306891092;4760.476347601761;5351.170568561879;5462.3445186577865;5671.943280567141;5857.974854400548;899.9910000899907;900.8603666431713;982.3221976835961;997.7156510394502;1012.2711647613706;3348.765586408928;3659.432787917877;3915.6280153385383;3959.6238357356006;3976.864061376194;8949.642014319485;10613.893861061291;11028.779424411467;11044.392558409203;11169.44152792357;9100.8634870477;10768.407737574353;10967.83548246777;11006.449677516091;11220.66338009861;6373.47036711186;7129.251428600026;8378.287845533197;8380.329573634095;8395.748127556182;4743.430788305425;5884.176215329943;5899.498542623754;6076.754265375499;6231.532635052298;9831.213502919893;9934.552945117342;10069.194464442973;10206.54070566841;10378.688639340831;10093.041161089684;10380.896191037993;10397.584096636352;10446.843297350393;10470.528826202992;6960.79117626472;7269.963650181715;7402.888956665546;7449.925500744923;7606.9619651901385;8389.412741108064;8406.453580517247;8491.36314776382;8528.872066919126;8592.183742544448;7528.623568821427;7678.1170165431695;7684.46208765381;7801.648925798239;7810.531368117931;7342.596157211405;7441.851162976818;7470.028896243453;7490.438217133846;7546;5780.508656764222;5958.463738263577;6609.636469994197;7074.045003924404;7197.568145911262;4658.184817656965;4776;5029.572486338702;5077.441481437113;5085.364329458817;4977.875553111201;4978.900421991612;5059.949400505948;5165.225216217447;5209.817656382009 19:13:17 INFO - PROCESS | 1652 | |4;prototype.html;270.95258329792284;305.3541759179335;306.51723535431694;310.7964283394376;313.9058282515248;4717.9764101179535;4938.629602779793;5228.424873263947;5510.311211098613;5656.943430565699;4589.5869371756535;4967.379077615298;5048.772805223764;5478.58910581706;5592.384837667853;4033.3748269018274;4122.546519882825;4181.560936101711;4222.97888510557;4253.319468884979;4169.770662613555;4195.62239398454;4223.387608796723;4224.423101907833;4263.957360426387;3444.603870554881;4186.6022727840955;4186.74879507229;4208.389783481393;4226.978865105671;3775.735128731878;4040.4545386372906;4197.223513649985;4225.661947044229;4244.830206791725;3068.7544996400457;3322.936660268715;3615.5480564929385;3623.5108260384773;3644.325799727059;5180.378354597435;5257.973710131425;5337.839864804062;5390.110631745796;5390.434004429518;4670.649701272384;4808.158572249878;4836.322914791932;4843.2250839865455;4870.951290487121;5830.47525722687;5837.956715149664;5861.531077513831;5864.472197502203;5904.424071344699;4032.9395059074136;4034.536028356747;4048.635622793933;4059.269331520325;4061.5329237137516;5743.684097374643;6104.755809767604;6147.96926015376;6200.627962322275;6220.1961431185355;3502.4571191465366;3928.799872071649;3973.4039894016128;4002.8598999034893;4019.251625542898;4040.4343391925154;4042.91914161721;4043.8584649537715;4050.311447053949;4051.837926482967;4018.8593399230886;4538.341940418687;4739.146953548326;4757.833475828329;4783.3497443381975;5014.473480284591;5709.371969083398;5938.8812223755285;5991.700414979234;6016;1116.6873275482878;1121.7756448710127;1356.6269275949194;1379.9103058301177;1383.688670049231;755.2749360613763;790.9243428936642;833.3708050421893;836.481381543447;842.3345557009893;5244.160934250502;5546.694931778791;5558.305211848519;5592.86017849557;5630.211770352154;5510.862228444321;5936.881262374728;6155.753769849156;6199.132121502993;6205.348438413992;5188.143956247252;5513.228148059275;5513.448655134534;5524.552735048324;5659.26429564154;2785.470760555488;2849.7292757188034;3148.9055328340187;3152.6374466936136;3167.9862444017695;4935.2973224237585;5943.910841337383;6011.226688127013;6337.683115844189;6347.398136351201;6110.969445152746;6168.8766224675255;6181.783637572664;6225.221847269091;6258.561900667;6241.095041219089;6270.2789179244055;6302.842428939313;6322.083297921868;6344.365563443804;5594.804181853779;5893.44012318829;6032.638041717511;6039.426254505991;6066.545009124245;5089.541941225308;5946.851328716817;5967.776605795714;6007.158997740233;6043.879122417615;5931.288245410578;6048.425399587032;6325.810225693236;6361.823062733409;6376.171097757262;3626.945595816012;3691.870784522529;3937.92124157521;3947.9605203947594;3952.3873799561115;5259.342582177228;5358.490943360374;5817.214676018683;6008.188894499187;6058.969705151446;5652.463016013472;5790.94209057904;5874.823755287348;5907.822765317047;5955.6724380157775;5019.7741101651245;5660.490555849993;5790.855228619318;5840;5880.617759845596;5160.406553246275;5479.753411096431;5756.453136951983;5771.653700777967;5796.536277097738;4894.730789806595;5121.3342265506735;5416.510459623635;5614.494695477427;5904.586678932434;4944.777485013255;5438.510534051954;5572;5638.238837757014;5664.26364572603 19:13:17 INFO - PROCESS | 1652 | |5;yui.html;693.9410150137238;721.3796135323621;742.6435311050695;773.4431209529137;776.2741836382986;15402.922985385085;15951;16339;16395.016299022067;16442.424515141956;13919.443222271122;14005.369758360872;14007.0195086344;14014.92992535036;14023.158610483353;663.0419044480727;825.6986200037003;830.2569200565505;858.7380848841101;860.2129051917487;800.9118996910336;849.3332733803934;851.0043249398219;852.0414533649645;852.3820230333021;901.0673952459206;909.2589539525299;916.1159481100736;916.8808054952847;920.0477505781517;904.9049849765779;907.5734404829719;914.049388635818;916.0610374366295;917.0737555069428;671.3990978074596;679.2053297641771;680.0717021265986;680.8910574308087;682.3994884501657;14882.479113231093;15074.924625376803;15084.39662413502;15094.56601622844;15126.092434453967;14216.857831421761;14351.497697580638;14373;14444.92777536116;14454.349554270084;11779.528818847235;12005;12068.577599784054;12183;12317.384130793514;3608.6391360863704;3616.3490571697084;3783.9243215135816;3785.2240290740606;3796.7532110412735;110362.44818775855;110880.4455977723;111305.53028106375;111671.44164279208;115218.42390788076;10978.780424391467;11033.6138235163;11046;11220.66338009861;11357.488912998935;22885.199018034287;22927;22936;22988.080476780742;23104;8028.759137225776;8114.026316842017;8180.836383272302;8284.875726864102;8423.747287581382;4452.30989196675;4473.2842745160615;4537.8184872604725;4558.088382323548;4608.377868987711;3517.8742802303527;3528.558930133733;3625.8549658013385;3626.5285512883156;3637.654422829827;642.7364780440091;650.1288273713247;655.6164643683403;657.1030543308445;667.0561155964256;583.6556431705229;643.2570381209711;647.1942431672597;674.5747893816481;676.7969609117246;7929.5242285463055;9313.115254050854;9381.530923453936;9533.285003624756;9586.66446674363;602.4999250621973;637.5154882289494;658.6871236162784;666.047552000634;667.8564108716649;4596.724196548218;4652.3951886255245;4683.81264749413;4691.648126390534;4694.88262793426;75919.86120208201;87617.56191219004;124440.37779811044;149325.01349973158;162459;98756;113232.43383783194;142727;143125;144838.03146703384;108769.82460350907;117500.23749643934;118031.06580611679;118166;120260.81186923431;9015.2787776979;9027.684031058881;9029.058180968617;9052.864207036768;9172.587233574639;52898;65122.88279640759;67032.99450508344;68115.3188468109;69042.65478672575;68520.3147968514;75676;87218.56390718005;90336.74158146098;91526.54236728774;11078.723031924266;13393.732125357634;13527.256000920044;13773.242471664154;14015.929920350332;14840.62898427548;15057;15179.772303415237;15388;15686.372545098524;13505.32473376347;13685.452581896612;13762.65593360174;13874.583762487142;14319.498817541336;13490.460381584626;13730.588082357544;13755.243461609707;13788.103773254707;13824.516141934984;13352;13468.057235993387;13474.865251347754;13503.32483375847;13881.65295867571;10706.304090234109;11091.279066860629;11224.326540407601;11317.773644526898;11814.409279536161;10583.417912014605;11343.886561134614;11448.535071322358;11550;11582.94208528952 19:13:17 INFO - PROCESS | 1652 | __end_tp_report 19:13:17 INFO - PROCESS | 1652 | __start_cc_report 19:13:17 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,389 19:13:17 INFO - PROCESS | 1652 | __end_cc_report 19:13:17 INFO - PROCESS | 1652 | __startTimestamp1472695997275__endTimestamp 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | ------- Summary: start ------- 19:13:17 INFO - PROCESS | 1652 | Number of tests: 6 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5818.34 Median:6098.07 stddev:644.98 (10.6%) stddev-sans-first:615.15 19:13:17 INFO - PROCESS | 1652 | Values: 3267.8 3295.7 3412.6 3431.2 3487.5 5510.4 5658.5 5844.9 5957.4 6023.0 5371.3 5583.7 5975.9 5981.1 6038.7 5295.8 5347.1 5462.2 5473.0 5515.7 5480.5 5495.9 5514.8 5545.6 5574.9 4705.8 5094.3 5278.3 5424.2 5436.3 5277.4 5341.6 5374.6 5390.5 5415.5 5322.9 5332.6 5354.4 5418.0 5457.5 5335.4 5997.5 6029.1 6047.0 6048.1 6133.6 6196.9 6229.6 6233.8 6259.9 6271.8 6302.1 6303.4 6304.2 6313.2 5913.1 6032.7 6081.7 6084.5 6134.9 6359.0 6408.4 6411.1 6421.5 6470.3 6214.9 6232.1 6234.3 6248.7 6254.7 6113.1 6157.1 6176.1 6219.9 6227.9 6118.0 6143.5 6191.1 6202.9 6207.4 6138.9 6229.2 6238.0 6256.1 6264.0 4939.2 4987.2 5025.4 5036.8 5075.4 4461.5 4470.6 4508.7 4529.8 4530.6 5909.3 6022.2 6029.8 6037.9 6051.9 6130.4 6175.7 6191.1 6284.1 6289.7 6015.7 6063.6 6069.7 6124.4 6136.5 5532.9 5552.9 5559.9 5568.5 5577.4 6307.2 6405.6 6420.1 6489.8 6491.2 6277.8 6287.5 6299.7 6301.0 6326.9 6511.5 6529.9 6532.3 6541.9 6552.4 6133.6 6144.7 6167.8 6225.1 6232.2 5113.9 6075.6 6142.0 6154.7 6198.2 5272.5 5590.0 5863.8 6108.8 6187.5 6098.1 6140.4 6161.8 6163.9 6234.4 5982.3 6143.5 6146.9 6152.7 6175.7 6128.4 6129.9 6190.7 6194.6 6215.2 4737.3 5210.1 5635.2 5681.2 6169.9 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:63144.55 Median:37310.71 stddev:136812.24 (366.7%) stddev-sans-first:137115.15 19:13:17 INFO - PROCESS | 1652 | Values: 484.4 505.9 536.4 536.7 562.5 37158.0 39850.8 39919.6 39954.4 40135.6 40571.8 40720.2 41066.4 41082.0 41261.6 9601.6 9757.9 9809.9 9813.0 9814.8 9725.5 9797.5 9815.6 9839.3 9872.7 9657.7 9772.4 9791.3 9791.8 9794.5 9696.2 9758.4 9765.3 9769.2 9786.9 7797.1 7869.3 7892.4 7894.4 7895.1 38470.0 38709.4 38759.0 38914.8 39009.0 2982.6 3144.4 3321.0 3358.9 3425.8 24574.4 30161.7 30192.0 30259.8 30932.4 3229.2 3242.3 3292.4 3373.6 3412.7 350326.0 354204.0 358472.0 359069.0 363847.2 49500.5 58208.7 58241.4 58459.4 58559.7 33827.3 34329.0 34652.7 35397.8 60970.0 16296.3 16370.1 16405.0 16411.9 16434.3 12532.9 12534.7 12611.2 12635.4 12637.4 15855.0 18946.2 19015.3 19087.8 19174.0 6891.2 7263.7 7864.9 7998.5 8145.2 849.6 941.6 960.8 1046.6 1063.1 29680.9 29904.0 30369.5 30582.5 31657.0 988.7 1004.9 1047.4 1075.7 1099.6 11893.0 14362.8 15051.6 15139.8 15398.8 611861.0 621611.0 627377.0 628118.0 632526.0 54410.7 54549.7 54867.7 54885.5 55019.4 1329.9 1358.5 1383.6 1384.0 1393.3 24764.4 28933.7 28968.0 29113.0 29683.6 49546.3 50188.0 50286.0 50331.5 50441.5 513647.0 514514.0 519913.0 520935.0 522047.0 25298.7 25446.9 25892.1 26197.9 26231.2 44261.3 44825.8 45163.8 45200.8 46014.1 36667.4 37258.4 37348.0 38519.0 38519.6 36735.0 37048.8 37273.4 38017.0 38350.6 31336.7 33026.8 35935.6 35995.5 37224.4 31062.8 31794.7 32782.0 33176.3 34256.3 24570.8 24729.9 25032.7 25056.9 25194.5 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:34749.10 Median:12973.63 stddev:100058.16 (771.2%) stddev-sans-first:100305.55 19:13:17 INFO - PROCESS | 1652 | Values: 370.4 371.1 373.3 382.3 397.8 19074.1 21023.7 21047.3 21173.9 21244.8 16127.5 20233.1 20776.0 20799.4 21107.5 5306.2 5871.7 6069.5 6138.3 6165.9 6039.0 6093.5 6098.1 6107.4 6114.3 5867.8 5947.0 5976.9 6014.8 6017.3 4874.2 6019.8 6034.6 6035.5 6039.6 5632.3 5738.7 5739.9 5760.7 5764.4 13153.5 24425.9 24907.8 25008.5 25576.8 2096.8 2117.2 2180.0 2203.9 2269.8 10073.2 10477.5 15023.5 15425.5 15950.5 1841.5 1886.0 1890.7 1899.9 1928.8 95654.0 118186.0 122831.0 124172.4 126124.4 10924.6 11288.3 11559.5 11592.0 11794.6 13841.4 13995.7 14022.4 14038.4 14052.9 7021.6 8131.0 8444.2 8444.3 8564.1 8261.9 8301.0 8330.9 8488.4 8497.2 4358.3 4367.6 4439.9 4521.6 4527.5 1881.8 1937.7 1969.2 1970.4 1972.3 777.6 822.6 869.9 920.2 929.0 18264.6 19805.1 20588.4 20591.8 20905.0 885.5 909.6 924.4 936.6 1005.8 10286.3 10713.2 10773.5 10851.5 10866.8 562642.0 591723.0 601629.0 612777.0 622664.0 101449.0 135761.0 137650.3 145525.0 147263.3 860.8 944.8 969.9 994.3 1010.2 17940.6 18718.5 18888.9 18962.6 19042.2 39847.0 40742.6 45014.5 49396.0 51446.0 50966.7 79105.6 79285.6 79611.8 80378.4 7267.9 7640.5 7647.7 7863.0 8170.1 12320.1 12765.7 12881.7 13207.3 13257.1 9324.9 9972.6 10639.9 11027.0 11092.4 9534.9 9597.9 10784.8 11151.3 11344.7 11295.7 11310.8 11355.3 11355.8 11483.9 7346.4 7679.3 7768.7 8033.7 8147.7 9925.6 11123.2 11123.8 11295.7 11446.7 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5740.98 Median:4895.85 stddev:3204.87 (65.5%) stddev-sans-first:3198.35 19:13:17 INFO - PROCESS | 1652 | Values: 1544.7 1584.1 1807.7 1883.1 1915.4 8097.9 8725.2 8890.5 9315.6 9341.9 9023.9 9108.8 9112.9 9113.6 9136.5 2073.3 2396.7 2435.5 2439.7 2440.7 2397.7 2428.2 2446.3 2447.4 2457.7 2420.2 2428.5 2432.5 2444.8 2452.7 2391.3 2406.3 2411.9 2416.3 2417.1 2379.7 2407.4 2408.4 2408.5 2420.2 9032.5 9053.4 9067.2 9067.8 9103.9 5324.3 5409.2 5434.7 5496.8 5499.3 3516.7 3649.2 3654.4 3711.6 3717.1 1070.7 1109.1 1111.2 1112.8 1123.7 10332.3 11057.1 11382.6 11845.3 11998.6 4741.5 4756.6 4800.3 4896.0 4944.9 10022.9 10129.9 10169.5 10181.5 10196.6 5424.1 5443.0 5454.2 5461.6 5478.7 4470.7 4895.7 4953.5 4968.0 5004.4 3962.8 4019.1 4068.6 4101.9 4145.2 1314.0 1332.4 1347.5 1351.2 1355.2 805.0 822.8 904.6 941.5 957.7 4760.5 5351.2 5462.3 5671.9 5858.0 900.0 900.9 982.3 997.7 1012.3 3348.8 3659.4 3915.6 3959.6 3976.9 8949.6 10613.9 11028.8 11044.4 11169.4 9100.9 10768.4 10967.8 11006.4 11220.7 6373.5 7129.3 8378.3 8380.3 8395.7 4743.4 5884.2 5899.5 6076.8 6231.5 9831.2 9934.6 10069.2 10206.5 10378.7 10093.0 10380.9 10397.6 10446.8 10470.5 6960.8 7270.0 7402.9 7449.9 7607.0 8389.4 8406.5 8491.4 8528.9 8592.2 7528.6 7678.1 7684.5 7801.6 7810.5 7342.6 7441.9 7470.0 7490.4 7546.0 5780.5 5958.5 6609.6 7074.0 7197.6 4658.2 4776.0 5029.6 5077.4 5085.4 4977.9 4978.9 5059.9 5165.2 5209.8 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4745.99 Median:5374.30 stddev:1498.30 (27.9%) stddev-sans-first:1464.37 19:13:17 INFO - PROCESS | 1652 | Values: 271.0 305.4 306.5 310.8 313.9 4718.0 4938.6 5228.4 5510.3 5656.9 4589.6 4967.4 5048.8 5478.6 5592.4 4033.4 4122.5 4181.6 4223.0 4253.3 4169.8 4195.6 4223.4 4224.4 4264.0 3444.6 4186.6 4186.7 4208.4 4227.0 3775.7 4040.5 4197.2 4225.7 4244.8 3068.8 3322.9 3615.5 3623.5 3644.3 5180.4 5258.0 5337.8 5390.1 5390.4 4670.6 4808.2 4836.3 4843.2 4871.0 5830.5 5838.0 5861.5 5864.5 5904.4 4032.9 4034.5 4048.6 4059.3 4061.5 5743.7 6104.8 6148.0 6200.6 6220.2 3502.5 3928.8 3973.4 4002.9 4019.3 4040.4 4042.9 4043.9 4050.3 4051.8 4018.9 4538.3 4739.1 4757.8 4783.3 5014.5 5709.4 5938.9 5991.7 6016.0 1116.7 1121.8 1356.6 1379.9 1383.7 755.3 790.9 833.4 836.5 842.3 5244.2 5546.7 5558.3 5592.9 5630.2 5510.9 5936.9 6155.8 6199.1 6205.3 5188.1 5513.2 5513.4 5524.6 5659.3 2785.5 2849.7 3148.9 3152.6 3168.0 4935.3 5943.9 6011.2 6337.7 6347.4 6111.0 6168.9 6181.8 6225.2 6258.6 6241.1 6270.3 6302.8 6322.1 6344.4 5594.8 5893.4 6032.6 6039.4 6066.5 5089.5 5946.9 5967.8 6007.2 6043.9 5931.3 6048.4 6325.8 6361.8 6376.2 3626.9 3691.9 3937.9 3948.0 3952.4 5259.3 5358.5 5817.2 6008.2 6059.0 5652.5 5790.9 5874.8 5907.8 5955.7 5019.8 5660.5 5790.9 5840.0 5880.6 5160.4 5479.8 5756.5 5771.7 5796.5 4894.7 5121.3 5416.5 5614.5 5904.6 4944.8 5438.5 5572.0 5638.2 5664.3 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24207.22 Median:3621.10 stddev:38019.33 (1049.9%) stddev-sans-first:38084.99 19:13:17 INFO - PROCESS | 1652 | Values: 693.9 721.4 742.6 773.4 776.3 15402.9 15951.0 16339.0 16395.0 16442.4 13919.4 14005.4 14007.0 14014.9 14023.2 663.0 825.7 830.3 858.7 860.2 800.9 849.3 851.0 852.0 852.4 901.1 909.3 916.1 916.9 920.0 904.9 907.6 914.0 916.1 917.1 671.4 679.2 680.1 680.9 682.4 14882.5 15074.9 15084.4 15094.6 15126.1 14216.9 14351.5 14373.0 14444.9 14454.3 11779.5 12005.0 12068.6 12183.0 12317.4 3608.6 3616.3 3783.9 3785.2 3796.8 110362.4 110880.4 111305.5 111671.4 115218.4 10978.8 11033.6 11046.0 11220.7 11357.5 22885.2 22927.0 22936.0 22988.1 23104.0 8028.8 8114.0 8180.8 8284.9 8423.7 4452.3 4473.3 4537.8 4558.1 4608.4 3517.9 3528.6 3625.9 3626.5 3637.7 642.7 650.1 655.6 657.1 667.1 583.7 643.3 647.2 674.6 676.8 7929.5 9313.1 9381.5 9533.3 9586.7 602.5 637.5 658.7 666.0 667.9 4596.7 4652.4 4683.8 4691.6 4694.9 75919.9 87617.6 124440.4 149325.0 162459.0 98756.0 113232.4 142727.0 143125.0 144838.0 108769.8 117500.2 118031.1 118166.0 120260.8 9015.3 9027.7 9029.1 9052.9 9172.6 52898.0 65122.9 67033.0 68115.3 69042.7 68520.3 75676.0 87218.6 90336.7 91526.5 11078.7 13393.7 13527.3 13773.2 14015.9 14840.6 15057.0 15179.8 15388.0 15686.4 13505.3 13685.5 13762.7 13874.6 14319.5 13490.5 13730.6 13755.2 13788.1 13824.5 13352.0 13468.1 13474.9 13503.3 13881.7 10706.3 11091.3 11224.3 11317.8 11814.4 10583.4 11343.9 11448.5 11550.0 11582.9 19:13:17 INFO - PROCESS | 1652 | -------- Summary: end -------- 19:13:17 INFO - PROCESS | 1652 | 19:13:17 INFO - TEST-INFO | 1652: exit 0 19:13:17 INFO - TEST-OK | dromaeo_css | took 1114618ms 19:13:17 INFO - TEST-START | kraken 19:13:17 INFO - operating with platform_type : mac_ 19:13:17 INFO - Initialising browser for kraken test... 19:13:17 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpIcFcBj/profile http://localhost:49267/getInfo.html) 19:13:20 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 19:13:20 INFO - PROCESS | 1665 | colorDepth:24 19:13:20 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 19:13:20 INFO - PROCESS | 1665 | __metrics 19:13:20 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 19:13:21 INFO - TEST-INFO | 1665: exit 0 19:13:21 INFO - Browser initialized. 19:13:21 INFO - Running cycle 1/1 for kraken test... 19:13:21 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpIcFcBj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:33 INFO - PROCESS | 1667 | RSS: Main: 245260288 19:13:33 INFO - PROCESS | 1667 | 19:13:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 19:13:37 INFO - PROCESS | 1667 | RSS: Main: 258015232 19:13:37 INFO - PROCESS | 1667 | 19:13:40 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 19:13:40 INFO - PROCESS | 1667 | RSS: Main: 266670080 19:13:40 INFO - PROCESS | 1667 | 19:13:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 19:13:44 INFO - PROCESS | 1667 | RSS: Main: 263249920 19:13:44 INFO - PROCESS | 1667 | 19:13:46 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 19:13:47 INFO - PROCESS | 1667 | RSS: Main: 264658944 19:13:47 INFO - PROCESS | 1667 | 19:13:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:13:50 INFO - PROCESS | 1667 | RSS: Main: 266350592 19:13:50 INFO - PROCESS | 1667 | 19:13:53 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 19:13:53 INFO - PROCESS | 1667 | RSS: Main: 267161600 19:13:53 INFO - PROCESS | 1667 | 19:13:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 19:13:57 INFO - PROCESS | 1667 | RSS: Main: 267403264 19:13:57 INFO - PROCESS | 1667 | 19:14:00 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 19:14:01 INFO - PROCESS | 1667 | RSS: Main: 267526144 19:14:01 INFO - PROCESS | 1667 | 19:14:02 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:14:03 INFO - PROCESS | 1667 | RSS: Main: 267251712 19:14:03 INFO - PROCESS | 1667 | 19:14:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:14:05 INFO - PROCESS | 1667 | RSS: Main: 266723328 19:14:05 INFO - PROCESS | 1667 | 19:14:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:14:08 INFO - PROCESS | 1667 | RSS: Main: 267034624 19:14:08 INFO - PROCESS | 1667 | 19:14:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:14:12 INFO - PROCESS | 1667 | RSS: Main: 267304960 19:14:12 INFO - PROCESS | 1667 | 19:14:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:14:15 INFO - PROCESS | 1667 | RSS: Main: 267362304 19:14:15 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 19:14:17 INFO - PROCESS | 1667 | RSS: Main: 269041664 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | __start_tp_report 19:14:17 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 19:14:17 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 19:14:17 INFO - PROCESS | 1667 | |i|pagename|runs| 19:14:17 INFO - PROCESS | 1667 | |0;ai-astar;114;120;107;117;119;119;102;117;117;120 19:14:17 INFO - PROCESS | 1667 | |1;audio-beat-detection;146;109;140;189;110;133;132;112;165;112 19:14:17 INFO - PROCESS | 1667 | |2;audio-dft;194;205;253;276;260;202;202;203;196;192 19:14:17 INFO - PROCESS | 1667 | |3;audio-fft;66;82;75;131;66;78;80;85;129;59 19:14:17 INFO - PROCESS | 1667 | |4;audio-oscillator;68;71;116;71;72;90;113;70;59;136 19:14:17 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;91;79;133;127;92;88;92;89;92;88 19:14:17 INFO - PROCESS | 1667 | |6;imaging-darkroom;97;98;97;102;97;117;98;148;146;111 19:14:17 INFO - PROCESS | 1667 | |7;imaging-desaturate;96;134;91;138;85;148;148;93;90;91 19:14:17 INFO - PROCESS | 1667 | |8;json-parse-financial;54;57;55;56;56;56;58;55;64;51 19:14:17 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;66;71;68;71;75;76;70;70;70;73 19:14:17 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;89;90;83;85;88;89;84;78;75;77 19:14:17 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;143;141;138;140;172;153;154;174;190;165 19:14:17 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;170;172;171;175;175;224;183;174;167;169 19:14:17 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;71;90;89;82;83;85;84;69;66;66 19:14:17 INFO - PROCESS | 1667 | __end_tp_report 19:14:17 INFO - PROCESS | 1667 | __start_cc_report 19:14:17 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,986 19:14:17 INFO - PROCESS | 1667 | __end_cc_report 19:14:17 INFO - PROCESS | 1667 | __startTimestamp1472696057987__endTimestamp 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | ------- Summary: start ------- 19:14:17 INFO - PROCESS | 1667 | Number of tests: 14 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:115.20 Median:118.00 stddev:6.03 (5.1%) stddev-sans-first:6.38 19:14:17 INFO - PROCESS | 1667 | Values: 114.0 120.0 107.0 117.0 119.0 119.0 102.0 117.0 117.0 120.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:136.50 stddev:26.53 (19.4%) stddev-sans-first:27.83 19:14:17 INFO - PROCESS | 1667 | Values: 146.0 109.0 140.0 189.0 110.0 133.0 132.0 112.0 165.0 112.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:218.30 Median:204.00 stddev:31.61 (15.5%) stddev-sans-first:32.28 19:14:17 INFO - PROCESS | 1667 | Values: 194.0 205.0 253.0 276.0 260.0 202.0 202.0 203.0 196.0 192.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:85.10 Median:76.50 stddev:25.02 (32.7%) stddev-sans-first:25.56 19:14:17 INFO - PROCESS | 1667 | Values: 66.0 82.0 75.0 131.0 66.0 78.0 80.0 85.0 129.0 59.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:70.50 stddev:26.03 (36.9%) stddev-sans-first:26.72 19:14:17 INFO - PROCESS | 1667 | Values: 68.0 71.0 116.0 71.0 72.0 90.0 113.0 70.0 59.0 136.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:90.00 stddev:17.82 (19.8%) stddev-sans-first:18.76 19:14:17 INFO - PROCESS | 1667 | Values: 91.0 79.0 133.0 127.0 92.0 88.0 92.0 89.0 92.0 88.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:97.00 stddev:20.10 (20.7%) stddev-sans-first:20.66 19:14:17 INFO - PROCESS | 1667 | Values: 97.0 98.0 97.0 102.0 97.0 117.0 98.0 148.0 146.0 111.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:111.40 Median:90.50 stddev:26.79 (29.6%) stddev-sans-first:27.83 19:14:17 INFO - PROCESS | 1667 | Values: 96.0 134.0 91.0 138.0 85.0 148.0 148.0 93.0 90.0 91.0 19:14:17 INFO - PROCESS | 1667 | 19:14:17 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:3.33 (5.9%) stddev-sans-first:3.43 19:14:17 INFO - PROCESS | 1667 | Values: 54.0 57.0 55.0 56.0 56.0 56.0 58.0 55.0 64.0 51.0 19:14:18 INFO - PROCESS | 1667 | 19:14:18 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.02 (4.3%) stddev-sans-first:2.60 19:14:18 INFO - PROCESS | 1667 | Values: 66.0 71.0 68.0 71.0 75.0 76.0 70.0 70.0 70.0 73.0 19:14:18 INFO - PROCESS | 1667 | 19:14:18 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:86.50 stddev:5.47 (6.3%) stddev-sans-first:5.47 19:14:18 INFO - PROCESS | 1667 | Values: 89.0 90.0 83.0 85.0 88.0 89.0 84.0 78.0 75.0 77.0 19:14:18 INFO - PROCESS | 1667 | 19:14:18 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:159.50 stddev:17.62 (11.0%) stddev-sans-first:17.95 19:14:18 INFO - PROCESS | 1667 | Values: 143.0 141.0 138.0 140.0 172.0 153.0 154.0 174.0 190.0 165.0 19:14:18 INFO - PROCESS | 1667 | 19:14:18 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:174.50 stddev:16.75 (9.6%) stddev-sans-first:17.52 19:14:18 INFO - PROCESS | 1667 | Values: 170.0 172.0 171.0 175.0 175.0 224.0 183.0 174.0 167.0 169.0 19:14:18 INFO - PROCESS | 1667 | 19:14:18 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:83.50 stddev:9.47 (11.3%) stddev-sans-first:9.64 19:14:18 INFO - PROCESS | 1667 | Values: 71.0 90.0 89.0 82.0 83.0 85.0 84.0 69.0 66.0 66.0 19:14:18 INFO - PROCESS | 1667 | -------- Summary: end -------- 19:14:18 INFO - PROCESS | 1667 | 19:14:18 INFO - TEST-INFO | 1667: exit 0 19:14:18 INFO - TEST-OK | kraken | took 60823ms 19:14:18 INFO - SUITE-END | took 1175s 19:14:19 INFO - Completed test suite (00:19:35) 19:14:19 INFO - javascript benchmark 19:14:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7374.618050527218, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3267.787593806404, 3295.6704329567046, 3412.641672624373, 3431.245126072264, 3487.5466189395383, 5510.448955104487, 5658.547316214698, 5844.883102337951, 5957.374475680056, 6023.0, 5371.301730775003, 5583.715745378566, 5975.910361344583, 5981.0729336952845, 6038.66787326698, 5295.8146464873735, 5347.064263753847, 5462.20797984294, 5473.0, 5515.7242137893145, 5480.534154596864, 5495.917561236564, 5514.80698175564, 5545.639533430334, 5574.86062848428, 4705.800020994659, 5094.286799848024, 5278.287431196816, 5424.186372044225, 5436.3476382833915, 5277.419483856773, 5341.626086173969, 5374.5700343972385, 5390.51485366319, 5415.512603865671, 5322.946770532322, 5332.626716129834, 5354.437784032699, 5417.972910135463, 5457.536109430703, 5335.359756829166, 5997.520198384119, 6029.095635654687, 6047.032474804054, 6048.123022161806, 6133.576326128218, 6196.938030619726, 6229.563930524865, 6233.844153896143, 6259.874802503924, 6271.843203919893, 6302.086197501337, 6303.369663033705, 6304.211973503312, 6313.242410910674, 5913.05391137416, 6032.728527216286, 6081.665508397081, 6084.5132389408745, 6134.938650613437, 6359.0, 6408.391202835724, 6411.070394792729, 6421.454176395058, 6470.320616335312, 6214.937850621526, 6232.065190221412, 6234.3453937336835, 6248.718807653667, 6254.655993920287, 6113.141862489121, 6157.1072194532435, 6176.073588961692, 6219.906701399483, 6227.875442491125, 6118.040554665226, 6143.450468055797, 6191.102290167901, 6202.945499265045, 6207.410296021871, 6138.877222455526, 6229.1590635264565, 6237.96881015592, 6256.124142620037, 6264.0, 4939.234418665113, 4987.177115775857, 5025.3718285214345, 5036.84889453317, 5075.390953085654, 4461.486929003207, 4470.575295346936, 4508.706934049342, 4529.818807247739, 4530.637548996073, 5909.261342332208, 6022.247219097613, 6029.819105426756, 6037.909431358533, 6051.909221361683, 6130.398136742002, 6175.6912154393, 6191.102290167901, 6284.088807123033, 6289.685515724195, 6015.699215039318, 6063.605865618721, 6069.696515174223, 6124.418180272867, 6136.478399336017, 5532.948739739437, 5552.91670624941, 5559.916601250984, 5568.49883510486, 5577.386487486373, 6307.24313082433, 6405.615663060231, 6420.133282006869, 6489.772857949949, 6491.188601424822, 6277.843053923689, 6287.497000240061, 6299.716512756845, 6300.968495157678, 6326.905096423466, 6511.544191906711, 6529.902051469327, 6532.314107018695, 6541.901871472027, 6552.410283074546, 6133.640713961282, 6144.678894037625, 6167.84580385494, 6225.128482012612, 6232.158658581215, 5113.926075524181, 6075.605085669417, 6141.969290153521, 6154.692265386802, 6198.163247961467, 5272.525472707474, 5590.0, 5863.765449382147, 6108.75564977396, 6187.535934804818, 6098.069176274567, 6140.416660417253, 6161.753529858755, 6163.876722465615, 6234.407731265523, 5982.252218472691, 6143.539234557336, 6146.862830376216, 6152.723127459186, 6175.660338681236, 6128.4409139008485, 6129.908988904127, 6190.659513726789, 6194.628322300677, 6215.223097112861, 4737.3130896020575, 5210.14032684618, 5635.154726791015, 5681.204631351531, 6169.907451388322], "value": 5777.992902816327, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.3533882267173, 505.9190529515278, 536.3938749213388, 536.745046103101, 562.4937556199418, 37158.0, 39850.80074599623, 39919.600803991954, 39954.40068398973, 40135.59864401359, 40571.79714101433, 40720.18559628813, 41066.38400423989, 41082.0, 41261.58738412615, 9601.567929443174, 9757.853632195523, 9809.852852207223, 9812.950935245313, 9814.803703925918, 9725.465099419529, 9797.461139637317, 9815.607375704963, 9839.311248212627, 9872.703818885446, 9657.661981830637, 9772.413655180711, 9791.314607977445, 9791.804163916717, 9794.510274486283, 9696.224302055854, 9758.365706229144, 9765.316427850054, 9769.169620582259, 9786.853197201977, 7797.142314345418, 7869.331106855924, 7892.408069394761, 7894.368450523944, 7895.131535531087, 38470.0, 38709.419358709645, 38759.0, 38914.80542597297, 39009.02477438058, 2982.5824384586176, 3144.418282617723, 3320.96679033209, 3358.932821343584, 3425.794452332868, 24574.385640358956, 30161.698383016108, 30192.0, 30259.78960841564, 30932.381352372828, 3229.2088438332526, 3242.2866969266724, 3292.37444885471, 3373.595168579762, 3412.692857642799, 350326.0, 354204.0, 358472.0, 359069.0, 363847.18076409714, 49500.50499495031, 58208.7089564558, 58241.4175858236, 58459.4154058454, 58559.70720146372, 33827.32345353128, 34329.02229176756, 34652.65347346545, 35397.82301088478, 60970.0, 16296.34814607426, 16370.099644519665, 16405.0, 16411.91794041046, 16434.260458279405, 12532.874671253354, 12534.686632834251, 12611.180273282207, 12635.368231588567, 12637.368131593383, 15855.048697078213, 18946.242150313832, 19015.33446329372, 19087.8091219086, 19174.0, 6891.241963384024, 7263.673134708951, 7864.9382333384265, 7998.48009879356, 8145.185481451926, 849.6176720475811, 941.6468824190929, 960.8058499322186, 1046.586598293685, 1063.122923588043, 29680.851595741882, 29904.0, 30369.544456833166, 30582.541261881088, 31657.0, 988.663854289545, 1004.914582260516, 1047.402980301221, 1075.7310672331917, 1099.571167244775, 11893.04855611549, 14362.784558231635, 15051.623709407133, 15139.848601513844, 15398.769018464733, 611861.0, 621611.0, 627377.0, 628118.0, 632526.0, 54410.72794636001, 54549.72701637915, 54867.725661371434, 54885.451145488034, 55019.449805502234, 1329.8736620021082, 1358.4701966233176, 1383.6402535340883, 1383.951561695356, 1393.3312010235145, 24764.38089047788, 28933.710662893944, 28967.986120485682, 29113.0, 29683.554746678386, 49546.25680614721, 50187.99624007572, 50285.99428011492, 50331.49668503268, 50441.495585045144, 513647.0, 514514.0, 519913.0, 520935.0, 522047.0, 25298.74701252964, 25446.872765636053, 25892.093776717724, 26197.869010654824, 26231.213063608124, 44261.33607995818, 44825.77587112044, 45163.77418112889, 45200.77399612981, 46014.079718406116, 36667.44998825073, 37258.441123383716, 37348.0, 38519.0370240735, 38519.614803851604, 36735.0, 37048.81475592605, 37273.411075522345, 38017.0, 38350.61649383471, 31336.68663313429, 33026.83486582552, 35935.64064359323, 35995.46006809847, 37224.441633374976, 31062.757489700158, 31794.6820531801, 32782.0, 33176.33647327088, 34256.314873701886, 24570.75429245756, 24729.87635061813, 25032.749672503043, 25056.874715626305, 25194.496110078064], "value": 19066.376093442726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.4184430444202, 371.072319201995, 373.26280595823243, 382.31183869035726, 397.82641208435103, 19074.141663625134, 21023.684644730303, 21047.2633457829, 21173.94130293485, 21244.787552124475, 16127.516174514754, 20233.089510972004, 20776.0, 20799.376018719424, 21107.47231319218, 5306.204069389584, 5871.735771890254, 6069.484093852028, 6138.263408391, 6165.938340616603, 6038.96980515099, 6093.482054025413, 6098.115773212881, 6107.4197951194565, 6114.327423983358, 5867.76528938842, 5946.970265148668, 5976.910346344808, 6014.789482368118, 6017.308009578911, 4874.2444921037295, 6019.765947980675, 6034.63792172471, 6035.517158627299, 6039.5772295939305, 5632.324121105453, 5738.713064346808, 5739.942600573982, 5760.732638819453, 5764.423557644244, 13153.473861045546, 24425.877870610533, 24907.750922490723, 25008.49983000348, 25576.849041793164, 2096.7693553709078, 2117.1743020222125, 2179.978200217993, 2203.911843526257, 2269.75163659988, 10073.24450666203, 10477.528511217013, 15023.474178403812, 15425.460108896246, 15950.521484355488, 1841.521204486843, 1885.9905700471547, 1890.6691329017367, 1899.905004749757, 1928.758905136858, 95654.0, 118186.0, 122831.0, 124172.37913810463, 126124.36937815252, 10924.563017479371, 11288.322700637988, 11559.537618495335, 11592.0, 11794.587189448328, 13841.377138028813, 13995.720085598232, 14022.368993395321, 14038.438462461387, 14052.946029047862, 7021.648917554102, 8131.0, 8444.155584441629, 8444.324454043784, 8564.057953625097, 8261.917380826115, 8301.0, 8330.916690833013, 8488.363372747068, 8497.235248827636, 4358.280883654225, 4367.563243675607, 4439.866803995886, 4521.570450807168, 4527.456705195332, 1881.7647794025747, 1937.6899696048565, 1969.212315073982, 1970.4482694910125, 1972.3491247888162, 777.6111944027986, 822.596927505518, 869.8952330540178, 920.2040235196507, 929.0, 18264.63470730578, 19805.10877010538, 20588.38234852957, 20591.794082058987, 20905.0, 885.5306687455659, 909.6134142989204, 924.3760461688333, 936.6347124621403, 1005.808117380906, 10286.2799604027, 10713.204982562927, 10773.51519181623, 10851.457427128613, 10866.836997445045, 562642.0, 591723.0, 601629.0, 612777.0, 622664.0, 101449.0, 135761.0, 137650.3117484406, 145525.0, 147263.26368368088, 860.8278344331159, 944.8204841080172, 969.9321047526605, 994.3039872089566, 1010.1867996263079, 17940.641187176443, 18718.53203669919, 18888.905555472684, 18962.620747584697, 19042.23831046798, 39847.0, 40742.59257407388, 45014.549854501034, 49396.0, 51445.97108057743, 50966.74516627393, 79105.60447197728, 79285.60357198177, 79611.80582291154, 80378.39243214986, 7267.873479610457, 7640.465167438226, 7647.694092236248, 7862.9606851967665, 8170.142135075732, 12320.137590368588, 12765.744685106432, 12881.742365152832, 13207.27359995171, 13257.138286011379, 9324.88101427833, 9972.551235194289, 10639.882812304597, 11027.0, 11092.445377731243, 9534.904650953678, 9597.856032159663, 10784.838227426751, 11151.275167114112, 11344.716382090513, 11295.661130166109, 11310.773784524428, 11355.261907975955, 11355.829662554903, 11483.885161148282, 7346.375558077623, 7679.347255483123, 7768.689252430066, 8033.6786528538205, 8147.714829980922, 9925.602975881173, 11123.165762567678, 11123.777524449304, 11295.717607059889, 11446.713832154262], "value": 9456.055280898798, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.6833399153172, 1584.1128967778043, 1807.737878007689, 1883.0867029490687, 1915.3870761356363, 8097.8785318220125, 8725.171108744667, 8890.511021893793, 9315.627374905012, 9341.859872101915, 9023.917129944399, 9108.817823643523, 9112.90887091129, 9113.635454581825, 9136.497492637902, 2073.295079672911, 2396.7243766966803, 2435.5372479228977, 2439.731629520751, 2440.6583078369044, 2397.6883005209384, 2428.1986944308337, 2446.2538925627678, 2447.412620970972, 2457.6682147910074, 2420.225527831095, 2428.4657375377387, 2432.4770174412497, 2444.792192663626, 2452.6934133233344, 2391.270662447953, 2406.2660888429023, 2411.9035238590436, 2416.2871952773976, 2417.069428270111, 2379.6787433696577, 2407.374082738483, 2408.421978725093, 2408.506256217471, 2420.201333559921, 9032.50321232332, 9053.411528250643, 9067.183953444155, 9067.773305667346, 9103.908960910372, 5324.254604355393, 5409.188621706736, 5434.673919564799, 5496.7526461309335, 5499.340079190482, 3516.7010804081683, 3649.233660931181, 3654.3604869147794, 3711.6288371162937, 3717.052151701326, 1070.7323169207698, 1109.057301293902, 1111.2221444988459, 1112.8497652816848, 1123.702218911989, 10332.328398654063, 11057.11543076552, 11382.60160894373, 11845.348505832177, 11998.58004969822, 4741.509799153335, 4756.6432517561325, 4800.279958006327, 4895.975520122377, 4944.90110197794, 10022.949885250528, 10129.898701013042, 10169.491525423698, 10181.490925453698, 10196.592136314614, 5424.077906755861, 5442.972785136049, 5454.154606036071, 5461.563074953994, 5478.671279723229, 4470.731756094645, 4895.73073480962, 4953.479884612064, 4967.975160124249, 5004.374453193351, 3962.821673024721, 4019.1157945252, 4068.572799856032, 4101.91796164081, 4145.2124096422185, 1313.9868601313865, 1332.367125615325, 1347.4677502386503, 1351.1555277951281, 1355.153029356652, 804.9597520123971, 822.7572866004543, 904.5838914099456, 941.5198248892976, 957.6600306891092, 4760.476347601761, 5351.170568561879, 5462.3445186577865, 5671.943280567141, 5857.974854400548, 899.9910000899907, 900.8603666431713, 982.3221976835961, 997.7156510394502, 1012.2711647613706, 3348.765586408928, 3659.432787917877, 3915.6280153385383, 3959.6238357356006, 3976.864061376194, 8949.642014319485, 10613.893861061291, 11028.779424411467, 11044.392558409203, 11169.44152792357, 9100.8634870477, 10768.407737574353, 10967.83548246777, 11006.449677516091, 11220.66338009861, 6373.47036711186, 7129.251428600026, 8378.287845533197, 8380.329573634095, 8395.748127556182, 4743.430788305425, 5884.176215329943, 5899.498542623754, 6076.754265375499, 6231.532635052298, 9831.213502919893, 9934.552945117342, 10069.194464442973, 10206.54070566841, 10378.688639340831, 10093.041161089684, 10380.896191037993, 10397.584096636352, 10446.843297350393, 10470.528826202992, 6960.79117626472, 7269.963650181715, 7402.888956665546, 7449.925500744923, 7606.9619651901385, 8389.412741108064, 8406.453580517247, 8491.36314776382, 8528.872066919126, 8592.183742544448, 7528.623568821427, 7678.1170165431695, 7684.46208765381, 7801.648925798239, 7810.531368117931, 7342.596157211405, 7441.851162976818, 7470.028896243453, 7490.438217133846, 7546.0, 5780.508656764222, 5958.463738263577, 6609.636469994197, 7074.045003924404, 7197.568145911262, 4658.184817656965, 4776.0, 5029.572486338702, 5077.441481437113, 5085.364329458817, 4977.875553111201, 4978.900421991612, 5059.949400505948, 5165.225216217447, 5209.817656382009], "value": 4636.588209421446, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.95258329792284, 305.3541759179335, 306.51723535431694, 310.7964283394376, 313.9058282515248, 4717.9764101179535, 4938.629602779793, 5228.424873263947, 5510.311211098613, 5656.943430565699, 4589.5869371756535, 4967.379077615298, 5048.772805223764, 5478.58910581706, 5592.384837667853, 4033.3748269018274, 4122.546519882825, 4181.560936101711, 4222.97888510557, 4253.319468884979, 4169.770662613555, 4195.62239398454, 4223.387608796723, 4224.423101907833, 4263.957360426387, 3444.603870554881, 4186.6022727840955, 4186.74879507229, 4208.389783481393, 4226.978865105671, 3775.735128731878, 4040.4545386372906, 4197.223513649985, 4225.661947044229, 4244.830206791725, 3068.7544996400457, 3322.936660268715, 3615.5480564929385, 3623.5108260384773, 3644.325799727059, 5180.378354597435, 5257.973710131425, 5337.839864804062, 5390.110631745796, 5390.434004429518, 4670.649701272384, 4808.158572249878, 4836.322914791932, 4843.2250839865455, 4870.951290487121, 5830.47525722687, 5837.956715149664, 5861.531077513831, 5864.472197502203, 5904.424071344699, 4032.9395059074136, 4034.536028356747, 4048.635622793933, 4059.269331520325, 4061.5329237137516, 5743.684097374643, 6104.755809767604, 6147.96926015376, 6200.627962322275, 6220.1961431185355, 3502.4571191465366, 3928.799872071649, 3973.4039894016128, 4002.8598999034893, 4019.251625542898, 4040.4343391925154, 4042.91914161721, 4043.8584649537715, 4050.311447053949, 4051.837926482967, 4018.8593399230886, 4538.341940418687, 4739.146953548326, 4757.833475828329, 4783.3497443381975, 5014.473480284591, 5709.371969083398, 5938.8812223755285, 5991.700414979234, 6016.0, 1116.6873275482878, 1121.7756448710127, 1356.6269275949194, 1379.9103058301177, 1383.688670049231, 755.2749360613763, 790.9243428936642, 833.3708050421893, 836.481381543447, 842.3345557009893, 5244.160934250502, 5546.694931778791, 5558.305211848519, 5592.86017849557, 5630.211770352154, 5510.862228444321, 5936.881262374728, 6155.753769849156, 6199.132121502993, 6205.348438413992, 5188.143956247252, 5513.228148059275, 5513.448655134534, 5524.552735048324, 5659.26429564154, 2785.470760555488, 2849.7292757188034, 3148.9055328340187, 3152.6374466936136, 3167.9862444017695, 4935.2973224237585, 5943.910841337383, 6011.226688127013, 6337.683115844189, 6347.398136351201, 6110.969445152746, 6168.8766224675255, 6181.783637572664, 6225.221847269091, 6258.561900667, 6241.095041219089, 6270.2789179244055, 6302.842428939313, 6322.083297921868, 6344.365563443804, 5594.804181853779, 5893.44012318829, 6032.638041717511, 6039.426254505991, 6066.545009124245, 5089.541941225308, 5946.851328716817, 5967.776605795714, 6007.158997740233, 6043.879122417615, 5931.288245410578, 6048.425399587032, 6325.810225693236, 6361.823062733409, 6376.171097757262, 3626.945595816012, 3691.870784522529, 3937.92124157521, 3947.9605203947594, 3952.3873799561115, 5259.342582177228, 5358.490943360374, 5817.214676018683, 6008.188894499187, 6058.969705151446, 5652.463016013472, 5790.94209057904, 5874.823755287348, 5907.822765317047, 5955.6724380157775, 5019.7741101651245, 5660.490555849993, 5790.855228619318, 5840.0, 5880.617759845596, 5160.406553246275, 5479.753411096431, 5756.453136951983, 5771.653700777967, 5796.536277097738, 4894.730789806595, 5121.3342265506735, 5416.510459623635, 5614.494695477427, 5904.586678932434, 4944.777485013255, 5438.510534051954, 5572.0, 5638.238837757014, 5664.26364572603], "value": 4246.499043728734, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9410150137238, 721.3796135323621, 742.6435311050695, 773.4431209529137, 776.2741836382986, 15402.922985385085, 15951.0, 16339.0, 16395.016299022067, 16442.424515141956, 13919.443222271122, 14005.369758360872, 14007.0195086344, 14014.92992535036, 14023.158610483353, 663.0419044480727, 825.6986200037003, 830.2569200565505, 858.7380848841101, 860.2129051917487, 800.9118996910336, 849.3332733803934, 851.0043249398219, 852.0414533649645, 852.3820230333021, 901.0673952459206, 909.2589539525299, 916.1159481100736, 916.8808054952847, 920.0477505781517, 904.9049849765779, 907.5734404829719, 914.049388635818, 916.0610374366295, 917.0737555069428, 671.3990978074596, 679.2053297641771, 680.0717021265986, 680.8910574308087, 682.3994884501657, 14882.479113231093, 15074.924625376803, 15084.39662413502, 15094.56601622844, 15126.092434453967, 14216.857831421761, 14351.497697580638, 14373.0, 14444.92777536116, 14454.349554270084, 11779.528818847235, 12005.0, 12068.577599784054, 12183.0, 12317.384130793514, 3608.6391360863704, 3616.3490571697084, 3783.9243215135816, 3785.2240290740606, 3796.7532110412735, 110362.44818775855, 110880.4455977723, 111305.53028106375, 111671.44164279208, 115218.42390788076, 10978.780424391467, 11033.6138235163, 11046.0, 11220.66338009861, 11357.488912998935, 22885.199018034287, 22927.0, 22936.0, 22988.080476780742, 23104.0, 8028.759137225776, 8114.026316842017, 8180.836383272302, 8284.875726864102, 8423.747287581382, 4452.30989196675, 4473.2842745160615, 4537.8184872604725, 4558.088382323548, 4608.377868987711, 3517.8742802303527, 3528.558930133733, 3625.8549658013385, 3626.5285512883156, 3637.654422829827, 642.7364780440091, 650.1288273713247, 655.6164643683403, 657.1030543308445, 667.0561155964256, 583.6556431705229, 643.2570381209711, 647.1942431672597, 674.5747893816481, 676.7969609117246, 7929.5242285463055, 9313.115254050854, 9381.530923453936, 9533.285003624756, 9586.66446674363, 602.4999250621973, 637.5154882289494, 658.6871236162784, 666.047552000634, 667.8564108716649, 4596.724196548218, 4652.3951886255245, 4683.81264749413, 4691.648126390534, 4694.88262793426, 75919.86120208201, 87617.56191219004, 124440.37779811044, 149325.01349973158, 162459.0, 98756.0, 113232.43383783194, 142727.0, 143125.0, 144838.03146703384, 108769.82460350907, 117500.23749643934, 118031.06580611679, 118166.0, 120260.81186923431, 9015.2787776979, 9027.684031058881, 9029.058180968617, 9052.864207036768, 9172.587233574639, 52898.0, 65122.88279640759, 67032.99450508344, 68115.3188468109, 69042.65478672575, 68520.3147968514, 75676.0, 87218.56390718005, 90336.74158146098, 91526.54236728774, 11078.723031924266, 13393.732125357634, 13527.256000920044, 13773.242471664154, 14015.929920350332, 14840.62898427548, 15057.0, 15179.772303415237, 15388.0, 15686.372545098524, 13505.32473376347, 13685.452581896612, 13762.65593360174, 13874.583762487142, 14319.498817541336, 13490.460381584626, 13730.588082357544, 13755.243461609707, 13788.103773254707, 13824.516141934984, 13352.0, 13468.057235993387, 13474.865251347754, 13503.32483375847, 13881.65295867571, 10706.304090234109, 11091.279066860629, 11224.326540407601, 11317.773644526898, 11814.409279536161, 10583.417912014605, 11343.886561134614, 11448.535071322358, 11550.0, 11582.94208528952], "value": 7841.707471514801, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 107.0, 117.0, 119.0, 119.0, 102.0, 117.0, 117.0, 120.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 140.0, 189.0, 110.0, 133.0, 132.0, 112.0, 165.0, 112.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 205.0, 253.0, 276.0, 260.0, 202.0, 202.0, 203.0, 196.0, 192.0], "value": 218.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 82.0, 75.0, 131.0, 66.0, 78.0, 80.0, 85.0, 129.0, 59.0], "value": 85.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 116.0, 71.0, 72.0, 90.0, 113.0, 70.0, 59.0, 136.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 79.0, 133.0, 127.0, 92.0, 88.0, 92.0, 89.0, 92.0, 88.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 102.0, 97.0, 117.0, 98.0, 148.0, 146.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 134.0, 91.0, 138.0, 85.0, 148.0, 148.0, 93.0, 90.0, 91.0], "value": 111.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 55.0, 56.0, 56.0, 56.0, 58.0, 55.0, 64.0, 51.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 71.0, 75.0, 76.0, 70.0, 70.0, 70.0, 73.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 83.0, 85.0, 88.0, 89.0, 84.0, 78.0, 75.0, 77.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 138.0, 140.0, 172.0, 153.0, 154.0, 174.0, 190.0, 165.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 171.0, 175.0, 175.0, 224.0, 183.0, 174.0, 167.0, 169.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 90.0, 89.0, 82.0, 83.0, 85.0, 84.0, 69.0, 66.0, 66.0], "value": 78.5, "unit": "score"}]}]} 19:14:19 INFO - Return code: 0 19:14:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:14:19 ERROR - # TBPL SUCCESS # 19:14:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:14:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:14:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:14:19 INFO - 2016-08-31 19:14:19.324 screenresolution[1672:10319] starting screenresolution argv=screenresolution get 19:14:19 INFO - 2016-08-31 19:14:19.326 screenresolution[1672:10319] Display 0: 1600x1200x32@60 19:14:19 INFO - 2016-08-31 19:14:19.332 screenresolution[1673:10322] starting screenresolution argv=screenresolution list 19:14:19 INFO - Available Modes on Display 0 19:14:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:14:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:14:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:14:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:14:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:14:19 INFO - Intel Iris: 19:14:19 INFO - Chipset Model: Intel Iris 19:14:19 INFO - Type: GPU 19:14:19 INFO - Bus: Built-In 19:14:19 INFO - VRAM (Dynamic, Max): 1536 MB 19:14:19 INFO - Vendor: Intel (0x8086) 19:14:19 INFO - Device ID: 0x0a2e 19:14:19 INFO - Revision ID: 0x0009 19:14:19 INFO - Displays: 19:14:19 INFO - Display: 19:14:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:14:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:14:19 INFO - Display Serial Number: 1600x1200 60 19:14:19 INFO - Main Display: Yes 19:14:19 INFO - Mirror: Off 19:14:19 INFO - Online: Yes 19:14:19 INFO - Rotation: Supported 19:14:19 INFO - Return code: 0 19:14:19 INFO - Running post-action listener: _resource_record_post_action 19:14:19 INFO - [mozharness: 2016-09-01 02:14:19.568504Z] Finished run-tests step (success) 19:14:19 INFO - Running post-run listener: _resource_record_post_run 19:14:19 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38142976; Write bytes: 705897472; Read time: 421; Write time: 2098 19:14:19 INFO - TinderboxPrint: CPU usage
54.8% 19:14:19 INFO - TinderboxPrint: I/O read bytes / time
38,142,976 / 421 19:14:19 INFO - TinderboxPrint: I/O write bytes / time
705,897,472 / 2,098 19:14:19 INFO - TinderboxPrint: CPU idle
2,189.8 (45.2%) 19:14:19 INFO - TinderboxPrint: CPU system
984.9 (20.3%) 19:14:19 INFO - TinderboxPrint: CPU user
1,670.9 (34.5%) 19:14:19 INFO - TinderboxPrint: Swap in / out
443,994,112 / 0 19:14:19 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 405156352; Write bytes: 399337472; Read time: 30993; Write time: 692 19:14:19 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 34415616; Write bytes: 295812096; Read time: 387; Write time: 1374 19:14:20 INFO - Running post-run listener: _upload_blobber_files 19:14:20 INFO - Blob upload gear active. 19:14:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:14:20 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:14:20 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:14:20 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:14:20 INFO - (blobuploader) - INFO - Open directory for files ... 19:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:21 INFO - (blobuploader) - INFO - Done attempting. 19:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:22 INFO - (blobuploader) - INFO - Done attempting. 19:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:25 INFO - (blobuploader) - INFO - Done attempting. 19:14:25 INFO - (blobuploader) - INFO - Iteration through files over. 19:14:25 INFO - Return code: 0 19:14:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:14:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:14:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73ba648ae3e3225ae27ba1b085789fb82880aa552d7a768551c57b762097565042c8f9b9403ffa5d09e1ea049d9f1d1a4fe00427c31743e6b6ae5fdf6c50329e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d07b18db4749172e53ff178ae10233c39427c6cfb0d42b63f79e1381d288a4cbb4385c169d18ed6a69b44a2fe860afd7ba385b57ab2a2fc7b1ac593d82064a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f8629c4e096a4080ca657b8344ca45c243247d5e431a24541e0345083b4ffbabd24c8388af92f0ca2b75269bc1e159c88b505e9e5cdee1acecf401ddf8798c9"} 19:14:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:14:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:14:25 INFO - Contents: 19:14:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73ba648ae3e3225ae27ba1b085789fb82880aa552d7a768551c57b762097565042c8f9b9403ffa5d09e1ea049d9f1d1a4fe00427c31743e6b6ae5fdf6c50329e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d07b18db4749172e53ff178ae10233c39427c6cfb0d42b63f79e1381d288a4cbb4385c169d18ed6a69b44a2fe860afd7ba385b57ab2a2fc7b1ac593d82064a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f8629c4e096a4080ca657b8344ca45c243247d5e431a24541e0345083b4ffbabd24c8388af92f0ca2b75269bc1e159c88b505e9e5cdee1acecf401ddf8798c9"} 19:14:25 INFO - Running post-run listener: copy_logs_to_upload_dir 19:14:25 INFO - Copying logs to upload dir... 19:14:25 INFO - mkdir: /builds/slave/test/build/upload/logs 19:14:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.515841 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-31 19:14:25.066273) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:25.069668) ========= 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.8zZGPJls3C/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.6NhPxmW3zo/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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/Autoland/sha512/73ba648ae3e3225ae27ba1b085789fb82880aa552d7a768551c57b762097565042c8f9b9403ffa5d09e1ea049d9f1d1a4fe00427c31743e6b6ae5fdf6c50329e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d07b18db4749172e53ff178ae10233c39427c6cfb0d42b63f79e1381d288a4cbb4385c169d18ed6a69b44a2fe860afd7ba385b57ab2a2fc7b1ac593d82064a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f8629c4e096a4080ca657b8344ca45c243247d5e431a24541e0345083b4ffbabd24c8388af92f0ca2b75269bc1e159c88b505e9e5cdee1acecf401ddf8798c9"} build_url:https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/SynLjU6YT5m_kX_3qDsFGg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73ba648ae3e3225ae27ba1b085789fb82880aa552d7a768551c57b762097565042c8f9b9403ffa5d09e1ea049d9f1d1a4fe00427c31743e6b6ae5fdf6c50329e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d07b18db4749172e53ff178ae10233c39427c6cfb0d42b63f79e1381d288a4cbb4385c169d18ed6a69b44a2fe860afd7ba385b57ab2a2fc7b1ac593d82064a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f8629c4e096a4080ca657b8344ca45c243247d5e431a24541e0345083b4ffbabd24c8388af92f0ca2b75269bc1e159c88b505e9e5cdee1acecf401ddf8798c9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:25.103614) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:25.103989) ========= 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.8zZGPJls3C/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.6NhPxmW3zo/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:14:25.153253) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 19:14:25.153604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 19:14:25.153959) ========= ========= Total master_lag: 0.17 =========