builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0268 starttime: 1473188224.8 results: success (0) buildid: 20160906094754 builduid: 91240e828c4b4db382a5c6bf86e9b40e revision: 236f750b6a3002e91e551fd6c2ddec63995a6e0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:04.806689) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:04.807166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:04.807495) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:04.882429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:04.882802) ========= 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.venuWayESr/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.EYqAz5TfW2/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-09-06 11:57:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2016-09-06 11:57:05 (15.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.509225 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:05.418607) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:05.418972) ========= 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.venuWayESr/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.EYqAz5TfW2/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.026868 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:05.477562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 11:57:05.477913) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 236f750b6a3002e91e551fd6c2ddec63995a6e0e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 236f750b6a3002e91e551fd6c2ddec63995a6e0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.venuWayESr/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.EYqAz5TfW2/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-09-06 11:57:05,555 truncating revision to first 12 chars 2016-09-06 11:57:05,555 Setting DEBUG logging. 2016-09-06 11:57:05,555 attempt 1/10 2016-09-06 11:57:05,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/236f750b6a30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 11:57:06,784 unpacking tar archive at: larch-236f750b6a30/testing/mozharness/ program finished with exit code 0 elapsedTime=1.495075 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 11:57:06.996641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.997027) ========= script_repo_revision: 236f750b6a3002e91e551fd6c2ddec63995a6e0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.997497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.997797) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:07.015936) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-06 11:57:07.016305) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.venuWayESr/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.EYqAz5TfW2/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:07 INFO - MultiFileLogger online at 20160906 11:57:07 in /builds/slave/test 11:57:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 11:57:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:57:07 INFO - {'append_to_log': False, 11:57:07 INFO - 'base_work_dir': '/builds/slave/test', 11:57:07 INFO - 'blob_upload_branch': 'Larch', 11:57:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:57:07 INFO - 'branch': 'Larch', 11:57:07 INFO - 'buildbot_json_path': 'buildprops.json', 11:57:07 INFO - 'config_files': ('talos/mac_config.py',), 11:57:07 INFO - 'default_actions': ('clobber', 11:57:07 INFO - 'read-buildbot-config', 11:57:07 INFO - 'download-and-extract', 11:57:07 INFO - 'populate-webroot', 11:57:07 INFO - 'create-virtualenv', 11:57:07 INFO - 'install', 11:57:07 INFO - 'run-tests'), 11:57:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:57:07 INFO - 'download_minidump_stackwalk': True, 11:57:07 INFO - 'download_symbols': 'ondemand', 11:57:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:57:07 INFO - 'tooltool.py': '/tools/tooltool.py', 11:57:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:57:07 INFO - '/tools/misc-python/virtualenv.py')}, 11:57:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:57:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:57:07 INFO - 'installer_path': 'installer.exe', 11:57:07 INFO - 'log_level': 'info', 11:57:07 INFO - 'log_name': 'talos', 11:57:07 INFO - 'log_to_console': True, 11:57:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:57:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:57:07 INFO - 'opt_config_files': (), 11:57:07 INFO - 'pip_index': False, 11:57:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:57:07 INFO - 'cmd': ('bash', 11:57:07 INFO - '-c', 11:57:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:57:07 INFO - 'enabled': True, 11:57:07 INFO - 'halt_on_failure': False, 11:57:07 INFO - 'name': 'check_screen_resolution'},), 11:57:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:57:07 INFO - 'cmd': ('bash', 11:57:07 INFO - '-c', 11:57:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:57:07 INFO - 'enabled': True, 11:57:07 INFO - 'halt_on_failure': False, 11:57:07 INFO - 'name': 'check_screen_resolution'},), 11:57:07 INFO - 'run_cmd_checks_enabled': True, 11:57:07 INFO - 'sps_profile': False, 11:57:07 INFO - 'sps_profile_interval': 0, 11:57:07 INFO - 'suite': 'dromaeojs-e10s', 11:57:07 INFO - 'system_bits': '32', 11:57:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:57:07 INFO - 'title': 't-yosemite-r7-0268', 11:57:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:57:07 INFO - 'use_talos_json': True, 11:57:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:57:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:57:07 INFO - 'work_dir': 'build'} 11:57:07 INFO - [mozharness: 2016-09-06 18:57:07.206344Z] Running clobber step. 11:57:07 INFO - Running pre-action listener: _resource_record_pre_action 11:57:07 INFO - Running main action method: clobber 11:57:07 INFO - rmtree: /builds/slave/test/build 11:57:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:57:09 INFO - Running post-action listener: _resource_record_post_action 11:57:09 INFO - [mozharness: 2016-09-06 18:57:09.263300Z] Finished clobber step (success) 11:57:09 INFO - [mozharness: 2016-09-06 18:57:09.263451Z] Running read-buildbot-config step. 11:57:09 INFO - Running pre-action listener: _resource_record_pre_action 11:57:09 INFO - Running main action method: read_buildbot_config 11:57:09 INFO - Using buildbot properties: 11:57:09 INFO - { 11:57:09 INFO - "project": "", 11:57:09 INFO - "product": "firefox", 11:57:09 INFO - "script_repo_revision": "production", 11:57:09 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 11:57:09 INFO - "repository": "", 11:57:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 11:57:09 INFO - "buildid": "20160906094754", 11:57:09 INFO - "pgo_build": "False", 11:57:09 INFO - "basedir": "/builds/slave/test", 11:57:09 INFO - "buildnumber": 0, 11:57:09 INFO - "slavename": "t-yosemite-r7-0268", 11:57:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:57:09 INFO - "platform": "yosemite_r7", 11:57:09 INFO - "scheduler": "tests-larch-macosx64-talos", 11:57:09 INFO - "branch": "larch", 11:57:09 INFO - "repo_path": "projects/larch", 11:57:09 INFO - "revision": "236f750b6a3002e91e551fd6c2ddec63995a6e0e", 11:57:09 INFO - "stage_platform": "macosx64", 11:57:09 INFO - "builduid": "91240e828c4b4db382a5c6bf86e9b40e", 11:57:09 INFO - "slavebuilddir": "test" 11:57:09 INFO - } 11:57:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:57:09 INFO - Running post-action listener: _resource_record_post_action 11:57:09 INFO - [mozharness: 2016-09-06 18:57:09.267695Z] Finished read-buildbot-config step (success) 11:57:09 INFO - [mozharness: 2016-09-06 18:57:09.267863Z] Running download-and-extract step. 11:57:09 INFO - Running pre-action listener: _resource_record_pre_action 11:57:09 INFO - Running main action method: download_and_extract 11:57:09 INFO - mkdir: /builds/slave/test/build/tests 11:57:09 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')]} 11:57:09 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:57:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:10 INFO - Downloaded 1199 bytes. 11:57:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:57:10 INFO - Using the following test package requirements: 11:57:10 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:57:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:57:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:57:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'jsshell-mac.zip'], 11:57:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:57:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:57:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:57:10 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:57:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:57:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:10 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:57:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:57:10 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')]} 11:57:10 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:57:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:11 INFO - Downloaded 16369902 bytes. 11:57:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:57:18 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 11:57:18 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')]} 11:57:18 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:57:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:19 INFO - Downloaded 16369902 bytes. 11:57:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:57:25 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')]} 11:57:25 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:57:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:26 INFO - Downloaded 14413998 bytes. 11:57:26 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:57:26 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')]} 11:57:26 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:57:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:57:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/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 11:57:30 INFO - Downloaded 111605229 bytes. 11:57:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:57:30 INFO - mkdir: /builds/slave/test/properties 11:57:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:30 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:30 INFO - Contents: 11:57:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:57:30 INFO - Running post-action listener: _resource_record_post_action 11:57:30 INFO - Running post-action listener: set_extra_try_arguments 11:57:30 INFO - [mozharness: 2016-09-06 18:57:30.959590Z] Finished download-and-extract step (success) 11:57:30 INFO - [mozharness: 2016-09-06 18:57:30.959748Z] Running populate-webroot step. 11:57:30 INFO - Running pre-action listener: _resource_record_pre_action 11:57:30 INFO - Running main action method: populate_webroot 11:57:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:30 INFO - '--tppagecycles', 11:57:30 INFO - '7'], 11:57:30 INFO - 'tests': ['tsvgm']}}, 11:57:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:57:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['damp', 'tps']}, 11:57:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'tests': ['damp', 'tps']}, 11:57:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['dromaeo_dom']}, 11:57:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['basic_compositor_video']}, 11:57:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:57:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['a11yr', 11:57:30 INFO - 'ts_paint', 11:57:30 INFO - 'tpaint', 11:57:30 INFO - 'sessionrestore', 11:57:30 INFO - 'sessionrestore_no_auto_restore', 11:57:30 INFO - 'tabpaint']}, 11:57:30 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:30 INFO - 'ts_paint', 11:57:30 INFO - 'tpaint', 11:57:30 INFO - 'sessionrestore', 11:57:30 INFO - 'sessionrestore_no_auto_restore', 11:57:30 INFO - 'tabpaint']}, 11:57:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:30 INFO - 'ts_paint', 11:57:30 INFO - 'tpaint', 11:57:30 INFO - 'sessionrestore', 11:57:30 INFO - 'sessionrestore_no_auto_restore', 11:57:30 INFO - 'tabpaint']}, 11:57:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:30 INFO - 'ts_paint', 11:57:30 INFO - 'tpaint', 11:57:30 INFO - 'sessionrestore', 11:57:30 INFO - 'sessionrestore_no_auto_restore', 11:57:30 INFO - 'tabpaint']}, 11:57:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['a11yr', 11:57:30 INFO - 'ts_paint', 11:57:30 INFO - 'tpaint', 11:57:30 INFO - 'sessionrestore', 11:57:30 INFO - 'sessionrestore_no_auto_restore', 11:57:30 INFO - 'tabpaint']}, 11:57:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['a11yr', 11:57:30 INFO - 'ts_paint', 11:57:30 INFO - 'tpaint', 11:57:30 INFO - 'sessionrestore', 11:57:30 INFO - 'sessionrestore_no_auto_restore', 11:57:30 INFO - 'tabpaint']}, 11:57:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['tsvgx', 11:57:30 INFO - 'tsvgr_opacity', 11:57:30 INFO - 'tart', 11:57:30 INFO - 'tscrollx', 11:57:30 INFO - 'cart']}, 11:57:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:30 INFO - 'tsvgr_opacity', 11:57:30 INFO - 'tart', 11:57:30 INFO - 'tscrollx', 11:57:30 INFO - 'cart']}, 11:57:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'talos_options': ['--disable-e10s'], 11:57:30 INFO - 'tests': ['tp5o']}, 11:57:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'tests': ['tp5o']}, 11:57:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'talos_options': ['--disable-e10s', 11:57:30 INFO - '--xperf_path', 11:57:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:30 INFO - 'tests': ['tp5n']}, 11:57:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:30 INFO - 'talos_options': ['--xperf_path', 11:57:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:30 INFO - 'tests': ['tp5n']}}, 11:57:30 INFO - 'talos.zip': {'path': '', 11:57:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:30 INFO - Running post-action listener: _resource_record_post_action 11:57:30 INFO - [mozharness: 2016-09-06 18:57:30.978264Z] Finished populate-webroot step (success) 11:57:30 INFO - [mozharness: 2016-09-06 18:57:30.978396Z] Running create-virtualenv step. 11:57:30 INFO - Running pre-action listener: _resource_record_pre_action 11:57:30 INFO - Running main action method: create_virtualenv 11:57:30 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:30 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 11:57:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:31 INFO - Using real prefix '/tools/python27' 11:57:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:31 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:34 INFO - Installing pip.................done. 11:57:34 INFO - Return code: 0 11:57:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:34 INFO - 'HOME': '/Users/cltbld', 11:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:34 INFO - 'LOGNAME': 'cltbld', 11:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:34 INFO - 'MOZ_NO_REMOTE': '1', 11:57:34 INFO - 'NO_EM_RESTART': '1', 11:57:34 INFO - 'PAGER': '/bin/cat', 11:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:34 INFO - 'PWD': '/builds/slave/test', 11:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:34 INFO - 'SHELL': '/bin/bash', 11:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:34 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:34 INFO - 'USER': 'cltbld', 11:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:34 INFO - 'XPC_FLAGS': '0x0', 11:57:34 INFO - 'XPC_SERVICE_NAME': '0', 11:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:34 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 11:57:34 INFO - Cleaning up... 11:57:34 INFO - Return code: 0 11:57:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:34 INFO - 'HOME': '/Users/cltbld', 11:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:34 INFO - 'LOGNAME': 'cltbld', 11:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:34 INFO - 'MOZ_NO_REMOTE': '1', 11:57:34 INFO - 'NO_EM_RESTART': '1', 11:57:34 INFO - 'PAGER': '/bin/cat', 11:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:34 INFO - 'PWD': '/builds/slave/test', 11:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:34 INFO - 'SHELL': '/bin/bash', 11:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:34 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:34 INFO - 'USER': 'cltbld', 11:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:34 INFO - 'XPC_FLAGS': '0x0', 11:57:34 INFO - 'XPC_SERVICE_NAME': '0', 11:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:35 INFO - Downloading/unpacking psutil>=3.1.1 11:57:35 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 11:57:35 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 11:57:35 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 11:57:35 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 11:57:35 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 11:57:35 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 11:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:38 INFO - Installing collected packages: psutil 11:57:38 INFO - Running setup.py install for psutil 11:57:38 INFO - building 'psutil._psutil_osx' extension 11:57:38 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 11:57:38 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 11:57:38 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 11:57:38 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 11:57:39 INFO - building 'psutil._psutil_posix' extension 11:57:39 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 11:57:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:57:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:57:39 INFO - ^ 11:57:39 INFO - 1 warning generated. 11:57:39 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 11:57:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:39 INFO - Successfully installed psutil 11:57:39 INFO - Cleaning up... 11:57:39 INFO - Return code: 0 11:57:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:57: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 mozsystemmonitor==0.3 11:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:39 INFO - 'HOME': '/Users/cltbld', 11:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:39 INFO - 'LOGNAME': 'cltbld', 11:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:39 INFO - 'MOZ_NO_REMOTE': '1', 11:57:39 INFO - 'NO_EM_RESTART': '1', 11:57:39 INFO - 'PAGER': '/bin/cat', 11:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:39 INFO - 'PWD': '/builds/slave/test', 11:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:39 INFO - 'SHELL': '/bin/bash', 11:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:39 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:39 INFO - 'USER': 'cltbld', 11:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:39 INFO - 'XPC_FLAGS': '0x0', 11:57:39 INFO - 'XPC_SERVICE_NAME': '0', 11:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:42 INFO - Installing collected packages: mozsystemmonitor 11:57:42 INFO - Running setup.py install for mozsystemmonitor 11:57:42 INFO - Successfully installed mozsystemmonitor 11:57:42 INFO - Cleaning up... 11:57:42 INFO - Return code: 0 11:57:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:57:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:42 INFO - 'HOME': '/Users/cltbld', 11:57:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:42 INFO - 'LOGNAME': 'cltbld', 11:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:42 INFO - 'MOZ_NO_REMOTE': '1', 11:57:42 INFO - 'NO_EM_RESTART': '1', 11:57:42 INFO - 'PAGER': '/bin/cat', 11:57:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:42 INFO - 'PWD': '/builds/slave/test', 11:57:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:42 INFO - 'SHELL': '/bin/bash', 11:57:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:42 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:42 INFO - 'USER': 'cltbld', 11:57:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:42 INFO - 'XPC_FLAGS': '0x0', 11:57:42 INFO - 'XPC_SERVICE_NAME': '0', 11:57:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:42 INFO - Downloading/unpacking jsonschema==2.5.1 11:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:57:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:45 INFO - Downloading functools32-3.2.3-2.tar.gz 11:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:57:45 INFO - warning: no files found matching '*.txt' 11:57:45 INFO - no previously-included directories found matching 'build' 11:57:45 INFO - no previously-included directories found matching 'dist' 11:57:45 INFO - no previously-included directories found matching '.git*' 11:57:45 INFO - Installing collected packages: jsonschema, functools32 11:57:45 INFO - Running setup.py install for functools32 11:57:45 INFO - warning: no files found matching '*.txt' 11:57:45 INFO - no previously-included directories found matching 'build' 11:57:45 INFO - no previously-included directories found matching 'dist' 11:57:45 INFO - no previously-included directories found matching '.git*' 11:57:45 INFO - Successfully installed jsonschema functools32 11:57:45 INFO - Cleaning up... 11:57:45 INFO - Return code: 0 11:57:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:45 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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:45 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 11:57:45 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 11:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:45 INFO - 'HOME': '/Users/cltbld', 11:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:45 INFO - 'LOGNAME': 'cltbld', 11:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:45 INFO - 'MOZ_NO_REMOTE': '1', 11:57:45 INFO - 'NO_EM_RESTART': '1', 11:57:45 INFO - 'PAGER': '/bin/cat', 11:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:45 INFO - 'PWD': '/builds/slave/test', 11:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:45 INFO - 'SHELL': '/bin/bash', 11:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:45 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:45 INFO - 'USER': 'cltbld', 11:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:45 INFO - 'XPC_FLAGS': '0x0', 11:57:45 INFO - 'XPC_SERVICE_NAME': '0', 11:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:57:46 INFO - Cleaning up... 11:57:46 INFO - Return code: 0 11:57:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:46 INFO - 'HOME': '/Users/cltbld', 11:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:46 INFO - 'LOGNAME': 'cltbld', 11:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:46 INFO - 'MOZ_NO_REMOTE': '1', 11:57:46 INFO - 'NO_EM_RESTART': '1', 11:57:46 INFO - 'PAGER': '/bin/cat', 11:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:46 INFO - 'PWD': '/builds/slave/test', 11:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:46 INFO - 'SHELL': '/bin/bash', 11:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:46 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:46 INFO - 'USER': 'cltbld', 11:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:46 INFO - 'XPC_FLAGS': '0x0', 11:57:46 INFO - 'XPC_SERVICE_NAME': '0', 11:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:46 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:46 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 11:57:46 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 11:57:46 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 11:57:46 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 11:57:46 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 11:57:46 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 11:57:48 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 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 11:57:49 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 11:57:50 INFO - Downloading docopt-0.6.1.tar.gz 11:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:50 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:50 INFO - Running setup.py install for blobuploader 11:57:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:50 INFO - Running setup.py install for requests 11:57:50 INFO - Running setup.py install for docopt 11:57:50 INFO - Successfully installed blobuploader requests docopt 11:57:50 INFO - Cleaning up... 11:57:51 INFO - Return code: 0 11:57:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:51 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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:51 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 11:57:51 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 11:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:51 INFO - 'HOME': '/Users/cltbld', 11:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:51 INFO - 'LOGNAME': 'cltbld', 11:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:51 INFO - 'MOZ_NO_REMOTE': '1', 11:57:51 INFO - 'NO_EM_RESTART': '1', 11:57:51 INFO - 'PAGER': '/bin/cat', 11:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:51 INFO - 'PWD': '/builds/slave/test', 11:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:51 INFO - 'SHELL': '/bin/bash', 11:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:51 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:51 INFO - 'USER': 'cltbld', 11:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:51 INFO - 'XPC_FLAGS': '0x0', 11:57:51 INFO - 'XPC_SERVICE_NAME': '0', 11:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-5qyxRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-8NY7vW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-2auqLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-KhyVzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-leeP_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-KI7kzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:51 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-0Ut1Er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-PmfCn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-e8Kvsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-3jNAj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-i9TNs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-pbQQ2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MHU0ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-xNNqtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-I1NCwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-eI3WO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:52 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-H2hZoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:53 INFO - Running setup.py install for manifestparser 11:57:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:53 INFO - Running setup.py install for mozcrash 11:57:53 INFO - Running setup.py install for mozdebug 11:57:53 INFO - Running setup.py install for mozdevice 11:57:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:57:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:53 INFO - Running setup.py install for mozfile 11:57:53 INFO - Running setup.py install for mozhttpd 11:57:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:53 INFO - Running setup.py install for mozinfo 11:57:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Running setup.py install for mozInstall 11:57:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Running setup.py install for mozleak 11:57:54 INFO - Running setup.py install for mozlog 11:57:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Running setup.py install for moznetwork 11:57:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:54 INFO - Running setup.py install for mozprocess 11:57:54 INFO - Running setup.py install for mozprofile 11:57:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:55 INFO - Running setup.py install for mozrunner 11:57:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:55 INFO - Running setup.py install for mozscreenshot 11:57:55 INFO - Running setup.py install for moztest 11:57:55 INFO - Running setup.py install for mozversion 11:57:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:55 INFO - Cleaning up... 11:57:55 INFO - Return code: 0 11:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:55 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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:57:55 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 11:57:55 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 11:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:55 INFO - 'HOME': '/Users/cltbld', 11:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:55 INFO - 'LOGNAME': 'cltbld', 11:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:55 INFO - 'MOZ_NO_REMOTE': '1', 11:57:55 INFO - 'NO_EM_RESTART': '1', 11:57:55 INFO - 'PAGER': '/bin/cat', 11:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:55 INFO - 'PWD': '/builds/slave/test', 11:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:55 INFO - 'SHELL': '/bin/bash', 11:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:57:55 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:55 INFO - 'USER': 'cltbld', 11:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:55 INFO - 'XPC_FLAGS': '0x0', 11:57:55 INFO - 'XPC_SERVICE_NAME': '0', 11:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:55 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-oK7Fgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-VEoLUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Ycdkkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-oYPPXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-iiis1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-hWKd2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ZbiW8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-oAMvGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-fB1wmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:56 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)) 11:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:56 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-NiX6Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Y0Czjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-q37QVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-069jyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-D2gEJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-RlBMBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ekPdg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:57 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)) 11:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:57 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-RFNL92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:57 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)) 11:57:57 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)) 11:57:57 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)) 11:57:57 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)) 11:57:57 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)) 11:57:57 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)) 11:57:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:00 INFO - Downloading blessings-1.6.tar.gz 11:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:58:00 INFO - Installing collected packages: blessings 11:58:00 INFO - Running setup.py install for blessings 11:58:00 INFO - Successfully installed blessings 11:58:00 INFO - Cleaning up... 11:58:00 INFO - Return code: 0 11:58:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:58:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:00 INFO - Reading from file tmpfile_stdout 11:58:00 INFO - Current package versions: 11:58:00 INFO - blessings == 1.6 11:58:00 INFO - blobuploader == 1.2.4 11:58:00 INFO - docopt == 0.6.1 11:58:00 INFO - functools32 == 3.2.3-2 11:58:00 INFO - jsonschema == 2.5.1 11:58:00 INFO - manifestparser == 1.1 11:58:00 INFO - mozInstall == 1.12 11:58:00 INFO - mozcrash == 0.17 11:58:00 INFO - mozdebug == 0.1 11:58:00 INFO - mozdevice == 0.48 11:58:00 INFO - mozfile == 1.2 11:58:00 INFO - mozhttpd == 0.7 11:58:00 INFO - mozinfo == 0.9 11:58:00 INFO - mozleak == 0.1 11:58:00 INFO - mozlog == 3.3 11:58:00 INFO - moznetwork == 0.27 11:58:00 INFO - mozprocess == 0.23 11:58:00 INFO - mozprofile == 0.28 11:58:00 INFO - mozrunner == 6.12 11:58:00 INFO - mozscreenshot == 0.1 11:58:00 INFO - mozsystemmonitor == 0.3 11:58:00 INFO - moztest == 0.7 11:58:00 INFO - mozversion == 1.4 11:58:00 INFO - psutil == 3.1.1 11:58:00 INFO - requests == 1.2.3 11:58:00 INFO - wsgiref == 0.1.2 11:58:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58: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')]} 11:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58: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')]} 11:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:00 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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:58:00 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 11:58:00 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 11:58:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:58:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:00 INFO - 'HOME': '/Users/cltbld', 11:58:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:00 INFO - 'LOGNAME': 'cltbld', 11:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:00 INFO - 'MOZ_NO_REMOTE': '1', 11:58:00 INFO - 'NO_EM_RESTART': '1', 11:58:00 INFO - 'PAGER': '/bin/cat', 11:58:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:00 INFO - 'PWD': '/builds/slave/test', 11:58:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:00 INFO - 'SHELL': '/bin/bash', 11:58:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:58:00 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:58:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:00 INFO - 'USER': 'cltbld', 11:58:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:00 INFO - 'XPC_FLAGS': '0x0', 11:58:00 INFO - 'XPC_SERVICE_NAME': '0', 11:58:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 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)) 11:58:01 INFO - Cleaning up... 11:58:01 INFO - Return code: 0 11:58:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:01 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 0x10ff1ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9a3db0360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, '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.venuWayESr/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.EYqAz5TfW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 11:58:01 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 11:58:01 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 11:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:01 INFO - 'HOME': '/Users/cltbld', 11:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:01 INFO - 'LOGNAME': 'cltbld', 11:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:01 INFO - 'MOZ_NO_REMOTE': '1', 11:58:01 INFO - 'NO_EM_RESTART': '1', 11:58:01 INFO - 'PAGER': '/bin/cat', 11:58:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:01 INFO - 'PWD': '/builds/slave/test', 11:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:01 INFO - 'SHELL': '/bin/bash', 11:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:58:01 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:01 INFO - 'USER': 'cltbld', 11:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:01 INFO - 'XPC_FLAGS': '0x0', 11:58:01 INFO - 'XPC_SERVICE_NAME': '0', 11:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:58:01 INFO - Cleaning up... 11:58:01 INFO - Return code: 0 11:58:01 INFO - Running post-action listener: _resource_record_post_action 11:58:01 INFO - Running post-action listener: _start_resource_monitoring 11:58:01 INFO - Starting resource monitoring. 11:58:01 INFO - [mozharness: 2016-09-06 18:58:01.363247Z] Finished create-virtualenv step (success) 11:58:01 INFO - [mozharness: 2016-09-06 18:58:01.364261Z] Running install step. 11:58:01 INFO - Running pre-action listener: _resource_record_pre_action 11:58:01 INFO - Running main action method: install 11:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:01 INFO - Reading from file tmpfile_stdout 11:58:01 INFO - Detecting whether we're running mozinstall >=1.0... 11:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:58:01 INFO - Reading from file tmpfile_stdout 11:58:01 INFO - Output received: 11:58:01 INFO - Usage: mozinstall [options] installer 11:58:01 INFO - Options: 11:58:01 INFO - -h, --help show this help message and exit 11:58:01 INFO - -d DEST, --destination=DEST 11:58:01 INFO - Directory to install application into. [default: 11:58:01 INFO - "/builds/slave/test"] 11:58:01 INFO - --app=APP Application being installed. [default: firefox] 11:58:01 INFO - mkdir: /builds/slave/test/build/application 11:58:01 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'] 11:58:01 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 11:58:36 INFO - Reading from file tmpfile_stdout 11:58:36 INFO - Output received: 11:58:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:58:36 INFO - Running post-action listener: _resource_record_post_action 11:58:36 INFO - [mozharness: 2016-09-06 18:58:36.041378Z] Finished install step (success) 11:58:36 INFO - [mozharness: 2016-09-06 18:58:36.041513Z] Running run-tests step. 11:58:36 INFO - Running pre-action listener: _resource_record_pre_action 11:58:36 INFO - Running main action method: run_tests 11:58:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:58:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:58:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:58:36 INFO - 2016-09-06 11:58:36.055 screenresolution[1649:7857] starting screenresolution argv=screenresolution get 11:58:36 INFO - 2016-09-06 11:58:36.058 screenresolution[1649:7857] Display 0: 1600x1200x32@60 11:58:36 INFO - 2016-09-06 11:58:36.064 screenresolution[1650:7859] starting screenresolution argv=screenresolution list 11:58:36 INFO - Available Modes on Display 0 11:58:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:58:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:58:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:58:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:58:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:58:36 INFO - Intel Iris: 11:58:36 INFO - Chipset Model: Intel Iris 11:58:36 INFO - Type: GPU 11:58:36 INFO - Bus: Built-In 11:58:36 INFO - VRAM (Dynamic, Max): 1536 MB 11:58:36 INFO - Vendor: Intel (0x8086) 11:58:36 INFO - Device ID: 0x0a2e 11:58:36 INFO - Revision ID: 0x0009 11:58:36 INFO - Displays: 11:58:36 INFO - Display: 11:58:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:58:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:58:36 INFO - Display Serial Number: 1600x1200 60 11:58:36 INFO - Main Display: Yes 11:58:36 INFO - Mirror: Off 11:58:36 INFO - Online: Yes 11:58:36 INFO - Rotation: Supported 11:58:36 INFO - Return code: 0 11:58:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:58:36 INFO - Python 2.7.3 11:58:36 INFO - Return code: 0 11:58:36 INFO - grabbing minidump binary from tooltool 11:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:36 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 0x10fe7fd30>, '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 0x10fbe4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:58:36 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 11:58:36 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 11:58:36 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 11:58:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:58:36 INFO - Return code: 0 11:58:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:58:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:58:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:58:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:58:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:58:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 11:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0268 --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 11:58:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.venuWayESr/Render', 11:58:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:36 INFO - 'HOME': '/Users/cltbld', 11:58:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:36 INFO - 'LOGNAME': 'cltbld', 11:58:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:58:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:36 INFO - 'MOZ_NO_REMOTE': '1', 11:58:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:58:36 INFO - 'NO_EM_RESTART': '1', 11:58:36 INFO - 'PAGER': '/bin/cat', 11:58:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:36 INFO - 'PWD': '/builds/slave/test', 11:58:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:58:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:36 INFO - 'SHELL': '/bin/bash', 11:58:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EYqAz5TfW2/Listeners', 11:58:36 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 11:58:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:36 INFO - 'USER': 'cltbld', 11:58:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:36 INFO - 'XPC_FLAGS': '0x0', 11:58:36 INFO - 'XPC_SERVICE_NAME': '0', 11:58:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 11:58:36 INFO - mozversion application_buildid: 20160906094754 11:58:36 INFO - mozversion application_changeset: 236f750b6a3002e91e551fd6c2ddec63995a6e0e 11:58:36 INFO - mozversion application_display_name: Nightly 11:58:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:58:36 INFO - mozversion application_name: Firefox 11:58:36 INFO - mozversion application_remotingname: firefox 11:58:36 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 11:58:36 INFO - mozversion application_vendor: Mozilla 11:58:36 INFO - mozversion application_version: 51.0a1 11:58:36 INFO - mozversion platform_buildid: 20160906094754 11:58:36 INFO - mozversion platform_changeset: 236f750b6a3002e91e551fd6c2ddec63995a6e0e 11:58:36 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 11:58:36 INFO - mozversion platform_version: 51.0a1 11:58:36 INFO - using testdate: 1473188316 11:58:36 INFO - actual date: 1473188316 11:58:36 INFO - starting webserver on 'localhost:49278' 11:58:36 INFO - SUITE-START | Running 2 tests 11:58:36 INFO - TEST-START | dromaeo_css 11:58:36 INFO - operating with platform_type : mac_ 11:58:36 INFO - Initialising browser for dromaeo_css test... 11:58:36 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpo9wMt9/profile http://localhost:49278/getInfo.html) 11:58:39 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 11:58:39 INFO - PROCESS | 1660 | colorDepth:24 11:58:39 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 11:58:39 INFO - PROCESS | 1660 | __metrics 11:58:39 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 11:58:40 INFO - TEST-INFO | 1660: exit 0 11:58:40 INFO - Browser initialized. 11:58:40 INFO - Running cycle 1/1 for dromaeo_css test... 11:58:40 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpo9wMt9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:52 INFO - PROCESS | 1664 | RSS: Main: 249671680 11:58:52 INFO - PROCESS | 1664 | 11:59:42 INFO - PROCESS | 1664 | 1473188382037 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] 12:01:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 12:01:55 INFO - PROCESS | 1664 | RSS: Main: 268460032 12:01:55 INFO - PROCESS | 1664 | 12:04:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 12:04:58 INFO - PROCESS | 1664 | RSS: Main: 273883136 12:04:58 INFO - PROCESS | 1664 | 12:08:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 12:08:00 INFO - PROCESS | 1664 | RSS: Main: 277139456 12:08:00 INFO - PROCESS | 1664 | 12:11:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 12:11:03 INFO - PROCESS | 1664 | RSS: Main: 272404480 12:11:03 INFO - PROCESS | 1664 | 12:14:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 12:14:06 INFO - PROCESS | 1664 | RSS: Main: 277794816 12:14:06 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 12:17:09 INFO - PROCESS | 1664 | RSS: Main: 280449024 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | __start_tp_report 12:17:09 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 12:17:09 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 12:17:09 INFO - PROCESS | 1664 | |i|pagename|runs| 12:17:09 INFO - PROCESS | 1664 | |0;dojo.html;3358.328334333133;3365.7475689323305;3366.6465021172776;3368.0737797105808;3374.932501349973;5730.398308177644;5768.480836724694;5796.8260952171395;5798.739056742446;5814.1278808178795;5573.247611572437;5688.402717714643;5739.737257803276;5753.395893431192;5852.853678658025;5112.4631913649;5170.405403378603;5240.6593571417925;5252.606054545905;5316.043112239795;5268.420473747904;5406.972675191701;5442.047641662713;5446.046941785192;5488.7530061147345;5149.716765577892;5167.044096842096;5293.788248470076;5315.654482458666;5346.358436987567;5189.532942035197;5205.8958820823755;5252.973735131338;5282.550983166436;5308.654937429054;5215.191645294985;5229.660072095302;5231.24146998683;5238.423773384925;5271.920921186185;4543.681942264043;4818;5754.676424422406;5809.593328467009;5840.62035967665;6194.318624951252;6195.504359651217;6277.529185311074;6308.3060863305;6314.5895516791725;6236.594621349643;6267.655278959655;6285.214348206474;6317.3366796486625;6321.841953951142;5734.856628584319;5810.302763668303;5876.41235876409;5877.23595932526;5906.5570082244;6317.715702793385;6328.557001009977;6331.651759153198;6343.968280158662;6350.936490635035;6213.65824879636;6230.283517395467;6235.781747638902;6238.189035425366;6252.781152659635;6108.0992441897915;6197.4732147767045;6265.060240963892;6276;6294.339094395114;6120.477608373095;6124.899720462962;6136.12084543884;6139.553468857682;6218.657973811484;6240.251169859646;6243.125962365272;6264.310925798159;6300.810975670645;6311.842203944846;4928.310036594879;5044.54599086084;5044.621653376012;5047.394312682502;5061.013102445061;4388.034632380871;4449.755263460476;4457.68796184264;4479.641628669699;4499.055198408371;5578.386377498472;5661;5665.951798917214;5691.9715401423555;5699.658020518699;6135.110179431545;6289.119523266746;6297.4647154992335;6300.779472718433;6322.620642761357;5585.609007369445;5651.745671444794;5665.801696940587;5691.288588926384;5765.1928729977835;5425.593080518976;5438.428964958702;5457.69982650958;5461.1808228766;5462.918056229159;6201.720922558406;6269.937300626935;6332.4934005279465;6349.714262858182;6352.396522330371;6194.318624951342;6216.813495595139;6228.813135605939;6300.180976473029;6311.558190926775;6270.529710271839;6311.905321420274;6313.210848643919;6314.905276420949;6362.840928976628;6085.087236914321;6248.0940263660605;6248.125262463168;6260.311365749672;6286.622802631857;6190.473809726043;6211.472024877936;6251.156093927261;6330.841728956813;6354.983202687733;6131.601445906002;6149.354317796567;6159.784407545714;6162;6182.814515564541;5864.912026319694;6127.571070025234;6151.569390142826;6152.753889844356;6163.660998645118;5459.181122831607;5809.24479817621;5878.970605146947;5944.494717949023;5989.8203053908455;5305.1776974569;6131.141640170291;6133.172021777182;6138.202033735586;6166.889959807098;5773.942260577341;5896.528277737685;5945.910811337747;5950.404959504015;5958.702064896825 12:17:09 INFO - PROCESS | 1664 | |1;ext.html;503.3808415648754;506.3821091612299;526.5603221310206;538.5556915544677;541.1098742568478;37178.44232336514;39474.60525394749;39481.40777888334;39494.40758388619;39557.60442395575;39321.60678393222;39947.40078898819;39948;40024;40044.79977600115;9626;9819.950900245525;9837.90162098377;9847.55685994132;9858.852117218212;7570.583617901013;9082;9505.952470237638;9865.457399843042;10211.438370889598;9830.410175389463;9833.754156146082;9882.85175722361;9887.653932112378;9900.405975641484;9831.606735730562;9885.604575816995;9898.80202395948;9927.453990030546;9927.50362481873;9712.417254964654;9783.657571984946;9815.263855210887;9818.410895346302;9837.360571562825;38638.80680596607;38734.80632596847;38967.61032389668;39019.024524386834;39040;2761.62718033067;3393.253484233465;3412.812295323756;3825.1393436476847;4038.2125485530332;26325.868370658216;29447.70552294471;29551.40897182066;30217.546736798966;30935;3203.19920019995;3227.225026235561;3232.628247751515;3239.9194753941574;3328.6338502764675;445465;449978;451516.7424162891;453478.46521534695;461007;32498.67501325004;55651.72174139184;55883.72058139683;55929.44070559324;56088.43911560914;33135.00594982154;33938.830305848314;34110.829445852614;34271.4859277111;34294;14109.435622574982;14217.360218789972;14221.360038798279;14255.429782808571;14255.50105746294;11935;12012.798720127917;12029.278243305429;12043.75912481763;12064.215825971283;7678.462507624525;7708.691652333956;7720.613969301625;7731.961340193263;7769.378449724121;3232.434323993292;3249.333886553251;3250.723688486458;3265.4448743713624;3265.918352041218;959.9568019439142;979.1677074486629;995.9601615935281;1007.8185926533148;1073.4471747050275;26161.73838261631;28886.711132888824;29693.85153074221;29899.70100299013;29922;923.6443969071821;989.4557993103908;1014.1177175857051;1056.2447849787286;1080.3409919948824;15240.923795381173;15385.769213461806;15394.131056120528;15622.218889055503;15659.217039148225;471370.5725885463;479997;496003;499076;508980;54412.45587544074;54482.45517544853;54493;54626.72686636621;54719.90560188774;1307.7972914198315;1351.8580549042406;1357.9388927498287;1361.5574938144944;1363.079921053285;26163.607545886447;28929;29153.833846645895;29722.702772972858;30693;46163.538364615924;46482.30276545922;46519;46640.30039549342;46693.76653116713;541454;544244;544549;545452;548057;22823.20118795834;23531;24006.87996560006;25905.74094259109;25998.480030399664;41131.97170070772;42507.937301567705;44448.77775611101;45830.85422864322;46217.84455388508;37399;37797.43303850389;38158.809205953796;38461.230775383774;39007.804960975016;36962.07594810151;37148.62851371452;37186.44220336643;38303.808480957414;38316.808415958854;32546.02361929146;35641.643583564866;36443.81778091199;36911.78529288144;37863;32626.83686581552;33663.3267334647;34060.82969585136;34339.4849077269;34371.48442773311;22690.68153238359;23877;25278;25296.16308149134;25455.74544254608 12:17:09 INFO - PROCESS | 1664 | |2;jquery.html;352.6880006376281;374.3916136278549;377.8645171260362;378.79545045675326;387.370522900287;15834.920825395871;20140.295089671847;20955.37133885982;21208.36374908755;21394.786052139472;18648.44054678362;19123.278904898558;20100.59798804023;20707;21110.05004774785;5641.943580564182;5742.454466825645;5870.295564532252;5991.2510936132985;6071.848203794896;5946.494547963428;6020.548458865581;6035.758569657209;6038.6074905131245;6046;5807.0998995155815;5857;5915.970420147914;5961;6020.789272375468;5870.178175055496;5967.999999999978;6007.459328660441;6013.428724271187;6046.274447066358;4986.775595098229;5143.177091665353;5183.429822719498;5186.740662966837;5204.479552044803;25458;25574.616380754305;25651.871740641363;25754.74245257561;25806.87096564524;2340.4265954841003;2364.3261670424017;2472.8269021168526;2489.054159419427;2941.3970136122043;15112.924435377863;15151.545453636409;15368.231588420535;15464.149471779048;15605.765913511306;1822.070743920597;1839.9593775202318;1859.8605104617209;1885.2176346816054;1899.7720273567122;90384.54807725985;109906;111759;112637.43681281542;113311;8788.472691638523;8900.643974241088;8906.93116825972;9815.950920245354;10170.898291016994;11506.137039722054;13082.803757943639;13119.737605247841;13180.934095329654;13189.73620527584;8104.1085480597085;8377.287930525852;8390.244877961013;8503.107173746792;8545.444546104483;8462.830743385099;8499.787505312417;8514.57427128641;8580.95709521461;8676.392652514263;5237.921431178535;6408.166938297992;6428.260750013715;6436.710348034256;6497.545171837926;1573.4886161997397;1592.5222433269973;1826.3060037185785;1864.1518109260253;1865.225931238548;877.2981614708208;880.423322723617;916.244098618629;977.2865807960229;992.6098025161217;20375.388738337875;21659.025343859565;21773.129074837147;21777.4339264767;21820.908954552207;928.4800511713462;974.6539978307599;980.2794945714894;999.1107913956585;1005.5223768709805;10795.784084318428;11592.6522204334;11595.76808463826;11604.071674266039;11627.59303424393;450375;517431;542023;547716;552308;110884;113290.43354783338;113507;113598.43200783944;118782;985.9852102218473;1007.9748006299901;1019.2966852871494;1026.7381817636604;1027.7995790820867;14876.925615371854;17781.73327400116;19352.935588542765;19419.902900485406;19449.805501945364;48893;49461.75269123631;50911.49088509067;51439;52275.2158717612;68147;69598;72645.63677181791;73796.2620373811;79653;5258.737063146904;5967.880642387041;7358.484906056525;7360.742374016883;7368.410527157922;9208.171264586219;12531.624051278477;12860.678483037998;13142.73714525685;13266.734665306447;8920.910790892009;9428.764280893032;11058.778824423627;11180.770115287474;11466.082713382742;8616.870746938926;9572.32993690435;11094;11216.719582010188;11604.95555400018;11470.885291146982;11596.47815848272;11666.941665291619;11783.88216117862;11890.583829565923;7963.92036079655;8161.877571836546;8195.83608327842;8266.380021498291;8384.53885036329;10959.06847917936;11119.888801111885;11278.887211128113;11392.316461012366;11487.942560287145 12:17:09 INFO - PROCESS | 1664 | |3;mootools.html;1297.1374036265881;1503.669192777589;1631.3311542267666;1806.6296409236109;1883.576195356045;7966.800829979239;8655.350848686357;8678.73963781086;9051.909480905197;9072.501012444322;8814.55927203639;8922.241609463188;8955.238804701592;8962.55187240637;8985.191332780048;2375.2874137758645;2423.903043878243;2425.6604075429454;2431.051889762994;2446.9755302447015;2065.7521097468248;2343.7226711442304;2416.9274921752376;2420.7700268474555;2420.878956052199;2282.6804247405375;2401.5557121932497;2406.1458182345277;2411.493586346869;2421;2328.93013209604;2335.5095429959815;2340.9180678676335;2350.4241460842118;2385.3678775124604;2011.718359429681;2367.5856725073045;2386.1886958434216;2389.032441861049;2392.6530653055383;8921.15249051339;8946.731598052069;8963.193312601898;8975.775605609846;8985.820283594358;5250.7112108834;5354.785808567653;5428.565714742811;5440.4287549807495;5445.9652665993335;3612.530371051773;3629.7277704172293;3638.6179451157514;3674.2259594006623;3703.222323312081;1105.5190991918541;1106.983395249072;1107.4518113533773;1110.134095405585;1119.6808909460765;11579.305241685526;11663.825042624367;11669.708257293636;11706.882931170665;11727.296362218294;4558.749268790249;4639.559241872017;4835.758212089381;4840.83057093;4843.854684359475;10085.29402941787;10113.544890479947;10136.54385552652;10140.594376224868;10154.339967902211;4628.328892310664;5320.14877619579;5363.249145119687;5386.272853164851;5405.78376864921;4394.582514661102;4660.440747110369;4867.634927380461;4896.608271338285;4905.288733133676;3864.632859878307;3906.199229158016;4005.939910901339;4044.69664775143;4066.1054567994993;1271.0467149637773;1299.389287035092;1302.1340808362334;1309.561296965508;1311.6196303072193;781.6638845296463;876.1588874680251;918.0452329577298;925.746002800158;930.9860352094724;5569;5769.798057067983;5935.851603709942;5936.465718085393;5982;433.4430257119586;441.3247730971619;445.7815670321519;448.9955100449019;450.3672340361797;3828.3729414998515;3906.0430194601936;4003.979980100081;4005.499312585927;4021.698372622065;10240.231982601335;10686.25196236256;10689.35863848156;10698.358098514114;10723.731906702395;10182.389056656477;10425.895741042492;10602.41686707223;10677.359358438518;10721.142308615292;7116.573005619679;8091.312238459679;8124.918750812534;8149.470284431493;8152.6331315090965;4723.858284251478;5323.707196104251;5771.9134212986;5783.566232532661;5841.18223448726;7870.858725484887;9739.123478886933;9787.412755234709;9788.510574471391;9825.705228843146;9886.703398898044;9984.650537231162;10003.799924001625;10094.545745441326;10113.949430252802;6971.651417429007;7346.485745997729;7502.399808015237;7556.697732090654;7655.50239234448;8454.957725211334;8470.576471176293;8501.659933602587;8508.021577518417;8520.275776559061;6464.095026696173;7400.481966262525;7532.623368831646;7559.924400756142;7659.617019148909;7136.500444968802;7150.320719531636;7156.677949492181;7166.713331466892;7309.926900730925;6981.930180698128;7015;7068.434525238072;7111.608861512666;7116.43068554504;4959.851204463872;4990.301357809982;5005.449400565862;5048.3941926969;5060.49395060491;4831.299461578122;4929.200841692828;4931.186354251508;5030.949690503195;5147.819826306061 12:17:09 INFO - PROCESS | 1664 | |4;prototype.html;261.8491728851698;304.7090029022284;310.3111093372712;312.5046800820699;314.7371944426404;4923.458419573849;5273.235380869772;5501.386841250404;5584.5811564132755;5655.321361436629;5625.80309689161;5628.380878103416;5663.971680141593;5698.857528561778;5741.971290143554;3607;3967.325554655701;4309.849155279566;4331.263685173512;4337;3977.9005524861964;4221.303484924999;4273.1667324871505;4381.277089280265;4385.802638881258;4186.727862688916;4208.179405016008;4209.095723950464;4215.135897141094;4222.894427639303;4175.603317684815;4222.894427639303;4231.984323762305;4239.554846741079;4284.871453856358;2998.865051072707;3212;3336.816475093869;3640.6541378568995;3644.234710710754;4606.286025666027;5561.63849349791;5579.8605034874045;5585.2459917910965;5590.832275031756;4410.9198990933255;4546.340780586829;4867;4876.463589005207;4916.2301571434255;4699.506551812045;4877.707337559722;4885.389326334208;5641.492265696066;5941.405859414067;3129.04564107947;3859.9421008684894;3977.781222032787;3990.541087774885;4009.83960641574;5148.433672296044;5518.696471694096;6119.55217912834;6387.297397286295;6431.710573024178;3454.3954807908517;3910.608939106067;3967.849323696096;3992.720509564361;4079.938800917989;3603.1892824114366;4116.156187981456;4133.359329303963;4137.338025915899;4147.52303485103;4840.201366774513;4848.10310092634;4849.733264670477;4860.2223644217465;4863.148948933923;5086.3896332440345;5729.5702822288495;5775.537956963432;5987.221661184019;6104;1283.3583208395803;1360.0411709744606;1384.702289007868;1393.540131756518;1395.6092294157652;753.8605358008786;812.4840726138945;816.0003995106066;823.2714048067503;848.108717559845;3984.601539845992;4566.49768525462;4597.448306203277;4601.930971035437;4607.746573938399;4783.378160839069;4823.1800593899125;4958.355413796184;5165.405978312542;5170.508801663836;4383.430154079949;4487.5991344630465;4519.977400112978;4561.338605902126;4643.3963584733765;2937.206954122375;3008.473517134493;3145.5596216529702;3251.040942921844;3281.556989806393;6026.246719160105;6486.902696459557;6498.902516462163;6513.772017979348;6527.281998980108;5831.912521312183;5864.416607515946;6087.726052327655;6385.584936979081;6387.329330420239;6174.5060395168275;6463.15799997008;6464.838379040468;6469.967650161813;6536.365908522869;5336.386315573759;5853.385394533512;6117.969410153099;6213.937860621336;6243.937560624518;5386.946130538645;5970.895384353908;6093.817185484442;6114.327423983447;6184.288806787097;6015.518758499397;6426.775062872777;6463.3213512580505;6469.923947912785;6523.804285871432;3209.5667084943234;3255.1373885919775;3715.461258117612;4028.939565906572;4032.6572241359813;5776.277965254343;6115.908261376172;6117.625897483143;6118.082287656887;6132.846678833065;5865.882682346414;5941.771835561664;5943.474023540065;5963.048291095762;5974.37269086757;5877.764889404376;5946.643201407929;5954.19618351517;5974.3271883872585;5997.760089596542;4769.594584460221;4985.052839960396;5529.313559364272;5799.420057994167;5841.561882858718;5301;5903.291605007426;5956.404359564009;5978.491828194652;5980.671063091571;4748.847500462038;4762.285657151455;5149.897002060013;5771.14328407441;5835.56233282514 12:17:09 INFO - PROCESS | 1664 | |5;yui.html;715.9785206443806;756.474250395975;757.776455945496;786.5673879366348;843.3885433061032;15004.324805383756;15433.305501252442;15940.884138110338;15945.282462289197;16290.83709162908;14343.64140896478;14632.561023169323;14678.266086695648;14679.339429725662;14693.118412895234;682.2870100744709;692.1970514203525;755.9168491465934;849.5157760076759;855.5337341149074;855.482433127958;858.7466697324304;877.6182360673096;879.5778026547262;880.6169316347377;881.7002219245455;920.5443305563755;920.7974245665943;922.8338898998178;929.4609126706528;894.6913314906315;904.4889637354906;913.7121806630923;918.967836125739;920.2362039507193;671.6540981394586;681.495693187043;682.3347236444476;684.288340126268;689.0972825598482;15207.163606001666;15210.467633632881;15321.387144514207;15345.46290879814;15359.078455292718;12155.209911355734;13940.93029534856;13943.16341019542;13994.790078148835;14186.858131418658;11134;11538.769224615544;11555.19113662044;11767.823482647682;12125.636230913087;4543.386642803212;4572.634189264851;4580.862574122748;4621.65337599678;4647.907041859177;99210.99999999927;99889.00110998869;102312.48843755807;102343.48828255886;102468.48765756123;9983.900160998443;10540.314879532805;10613.363198208132;10628.043476087189;10687.14502839771;19998.70001949972;20041.098150583257;20054.699179512318;20093.296734614218;20124.094415751326;7696.576688282086;7753.1859154787935;7793;7819.569923654253;7861.685057152466;4341.891452713708;4367.388565600819;4378.408914796461;4415.227335216325;4580.931286030712;3398.5242066110764;3494.1264683829045;3510.4558793387064;3516.5780106387397;3523.383407903658;630.7634637011121;633.4552285034866;636.688022868791;644.0950464597248;652.5758257132807;582.5863636817822;608.6104892868568;610.2646311194999;629.1600713048132;633.2938773267865;8862.77843053916;9012.459252444874;9187.448753074837;9207.90792092084;9254.583543740548;579.5334755521761;612.1980205930246;626.8432891777056;630.1933525087895;634.5589815078476;4233.394787981476;4293.463317085364;4466.441694788152;4549.7042692224895;4567.634589232854;91665.5416722912;138683;139251.30374348065;152096;161907;107979.38030929542;126314;138573.6142638581;142644;142963.2851835755;100327.49836250771;106214;113128.43435782769;115932.42033789946;123917;7376.4919927225;9545.713628591153;9819.705408837746;9848.965858585032;10014.341772404063;45228.321575177055;54003.72998134984;57913;57989;58444;86452;91376.54311728399;91938;92292.53853730689;96466;12656.367181641066;12924.483020679067;13003.544875929298;13059.02057345722;13478.393472293967;14572.927135364256;14648.633784155481;14962.625934351292;15240.771388429404;15458.995165314218;13486.527971520949;13581.66045848822;13730.794038089236;13793.310334483436;13891.235982021086;13615.931920340336;13807.309634518435;13854.30728463593;14307.785383219469;14324.414665661981;13426.395812188279;13566.728665426834;13583.728325433238;13599.896992987804;14078.788818167532;10824.24230303871;10939.8090825577;11277.892885357896;11301.484512798897;11678.24091434088;10429.374237545771;10474.68575942723;11427.942860285646;11706.824397633873;11780.882191177978 12:17:09 INFO - PROCESS | 1664 | __end_tp_report 12:17:09 INFO - PROCESS | 1664 | __start_cc_report 12:17:09 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,379 12:17:09 INFO - PROCESS | 1664 | __end_cc_report 12:17:09 INFO - PROCESS | 1664 | __startTimestamp1473189429565__endTimestamp 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | ------- Summary: start ------- 12:17:09 INFO - PROCESS | 1664 | Number of tests: 6 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5758.44 Median:5944.49 stddev:644.85 (10.8%) stddev-sans-first:618.73 12:17:09 INFO - PROCESS | 1664 | Values: 3358.3 3365.7 3366.6 3368.1 3374.9 5730.4 5768.5 5796.8 5798.7 5814.1 5573.2 5688.4 5739.7 5753.4 5852.9 5112.5 5170.4 5240.7 5252.6 5316.0 5268.4 5407.0 5442.0 5446.0 5488.8 5149.7 5167.0 5293.8 5315.7 5346.4 5189.5 5205.9 5253.0 5282.6 5308.7 5215.2 5229.7 5231.2 5238.4 5271.9 4543.7 4818.0 5754.7 5809.6 5840.6 6194.3 6195.5 6277.5 6308.3 6314.6 6236.6 6267.7 6285.2 6317.3 6321.8 5734.9 5810.3 5876.4 5877.2 5906.6 6317.7 6328.6 6331.7 6344.0 6350.9 6213.7 6230.3 6235.8 6238.2 6252.8 6108.1 6197.5 6265.1 6276.0 6294.3 6120.5 6124.9 6136.1 6139.6 6218.7 6240.3 6243.1 6264.3 6300.8 6311.8 4928.3 5044.5 5044.6 5047.4 5061.0 4388.0 4449.8 4457.7 4479.6 4499.1 5578.4 5661.0 5666.0 5692.0 5699.7 6135.1 6289.1 6297.5 6300.8 6322.6 5585.6 5651.7 5665.8 5691.3 5765.2 5425.6 5438.4 5457.7 5461.2 5462.9 6201.7 6269.9 6332.5 6349.7 6352.4 6194.3 6216.8 6228.8 6300.2 6311.6 6270.5 6311.9 6313.2 6314.9 6362.8 6085.1 6248.1 6248.1 6260.3 6286.6 6190.5 6211.5 6251.2 6330.8 6355.0 6131.6 6149.4 6159.8 6162.0 6182.8 5864.9 6127.6 6151.6 6152.8 6163.7 5459.2 5809.2 5879.0 5944.5 5989.8 5305.2 6131.1 6133.2 6138.2 6166.9 5773.9 5896.5 5945.9 5950.4 5958.7 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:61932.61 Median:38550.02 stddev:132696.93 (344.2%) stddev-sans-first:132989.03 12:17:09 INFO - PROCESS | 1664 | Values: 503.4 506.4 526.6 538.6 541.1 37178.4 39474.6 39481.4 39494.4 39557.6 39321.6 39947.4 39948.0 40024.0 40044.8 9626.0 9820.0 9837.9 9847.6 9858.9 7570.6 9082.0 9506.0 9865.5 10211.4 9830.4 9833.8 9882.9 9887.7 9900.4 9831.6 9885.6 9898.8 9927.5 9927.5 9712.4 9783.7 9815.3 9818.4 9837.4 38638.8 38734.8 38967.6 39019.0 39040.0 2761.6 3393.3 3412.8 3825.1 4038.2 26325.9 29447.7 29551.4 30217.5 30935.0 3203.2 3227.2 3232.6 3239.9 3328.6 445465.0 449978.0 451516.7 453478.5 461007.0 32498.7 55651.7 55883.7 55929.4 56088.4 33135.0 33938.8 34110.8 34271.5 34294.0 14109.4 14217.4 14221.4 14255.4 14255.5 11935.0 12012.8 12029.3 12043.8 12064.2 7678.5 7708.7 7720.6 7732.0 7769.4 3232.4 3249.3 3250.7 3265.4 3265.9 960.0 979.2 996.0 1007.8 1073.4 26161.7 28886.7 29693.9 29899.7 29922.0 923.6 989.5 1014.1 1056.2 1080.3 15240.9 15385.8 15394.1 15622.2 15659.2 471370.6 479997.0 496003.0 499076.0 508980.0 54412.5 54482.5 54493.0 54626.7 54719.9 1307.8 1351.9 1357.9 1361.6 1363.1 26163.6 28929.0 29153.8 29722.7 30693.0 46163.5 46482.3 46519.0 46640.3 46693.8 541454.0 544244.0 544549.0 545452.0 548057.0 22823.2 23531.0 24006.9 25905.7 25998.5 41132.0 42507.9 44448.8 45830.9 46217.8 37399.0 37797.4 38158.8 38461.2 39007.8 36962.1 37148.6 37186.4 38303.8 38316.8 32546.0 35641.6 36443.8 36911.8 37863.0 32626.8 33663.3 34060.8 34339.5 34371.5 22690.7 23877.0 25278.0 25296.2 25455.7 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:32036.23 Median:363.54 stddev:87396.46 (24040.4%) stddev-sans-first:87609.25 12:17:09 INFO - PROCESS | 1664 | Values: 352.7 374.4 377.9 378.8 387.4 15834.9 20140.3 20955.4 21208.4 21394.8 18648.4 19123.3 20100.6 20707.0 21110.1 5641.9 5742.5 5870.3 5991.3 6071.8 5946.5 6020.5 6035.8 6038.6 6046.0 5807.1 5857.0 5916.0 5961.0 6020.8 5870.2 5968.0 6007.5 6013.4 6046.3 4986.8 5143.2 5183.4 5186.7 5204.5 25458.0 25574.6 25651.9 25754.7 25806.9 2340.4 2364.3 2472.8 2489.1 2941.4 15112.9 15151.5 15368.2 15464.1 15605.8 1822.1 1840.0 1859.9 1885.2 1899.8 90384.5 109906.0 111759.0 112637.4 113311.0 8788.5 8900.6 8906.9 9816.0 10170.9 11506.1 13082.8 13119.7 13180.9 13189.7 8104.1 8377.3 8390.2 8503.1 8545.4 8462.8 8499.8 8514.6 8581.0 8676.4 5237.9 6408.2 6428.3 6436.7 6497.5 1573.5 1592.5 1826.3 1864.2 1865.2 877.3 880.4 916.2 977.3 992.6 20375.4 21659.0 21773.1 21777.4 21820.9 928.5 974.7 980.3 999.1 1005.5 10795.8 11592.7 11595.8 11604.1 11627.6 450375.0 517431.0 542023.0 547716.0 552308.0 110884.0 113290.4 113507.0 113598.4 118782.0 986.0 1008.0 1019.3 1026.7 1027.8 14876.9 17781.7 19352.9 19419.9 19449.8 48893.0 49461.8 50911.5 51439.0 52275.2 68147.0 69598.0 72645.6 73796.3 79653.0 5258.7 5967.9 7358.5 7360.7 7368.4 9208.2 12531.6 12860.7 13142.7 13266.7 8920.9 9428.8 11058.8 11180.8 11466.1 8616.9 9572.3 11094.0 11216.7 11605.0 11470.9 11596.5 11666.9 11783.9 11890.6 7963.9 8161.9 8195.8 8266.4 8384.5 10959.1 11119.9 11278.9 11392.3 11487.9 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5679.35 Median:4838.29 stddev:3197.37 (66.1%) stddev-sans-first:3189.38 12:17:09 INFO - PROCESS | 1664 | Values: 1297.1 1503.7 1631.3 1806.6 1883.6 7966.8 8655.4 8678.7 9051.9 9072.5 8814.6 8922.2 8955.2 8962.6 8985.2 2375.3 2423.9 2425.7 2431.1 2447.0 2065.8 2343.7 2416.9 2420.8 2420.9 2282.7 2401.6 2406.1 2411.5 2421.0 2328.9 2335.5 2340.9 2350.4 2385.4 2011.7 2367.6 2386.2 2389.0 2392.7 8921.2 8946.7 8963.2 8975.8 8985.8 5250.7 5354.8 5428.6 5440.4 5446.0 3612.5 3629.7 3638.6 3674.2 3703.2 1105.5 1107.0 1107.5 1110.1 1119.7 11579.3 11663.8 11669.7 11706.9 11727.3 4558.7 4639.6 4835.8 4840.8 4843.9 10085.3 10113.5 10136.5 10140.6 10154.3 4628.3 5320.1 5363.2 5386.3 5405.8 4394.6 4660.4 4867.6 4896.6 4905.3 3864.6 3906.2 4005.9 4044.7 4066.1 1271.0 1299.4 1302.1 1309.6 1311.6 781.7 876.2 918.0 925.7 931.0 5569.0 5769.8 5935.9 5936.5 5982.0 433.4 441.3 445.8 449.0 450.4 3828.4 3906.0 4004.0 4005.5 4021.7 10240.2 10686.3 10689.4 10698.4 10723.7 10182.4 10425.9 10602.4 10677.4 10721.1 7116.6 8091.3 8124.9 8149.5 8152.6 4723.9 5323.7 5771.9 5783.6 5841.2 7870.9 9739.1 9787.4 9788.5 9825.7 9886.7 9984.7 10003.8 10094.5 10113.9 6971.7 7346.5 7502.4 7556.7 7655.5 8455.0 8470.6 8501.7 8508.0 8520.3 6464.1 7400.5 7532.6 7559.9 7659.6 7136.5 7150.3 7156.7 7166.7 7309.9 6981.9 7015.0 7068.4 7111.6 7116.4 4959.9 4990.3 5005.4 5048.4 5060.5 4831.3 4929.2 4931.2 5030.9 5147.8 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4711.15 Median:4971.70 stddev:1513.24 (30.4%) stddev-sans-first:1480.18 12:17:09 INFO - PROCESS | 1664 | Values: 261.8 304.7 310.3 312.5 314.7 4923.5 5273.2 5501.4 5584.6 5655.3 5625.8 5628.4 5664.0 5698.9 5742.0 3607.0 3967.3 4309.8 4331.3 4337.0 3977.9 4221.3 4273.2 4381.3 4385.8 4186.7 4208.2 4209.1 4215.1 4222.9 4175.6 4222.9 4232.0 4239.6 4284.9 2998.9 3212.0 3336.8 3640.7 3644.2 4606.3 5561.6 5579.9 5585.2 5590.8 4410.9 4546.3 4867.0 4876.5 4916.2 4699.5 4877.7 4885.4 5641.5 5941.4 3129.0 3859.9 3977.8 3990.5 4009.8 5148.4 5518.7 6119.6 6387.3 6431.7 3454.4 3910.6 3967.8 3992.7 4079.9 3603.2 4116.2 4133.4 4137.3 4147.5 4840.2 4848.1 4849.7 4860.2 4863.1 5086.4 5729.6 5775.5 5987.2 6104.0 1283.4 1360.0 1384.7 1393.5 1395.6 753.9 812.5 816.0 823.3 848.1 3984.6 4566.5 4597.4 4601.9 4607.7 4783.4 4823.2 4958.4 5165.4 5170.5 4383.4 4487.6 4520.0 4561.3 4643.4 2937.2 3008.5 3145.6 3251.0 3281.6 6026.2 6486.9 6498.9 6513.8 6527.3 5831.9 5864.4 6087.7 6385.6 6387.3 6174.5 6463.2 6464.8 6470.0 6536.4 5336.4 5853.4 6118.0 6213.9 6243.9 5386.9 5970.9 6093.8 6114.3 6184.3 6015.5 6426.8 6463.3 6469.9 6523.8 3209.6 3255.1 3715.5 4028.9 4032.7 5776.3 6115.9 6117.6 6118.1 6132.8 5865.9 5941.8 5943.5 5963.0 5974.4 5877.8 5946.6 5954.2 5974.3 5997.8 4769.6 4985.1 5529.3 5799.4 5841.6 5301.0 5903.3 5956.4 5978.5 5980.7 4748.8 4762.3 5149.9 5771.1 5835.6 12:17:09 INFO - PROCESS | 1664 | 12:17:09 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:24195.55 Median:3519.98 stddev:38495.43 (1093.6%) stddev-sans-first:38563.05 12:17:09 INFO - PROCESS | 1664 | Values: 716.0 756.5 757.8 786.6 843.4 15004.3 15433.3 15940.9 15945.3 16290.8 14343.6 14632.6 14678.3 14679.3 14693.1 682.3 692.2 755.9 849.5 855.5 855.5 858.7 877.6 879.6 880.6 881.7 920.5 920.8 922.8 929.5 894.7 904.5 913.7 919.0 920.2 671.7 681.5 682.3 684.3 689.1 15207.2 15210.5 15321.4 15345.5 15359.1 12155.2 13940.9 13943.2 13994.8 14186.9 11134.0 11538.8 11555.2 11767.8 12125.6 4543.4 4572.6 4580.9 4621.7 4647.9 99211.0 99889.0 102312.5 102343.5 102468.5 9983.9 10540.3 10613.4 10628.0 10687.1 19998.7 20041.1 20054.7 20093.3 20124.1 7696.6 7753.2 7793.0 7819.6 7861.7 4341.9 4367.4 4378.4 4415.2 4580.9 3398.5 3494.1 3510.5 3516.6 3523.4 630.8 633.5 636.7 644.1 652.6 582.6 608.6 610.3 629.2 633.3 8862.8 9012.5 9187.4 9207.9 9254.6 579.5 612.2 626.8 630.2 634.6 4233.4 4293.5 4466.4 4549.7 4567.6 91665.5 138683.0 139251.3 152096.0 161907.0 107979.4 126314.0 138573.6 142644.0 142963.3 100327.5 106214.0 113128.4 115932.4 123917.0 7376.5 9545.7 9819.7 9849.0 10014.3 45228.3 54003.7 57913.0 57989.0 58444.0 86452.0 91376.5 91938.0 92292.5 96466.0 12656.4 12924.5 13003.5 13059.0 13478.4 14572.9 14648.6 14962.6 15240.8 15459.0 13486.5 13581.7 13730.8 13793.3 13891.2 13615.9 13807.3 13854.3 14307.8 14324.4 13426.4 13566.7 13583.7 13599.9 14078.8 10824.2 10939.8 11277.9 11301.5 11678.2 10429.4 10474.7 11427.9 11706.8 11780.9 12:17:09 INFO - PROCESS | 1664 | -------- Summary: end -------- 12:17:09 INFO - PROCESS | 1664 | 12:17:10 INFO - TEST-INFO | 1664: exit 0 12:17:10 INFO - TEST-OK | dromaeo_css | took 1113493ms 12:17:10 INFO - TEST-START | kraken 12:17:10 INFO - operating with platform_type : mac_ 12:17:10 INFO - Initialising browser for kraken test... 12:17:10 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpeNTzEj/profile http://localhost:49278/getInfo.html) 12:17:12 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 12:17:12 INFO - PROCESS | 1672 | colorDepth:24 12:17:12 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 12:17:12 INFO - PROCESS | 1672 | __metrics 12:17:13 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 12:17:13 INFO - TEST-INFO | 1672: exit 0 12:17:13 INFO - Browser initialized. 12:17:13 INFO - Running cycle 1/1 for kraken test... 12:17:13 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpeNTzEj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:25 INFO - PROCESS | 1674 | RSS: Main: 249671680 12:17:25 INFO - PROCESS | 1674 | 12:17:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 12:17:29 INFO - PROCESS | 1674 | RSS: Main: 261042176 12:17:29 INFO - PROCESS | 1674 | 12:17:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 12:17:32 INFO - PROCESS | 1674 | RSS: Main: 269676544 12:17:32 INFO - PROCESS | 1674 | 12:17:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 12:17:36 INFO - PROCESS | 1674 | RSS: Main: 265113600 12:17:36 INFO - PROCESS | 1674 | 12:17:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 12:17:39 INFO - PROCESS | 1674 | RSS: Main: 266342400 12:17:39 INFO - PROCESS | 1674 | 12:17:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:41 INFO - PROCESS | 1674 | RSS: Main: 266661888 12:17:41 INFO - PROCESS | 1674 | 12:17:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:45 INFO - PROCESS | 1674 | RSS: Main: 266850304 12:17:45 INFO - PROCESS | 1674 | 12:17:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:49 INFO - PROCESS | 1674 | RSS: Main: 268029952 12:17:49 INFO - PROCESS | 1674 | 12:17:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 12:17:52 INFO - PROCESS | 1674 | RSS: Main: 268390400 12:17:52 INFO - PROCESS | 1674 | 12:17:54 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:54 INFO - PROCESS | 1674 | RSS: Main: 268226560 12:17:54 INFO - PROCESS | 1674 | 12:17:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:57 INFO - PROCESS | 1674 | RSS: Main: 268492800 12:17:57 INFO - PROCESS | 1674 | 12:17:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:18:00 INFO - PROCESS | 1674 | RSS: Main: 268308480 12:18:00 INFO - PROCESS | 1674 | 12:18:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:18:04 INFO - PROCESS | 1674 | RSS: Main: 268636160 12:18:04 INFO - PROCESS | 1674 | 12:18:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:18:07 INFO - PROCESS | 1674 | RSS: Main: 268664832 12:18:07 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 12:18:09 INFO - PROCESS | 1674 | RSS: Main: 270417920 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | __start_tp_report 12:18:09 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 12:18:09 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 12:18:09 INFO - PROCESS | 1674 | |i|pagename|runs| 12:18:09 INFO - PROCESS | 1674 | |0;ai-astar;131;160;152;116;116;100;115;118;116;114 12:18:09 INFO - PROCESS | 1674 | |1;audio-beat-detection;142;111;134;157;113;133;128;110;157;111 12:18:09 INFO - PROCESS | 1674 | |2;audio-dft;195;200;200;217;208;195;195;202;201;198 12:18:09 INFO - PROCESS | 1674 | |3;audio-fft;69;82;74;149;69;112;85;63;127;59 12:18:09 INFO - PROCESS | 1674 | |4;audio-oscillator;68;73;121;73;101;115;138;75;71;109 12:18:09 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;138;132;89;93;89;85;89;85;88;86 12:18:09 INFO - PROCESS | 1674 | |6;imaging-darkroom;100;99;126;95;147;143;175;100;100;99 12:18:09 INFO - PROCESS | 1674 | |7;imaging-desaturate;92;95;91;90;95;90;97;93;92;92 12:18:09 INFO - PROCESS | 1674 | |8;json-parse-financial;57;59;57;60;62;75;88;87;86;88 12:18:09 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;68;73;113;72;79;66;101;62;92;108 12:18:09 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;100;88;89;88;88;88;85;78;96;98 12:18:09 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;157;162;154;159;141;141;162;163;191;146 12:18:09 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;152;203;161;207;196;236;242;182;171;177 12:18:09 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;68;70;68;70;73;69;68;91;90;91 12:18:09 INFO - PROCESS | 1674 | __end_tp_report 12:18:09 INFO - PROCESS | 1674 | __start_cc_report 12:18:09 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,974 12:18:09 INFO - PROCESS | 1674 | __end_cc_report 12:18:09 INFO - PROCESS | 1674 | __startTimestamp1473189489952__endTimestamp 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | ------- Summary: start ------- 12:18:09 INFO - PROCESS | 1674 | Number of tests: 14 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:123.80 Median:117.00 stddev:18.60 (15.9%) stddev-sans-first:19.54 12:18:09 INFO - PROCESS | 1674 | Values: 131.0 160.0 152.0 116.0 116.0 100.0 115.0 118.0 116.0 114.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:133.50 stddev:18.38 (13.8%) stddev-sans-first:18.94 12:18:09 INFO - PROCESS | 1674 | Values: 142.0 111.0 134.0 157.0 113.0 133.0 128.0 110.0 157.0 111.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:201.10 Median:200.50 stddev:6.87 (3.4%) stddev-sans-first:6.92 12:18:09 INFO - PROCESS | 1674 | Values: 195.0 200.0 200.0 217.0 208.0 195.0 195.0 202.0 201.0 198.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:88.90 Median:69.00 stddev:30.26 (43.8%) stddev-sans-first:31.22 12:18:09 INFO - PROCESS | 1674 | Values: 69.0 82.0 74.0 149.0 69.0 112.0 85.0 63.0 127.0 59.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:69.50 stddev:25.44 (36.6%) stddev-sans-first:25.13 12:18:09 INFO - PROCESS | 1674 | Values: 68.0 73.0 121.0 73.0 101.0 115.0 138.0 75.0 71.0 109.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:88.50 stddev:20.01 (22.6%) stddev-sans-first:14.88 12:18:09 INFO - PROCESS | 1674 | Values: 138.0 132.0 89.0 93.0 89.0 85.0 89.0 85.0 88.0 86.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:118.40 Median:161.00 stddev:27.89 (17.3%) stddev-sans-first:28.78 12:18:09 INFO - PROCESS | 1674 | Values: 100.0 99.0 126.0 95.0 147.0 143.0 175.0 100.0 100.0 99.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:92.50 stddev:2.31 (2.5%) stddev-sans-first:2.44 12:18:09 INFO - PROCESS | 1674 | Values: 92.0 95.0 91.0 90.0 95.0 90.0 97.0 93.0 92.0 92.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:71.90 Median:80.50 stddev:14.16 (17.6%) stddev-sans-first:13.96 12:18:09 INFO - PROCESS | 1674 | Values: 57.0 59.0 57.0 60.0 62.0 75.0 88.0 87.0 86.0 88.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:70.00 stddev:18.62 (26.6%) stddev-sans-first:18.90 12:18:09 INFO - PROCESS | 1674 | Values: 68.0 73.0 113.0 72.0 79.0 66.0 101.0 62.0 92.0 108.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:88.00 stddev:6.55 (7.4%) stddev-sans-first:5.81 12:18:09 INFO - PROCESS | 1674 | Values: 100.0 88.0 89.0 88.0 88.0 88.0 85.0 78.0 96.0 98.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:157.60 Median:160.50 stddev:14.47 (9.0%) stddev-sans-first:15.35 12:18:09 INFO - PROCESS | 1674 | Values: 157.0 162.0 154.0 159.0 141.0 141.0 162.0 163.0 191.0 146.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.70 Median:199.50 stddev:30.04 (15.1%) stddev-sans-first:28.02 12:18:09 INFO - PROCESS | 1674 | Values: 152.0 203.0 161.0 207.0 196.0 236.0 242.0 182.0 171.0 177.0 12:18:09 INFO - PROCESS | 1674 | 12:18:09 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:71.50 stddev:10.37 (14.5%) stddev-sans-first:10.61 12:18:09 INFO - PROCESS | 1674 | Values: 68.0 70.0 68.0 70.0 73.0 69.0 68.0 91.0 90.0 91.0 12:18:09 INFO - PROCESS | 1674 | -------- Summary: end -------- 12:18:09 INFO - PROCESS | 1674 | 12:18:10 INFO - TEST-INFO | 1674: exit 0 12:18:10 INFO - TEST-OK | kraken | took 60622ms 12:18:10 INFO - SUITE-END | took 1174s 12:18:11 INFO - Completed test suite (00:19:34) 12:18:11 INFO - javascript benchmark 12:18:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7241.373175171779, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3358.328334333133, 3365.7475689323305, 3366.6465021172776, 3368.0737797105808, 3374.932501349973, 5730.398308177644, 5768.480836724694, 5796.8260952171395, 5798.739056742446, 5814.1278808178795, 5573.247611572437, 5688.402717714643, 5739.737257803276, 5753.395893431192, 5852.853678658025, 5112.4631913649, 5170.405403378603, 5240.6593571417925, 5252.606054545905, 5316.043112239795, 5268.420473747904, 5406.972675191701, 5442.047641662713, 5446.046941785192, 5488.7530061147345, 5149.716765577892, 5167.044096842096, 5293.788248470076, 5315.654482458666, 5346.358436987567, 5189.532942035197, 5205.8958820823755, 5252.973735131338, 5282.550983166436, 5308.654937429054, 5215.191645294985, 5229.660072095302, 5231.24146998683, 5238.423773384925, 5271.920921186185, 4543.681942264043, 4818.0, 5754.676424422406, 5809.593328467009, 5840.62035967665, 6194.318624951252, 6195.504359651217, 6277.529185311074, 6308.3060863305, 6314.5895516791725, 6236.594621349643, 6267.655278959655, 6285.214348206474, 6317.3366796486625, 6321.841953951142, 5734.856628584319, 5810.302763668303, 5876.41235876409, 5877.23595932526, 5906.5570082244, 6317.715702793385, 6328.557001009977, 6331.651759153198, 6343.968280158662, 6350.936490635035, 6213.65824879636, 6230.283517395467, 6235.781747638902, 6238.189035425366, 6252.781152659635, 6108.0992441897915, 6197.4732147767045, 6265.060240963892, 6276.0, 6294.339094395114, 6120.477608373095, 6124.899720462962, 6136.12084543884, 6139.553468857682, 6218.657973811484, 6240.251169859646, 6243.125962365272, 6264.310925798159, 6300.810975670645, 6311.842203944846, 4928.310036594879, 5044.54599086084, 5044.621653376012, 5047.394312682502, 5061.013102445061, 4388.034632380871, 4449.755263460476, 4457.68796184264, 4479.641628669699, 4499.055198408371, 5578.386377498472, 5661.0, 5665.951798917214, 5691.9715401423555, 5699.658020518699, 6135.110179431545, 6289.119523266746, 6297.4647154992335, 6300.779472718433, 6322.620642761357, 5585.609007369445, 5651.745671444794, 5665.801696940587, 5691.288588926384, 5765.1928729977835, 5425.593080518976, 5438.428964958702, 5457.69982650958, 5461.1808228766, 5462.918056229159, 6201.720922558406, 6269.937300626935, 6332.4934005279465, 6349.714262858182, 6352.396522330371, 6194.318624951342, 6216.813495595139, 6228.813135605939, 6300.180976473029, 6311.558190926775, 6270.529710271839, 6311.905321420274, 6313.210848643919, 6314.905276420949, 6362.840928976628, 6085.087236914321, 6248.0940263660605, 6248.125262463168, 6260.311365749672, 6286.622802631857, 6190.473809726043, 6211.472024877936, 6251.156093927261, 6330.841728956813, 6354.983202687733, 6131.601445906002, 6149.354317796567, 6159.784407545714, 6162.0, 6182.814515564541, 5864.912026319694, 6127.571070025234, 6151.569390142826, 6152.753889844356, 6163.660998645118, 5459.181122831607, 5809.24479817621, 5878.970605146947, 5944.494717949023, 5989.8203053908455, 5305.1776974569, 6131.141640170291, 6133.172021777182, 6138.202033735586, 6166.889959807098, 5773.942260577341, 5896.528277737685, 5945.910811337747, 5950.404959504015, 5958.702064896825], "value": 5717.359873219912, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.3808415648754, 506.3821091612299, 526.5603221310206, 538.5556915544677, 541.1098742568478, 37178.44232336514, 39474.60525394749, 39481.40777888334, 39494.40758388619, 39557.60442395575, 39321.60678393222, 39947.40078898819, 39948.0, 40024.0, 40044.79977600115, 9626.0, 9819.950900245525, 9837.90162098377, 9847.55685994132, 9858.852117218212, 7570.583617901013, 9082.0, 9505.952470237638, 9865.457399843042, 10211.438370889598, 9830.410175389463, 9833.754156146082, 9882.85175722361, 9887.653932112378, 9900.405975641484, 9831.606735730562, 9885.604575816995, 9898.80202395948, 9927.453990030546, 9927.50362481873, 9712.417254964654, 9783.657571984946, 9815.263855210887, 9818.410895346302, 9837.360571562825, 38638.80680596607, 38734.80632596847, 38967.61032389668, 39019.024524386834, 39040.0, 2761.62718033067, 3393.253484233465, 3412.812295323756, 3825.1393436476847, 4038.2125485530332, 26325.868370658216, 29447.70552294471, 29551.40897182066, 30217.546736798966, 30935.0, 3203.19920019995, 3227.225026235561, 3232.628247751515, 3239.9194753941574, 3328.6338502764675, 445465.0, 449978.0, 451516.7424162891, 453478.46521534695, 461007.0, 32498.67501325004, 55651.72174139184, 55883.72058139683, 55929.44070559324, 56088.43911560914, 33135.00594982154, 33938.830305848314, 34110.829445852614, 34271.4859277111, 34294.0, 14109.435622574982, 14217.360218789972, 14221.360038798279, 14255.429782808571, 14255.50105746294, 11935.0, 12012.798720127917, 12029.278243305429, 12043.75912481763, 12064.215825971283, 7678.462507624525, 7708.691652333956, 7720.613969301625, 7731.961340193263, 7769.378449724121, 3232.434323993292, 3249.333886553251, 3250.723688486458, 3265.4448743713624, 3265.918352041218, 959.9568019439142, 979.1677074486629, 995.9601615935281, 1007.8185926533148, 1073.4471747050275, 26161.73838261631, 28886.711132888824, 29693.85153074221, 29899.70100299013, 29922.0, 923.6443969071821, 989.4557993103908, 1014.1177175857051, 1056.2447849787286, 1080.3409919948824, 15240.923795381173, 15385.769213461806, 15394.131056120528, 15622.218889055503, 15659.217039148225, 471370.5725885463, 479997.0, 496003.0, 499076.0, 508980.0, 54412.45587544074, 54482.45517544853, 54493.0, 54626.72686636621, 54719.90560188774, 1307.7972914198315, 1351.8580549042406, 1357.9388927498287, 1361.5574938144944, 1363.079921053285, 26163.607545886447, 28929.0, 29153.833846645895, 29722.702772972858, 30693.0, 46163.538364615924, 46482.30276545922, 46519.0, 46640.30039549342, 46693.76653116713, 541454.0, 544244.0, 544549.0, 545452.0, 548057.0, 22823.20118795834, 23531.0, 24006.87996560006, 25905.74094259109, 25998.480030399664, 41131.97170070772, 42507.937301567705, 44448.77775611101, 45830.85422864322, 46217.84455388508, 37399.0, 37797.43303850389, 38158.809205953796, 38461.230775383774, 39007.804960975016, 36962.07594810151, 37148.62851371452, 37186.44220336643, 38303.808480957414, 38316.808415958854, 32546.02361929146, 35641.643583564866, 36443.81778091199, 36911.78529288144, 37863.0, 32626.83686581552, 33663.3267334647, 34060.82969585136, 34339.4849077269, 34371.48442773311, 22690.68153238359, 23877.0, 25278.0, 25296.16308149134, 25455.74544254608], "value": 18047.40849672958, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.6880006376281, 374.3916136278549, 377.8645171260362, 378.79545045675326, 387.370522900287, 15834.920825395871, 20140.295089671847, 20955.37133885982, 21208.36374908755, 21394.786052139472, 18648.44054678362, 19123.278904898558, 20100.59798804023, 20707.0, 21110.05004774785, 5641.943580564182, 5742.454466825645, 5870.295564532252, 5991.2510936132985, 6071.848203794896, 5946.494547963428, 6020.548458865581, 6035.758569657209, 6038.6074905131245, 6046.0, 5807.0998995155815, 5857.0, 5915.970420147914, 5961.0, 6020.789272375468, 5870.178175055496, 5967.999999999978, 6007.459328660441, 6013.428724271187, 6046.274447066358, 4986.775595098229, 5143.177091665353, 5183.429822719498, 5186.740662966837, 5204.479552044803, 25458.0, 25574.616380754305, 25651.871740641363, 25754.74245257561, 25806.87096564524, 2340.4265954841003, 2364.3261670424017, 2472.8269021168526, 2489.054159419427, 2941.3970136122043, 15112.924435377863, 15151.545453636409, 15368.231588420535, 15464.149471779048, 15605.765913511306, 1822.070743920597, 1839.9593775202318, 1859.8605104617209, 1885.2176346816054, 1899.7720273567122, 90384.54807725985, 109906.0, 111759.0, 112637.43681281542, 113311.0, 8788.472691638523, 8900.643974241088, 8906.93116825972, 9815.950920245354, 10170.898291016994, 11506.137039722054, 13082.803757943639, 13119.737605247841, 13180.934095329654, 13189.73620527584, 8104.1085480597085, 8377.287930525852, 8390.244877961013, 8503.107173746792, 8545.444546104483, 8462.830743385099, 8499.787505312417, 8514.57427128641, 8580.95709521461, 8676.392652514263, 5237.921431178535, 6408.166938297992, 6428.260750013715, 6436.710348034256, 6497.545171837926, 1573.4886161997397, 1592.5222433269973, 1826.3060037185785, 1864.1518109260253, 1865.225931238548, 877.2981614708208, 880.423322723617, 916.244098618629, 977.2865807960229, 992.6098025161217, 20375.388738337875, 21659.025343859565, 21773.129074837147, 21777.4339264767, 21820.908954552207, 928.4800511713462, 974.6539978307599, 980.2794945714894, 999.1107913956585, 1005.5223768709805, 10795.784084318428, 11592.6522204334, 11595.76808463826, 11604.071674266039, 11627.59303424393, 450375.0, 517431.0, 542023.0, 547716.0, 552308.0, 110884.0, 113290.43354783338, 113507.0, 113598.43200783944, 118782.0, 985.9852102218473, 1007.9748006299901, 1019.2966852871494, 1026.7381817636604, 1027.7995790820867, 14876.925615371854, 17781.73327400116, 19352.935588542765, 19419.902900485406, 19449.805501945364, 48893.0, 49461.75269123631, 50911.49088509067, 51439.0, 52275.2158717612, 68147.0, 69598.0, 72645.63677181791, 73796.2620373811, 79653.0, 5258.737063146904, 5967.880642387041, 7358.484906056525, 7360.742374016883, 7368.410527157922, 9208.171264586219, 12531.624051278477, 12860.678483037998, 13142.73714525685, 13266.734665306447, 8920.910790892009, 9428.764280893032, 11058.778824423627, 11180.770115287474, 11466.082713382742, 8616.870746938926, 9572.32993690435, 11094.0, 11216.719582010188, 11604.95555400018, 11470.885291146982, 11596.47815848272, 11666.941665291619, 11783.88216117862, 11890.583829565923, 7963.92036079655, 8161.877571836546, 8195.83608327842, 8266.380021498291, 8384.53885036329, 10959.06847917936, 11119.888801111885, 11278.887211128113, 11392.316461012366, 11487.942560287145], "value": 9488.145679886376, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.1374036265881, 1503.669192777589, 1631.3311542267666, 1806.6296409236109, 1883.576195356045, 7966.800829979239, 8655.350848686357, 8678.73963781086, 9051.909480905197, 9072.501012444322, 8814.55927203639, 8922.241609463188, 8955.238804701592, 8962.55187240637, 8985.191332780048, 2375.2874137758645, 2423.903043878243, 2425.6604075429454, 2431.051889762994, 2446.9755302447015, 2065.7521097468248, 2343.7226711442304, 2416.9274921752376, 2420.7700268474555, 2420.878956052199, 2282.6804247405375, 2401.5557121932497, 2406.1458182345277, 2411.493586346869, 2421.0, 2328.93013209604, 2335.5095429959815, 2340.9180678676335, 2350.4241460842118, 2385.3678775124604, 2011.718359429681, 2367.5856725073045, 2386.1886958434216, 2389.032441861049, 2392.6530653055383, 8921.15249051339, 8946.731598052069, 8963.193312601898, 8975.775605609846, 8985.820283594358, 5250.7112108834, 5354.785808567653, 5428.565714742811, 5440.4287549807495, 5445.9652665993335, 3612.530371051773, 3629.7277704172293, 3638.6179451157514, 3674.2259594006623, 3703.222323312081, 1105.5190991918541, 1106.983395249072, 1107.4518113533773, 1110.134095405585, 1119.6808909460765, 11579.305241685526, 11663.825042624367, 11669.708257293636, 11706.882931170665, 11727.296362218294, 4558.749268790249, 4639.559241872017, 4835.758212089381, 4840.83057093, 4843.854684359475, 10085.29402941787, 10113.544890479947, 10136.54385552652, 10140.594376224868, 10154.339967902211, 4628.328892310664, 5320.14877619579, 5363.249145119687, 5386.272853164851, 5405.78376864921, 4394.582514661102, 4660.440747110369, 4867.634927380461, 4896.608271338285, 4905.288733133676, 3864.632859878307, 3906.199229158016, 4005.939910901339, 4044.69664775143, 4066.1054567994993, 1271.0467149637773, 1299.389287035092, 1302.1340808362334, 1309.561296965508, 1311.6196303072193, 781.6638845296463, 876.1588874680251, 918.0452329577298, 925.746002800158, 930.9860352094724, 5569.0, 5769.798057067983, 5935.851603709942, 5936.465718085393, 5982.0, 433.4430257119586, 441.3247730971619, 445.7815670321519, 448.9955100449019, 450.3672340361797, 3828.3729414998515, 3906.0430194601936, 4003.979980100081, 4005.499312585927, 4021.698372622065, 10240.231982601335, 10686.25196236256, 10689.35863848156, 10698.358098514114, 10723.731906702395, 10182.389056656477, 10425.895741042492, 10602.41686707223, 10677.359358438518, 10721.142308615292, 7116.573005619679, 8091.312238459679, 8124.918750812534, 8149.470284431493, 8152.6331315090965, 4723.858284251478, 5323.707196104251, 5771.9134212986, 5783.566232532661, 5841.18223448726, 7870.858725484887, 9739.123478886933, 9787.412755234709, 9788.510574471391, 9825.705228843146, 9886.703398898044, 9984.650537231162, 10003.799924001625, 10094.545745441326, 10113.949430252802, 6971.651417429007, 7346.485745997729, 7502.399808015237, 7556.697732090654, 7655.50239234448, 8454.957725211334, 8470.576471176293, 8501.659933602587, 8508.021577518417, 8520.275776559061, 6464.095026696173, 7400.481966262525, 7532.623368831646, 7559.924400756142, 7659.617019148909, 7136.500444968802, 7150.320719531636, 7156.677949492181, 7166.713331466892, 7309.926900730925, 6981.930180698128, 7015.0, 7068.434525238072, 7111.608861512666, 7116.43068554504, 4959.851204463872, 4990.301357809982, 5005.449400565862, 5048.3941926969, 5060.49395060491, 4831.299461578122, 4929.200841692828, 4931.186354251508, 5030.949690503195, 5147.819826306061], "value": 4494.376712186725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.8491728851698, 304.7090029022284, 310.3111093372712, 312.5046800820699, 314.7371944426404, 4923.458419573849, 5273.235380869772, 5501.386841250404, 5584.5811564132755, 5655.321361436629, 5625.80309689161, 5628.380878103416, 5663.971680141593, 5698.857528561778, 5741.971290143554, 3607.0, 3967.325554655701, 4309.849155279566, 4331.263685173512, 4337.0, 3977.9005524861964, 4221.303484924999, 4273.1667324871505, 4381.277089280265, 4385.802638881258, 4186.727862688916, 4208.179405016008, 4209.095723950464, 4215.135897141094, 4222.894427639303, 4175.603317684815, 4222.894427639303, 4231.984323762305, 4239.554846741079, 4284.871453856358, 2998.865051072707, 3212.0, 3336.816475093869, 3640.6541378568995, 3644.234710710754, 4606.286025666027, 5561.63849349791, 5579.8605034874045, 5585.2459917910965, 5590.832275031756, 4410.9198990933255, 4546.340780586829, 4867.0, 4876.463589005207, 4916.2301571434255, 4699.506551812045, 4877.707337559722, 4885.389326334208, 5641.492265696066, 5941.405859414067, 3129.04564107947, 3859.9421008684894, 3977.781222032787, 3990.541087774885, 4009.83960641574, 5148.433672296044, 5518.696471694096, 6119.55217912834, 6387.297397286295, 6431.710573024178, 3454.3954807908517, 3910.608939106067, 3967.849323696096, 3992.720509564361, 4079.938800917989, 3603.1892824114366, 4116.156187981456, 4133.359329303963, 4137.338025915899, 4147.52303485103, 4840.201366774513, 4848.10310092634, 4849.733264670477, 4860.2223644217465, 4863.148948933923, 5086.3896332440345, 5729.5702822288495, 5775.537956963432, 5987.221661184019, 6104.0, 1283.3583208395803, 1360.0411709744606, 1384.702289007868, 1393.540131756518, 1395.6092294157652, 753.8605358008786, 812.4840726138945, 816.0003995106066, 823.2714048067503, 848.108717559845, 3984.601539845992, 4566.49768525462, 4597.448306203277, 4601.930971035437, 4607.746573938399, 4783.378160839069, 4823.1800593899125, 4958.355413796184, 5165.405978312542, 5170.508801663836, 4383.430154079949, 4487.5991344630465, 4519.977400112978, 4561.338605902126, 4643.3963584733765, 2937.206954122375, 3008.473517134493, 3145.5596216529702, 3251.040942921844, 3281.556989806393, 6026.246719160105, 6486.902696459557, 6498.902516462163, 6513.772017979348, 6527.281998980108, 5831.912521312183, 5864.416607515946, 6087.726052327655, 6385.584936979081, 6387.329330420239, 6174.5060395168275, 6463.15799997008, 6464.838379040468, 6469.967650161813, 6536.365908522869, 5336.386315573759, 5853.385394533512, 6117.969410153099, 6213.937860621336, 6243.937560624518, 5386.946130538645, 5970.895384353908, 6093.817185484442, 6114.327423983447, 6184.288806787097, 6015.518758499397, 6426.775062872777, 6463.3213512580505, 6469.923947912785, 6523.804285871432, 3209.5667084943234, 3255.1373885919775, 3715.461258117612, 4028.939565906572, 4032.6572241359813, 5776.277965254343, 6115.908261376172, 6117.625897483143, 6118.082287656887, 6132.846678833065, 5865.882682346414, 5941.771835561664, 5943.474023540065, 5963.048291095762, 5974.37269086757, 5877.764889404376, 5946.643201407929, 5954.19618351517, 5974.3271883872585, 5997.760089596542, 4769.594584460221, 4985.052839960396, 5529.313559364272, 5799.420057994167, 5841.561882858718, 5301.0, 5903.291605007426, 5956.404359564009, 5978.491828194652, 5980.671063091571, 4748.847500462038, 4762.285657151455, 5149.897002060013, 5771.14328407441, 5835.56233282514], "value": 4217.467624079459, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.9785206443806, 756.474250395975, 757.776455945496, 786.5673879366348, 843.3885433061032, 15004.324805383756, 15433.305501252442, 15940.884138110338, 15945.282462289197, 16290.83709162908, 14343.64140896478, 14632.561023169323, 14678.266086695648, 14679.339429725662, 14693.118412895234, 682.2870100744709, 692.1970514203525, 755.9168491465934, 849.5157760076759, 855.5337341149074, 855.482433127958, 858.7466697324304, 877.6182360673096, 879.5778026547262, 880.6169316347377, 881.7002219245455, 920.5443305563755, 920.7974245665943, 922.8338898998178, 929.4609126706528, 894.6913314906315, 904.4889637354906, 913.7121806630923, 918.967836125739, 920.2362039507193, 671.6540981394586, 681.495693187043, 682.3347236444476, 684.288340126268, 689.0972825598482, 15207.163606001666, 15210.467633632881, 15321.387144514207, 15345.46290879814, 15359.078455292718, 12155.209911355734, 13940.93029534856, 13943.16341019542, 13994.790078148835, 14186.858131418658, 11134.0, 11538.769224615544, 11555.19113662044, 11767.823482647682, 12125.636230913087, 4543.386642803212, 4572.634189264851, 4580.862574122748, 4621.65337599678, 4647.907041859177, 99210.99999999927, 99889.00110998869, 102312.48843755807, 102343.48828255886, 102468.48765756123, 9983.900160998443, 10540.314879532805, 10613.363198208132, 10628.043476087189, 10687.14502839771, 19998.70001949972, 20041.098150583257, 20054.699179512318, 20093.296734614218, 20124.094415751326, 7696.576688282086, 7753.1859154787935, 7793.0, 7819.569923654253, 7861.685057152466, 4341.891452713708, 4367.388565600819, 4378.408914796461, 4415.227335216325, 4580.931286030712, 3398.5242066110764, 3494.1264683829045, 3510.4558793387064, 3516.5780106387397, 3523.383407903658, 630.7634637011121, 633.4552285034866, 636.688022868791, 644.0950464597248, 652.5758257132807, 582.5863636817822, 608.6104892868568, 610.2646311194999, 629.1600713048132, 633.2938773267865, 8862.77843053916, 9012.459252444874, 9187.448753074837, 9207.90792092084, 9254.583543740548, 579.5334755521761, 612.1980205930246, 626.8432891777056, 630.1933525087895, 634.5589815078476, 4233.394787981476, 4293.463317085364, 4466.441694788152, 4549.7042692224895, 4567.634589232854, 91665.5416722912, 138683.0, 139251.30374348065, 152096.0, 161907.0, 107979.38030929542, 126314.0, 138573.6142638581, 142644.0, 142963.2851835755, 100327.49836250771, 106214.0, 113128.43435782769, 115932.42033789946, 123917.0, 7376.4919927225, 9545.713628591153, 9819.705408837746, 9848.965858585032, 10014.341772404063, 45228.321575177055, 54003.72998134984, 57913.0, 57989.0, 58444.0, 86452.0, 91376.54311728399, 91938.0, 92292.53853730689, 96466.0, 12656.367181641066, 12924.483020679067, 13003.544875929298, 13059.02057345722, 13478.393472293967, 14572.927135364256, 14648.633784155481, 14962.625934351292, 15240.771388429404, 15458.995165314218, 13486.527971520949, 13581.66045848822, 13730.794038089236, 13793.310334483436, 13891.235982021086, 13615.931920340336, 13807.309634518435, 13854.30728463593, 14307.785383219469, 14324.414665661981, 13426.395812188279, 13566.728665426834, 13583.728325433238, 13599.896992987804, 14078.788818167532, 10824.24230303871, 10939.8090825577, 11277.892885357896, 11301.484512798897, 11678.24091434088, 10429.374237545771, 10474.68575942723, 11427.942860285646, 11706.824397633873, 11780.882191177978], "value": 7769.108292319539, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 160.0, 152.0, 116.0, 116.0, 100.0, 115.0, 118.0, 116.0, 114.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 134.0, 157.0, 113.0, 133.0, 128.0, 110.0, 157.0, 111.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 200.0, 217.0, 208.0, 195.0, 195.0, 202.0, 201.0, 198.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 74.0, 149.0, 69.0, 112.0, 85.0, 63.0, 127.0, 59.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 121.0, 73.0, 101.0, 115.0, 138.0, 75.0, 71.0, 109.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 89.0, 93.0, 89.0, 85.0, 89.0, 85.0, 88.0, 86.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 99.0, 126.0, 95.0, 147.0, 143.0, 175.0, 100.0, 100.0, 99.0], "value": 118.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 91.0, 90.0, 95.0, 90.0, 97.0, 93.0, 92.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 60.0, 62.0, 75.0, 88.0, 87.0, 86.0, 88.0], "value": 71.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 113.0, 72.0, 79.0, 66.0, 101.0, 62.0, 92.0, 108.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 88.0, 89.0, 88.0, 88.0, 88.0, 85.0, 78.0, 96.0, 98.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 162.0, 154.0, 159.0, 141.0, 141.0, 162.0, 163.0, 191.0, 146.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 203.0, 161.0, 207.0, 196.0, 236.0, 242.0, 182.0, 171.0, 177.0], "value": 192.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 70.0, 73.0, 69.0, 68.0, 91.0, 90.0, 91.0], "value": 75.8, "unit": "score"}]}]} 12:18:11 INFO - Return code: 0 12:18:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:18:11 ERROR - # TBPL SUCCESS # 12:18:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:11 INFO - 2016-09-06 12:18:11.232 screenresolution[1679:10905] starting screenresolution argv=screenresolution get 12:18:11 INFO - 2016-09-06 12:18:11.235 screenresolution[1679:10905] Display 0: 1600x1200x32@60 12:18:11 INFO - 2016-09-06 12:18:11.241 screenresolution[1680:10908] starting screenresolution argv=screenresolution list 12:18:11 INFO - Available Modes on Display 0 12:18:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:11 INFO - Intel Iris: 12:18:11 INFO - Chipset Model: Intel Iris 12:18:11 INFO - Type: GPU 12:18:11 INFO - Bus: Built-In 12:18:11 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:11 INFO - Vendor: Intel (0x8086) 12:18:11 INFO - Device ID: 0x0a2e 12:18:11 INFO - Revision ID: 0x0009 12:18:11 INFO - Displays: 12:18:11 INFO - Display: 12:18:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:11 INFO - Display Serial Number: 1600x1200 60 12:18:11 INFO - Main Display: Yes 12:18:11 INFO - Mirror: Off 12:18:11 INFO - Online: Yes 12:18:11 INFO - Rotation: Supported 12:18:11 INFO - Return code: 0 12:18:11 INFO - Running post-action listener: _resource_record_post_action 12:18:11 INFO - [mozharness: 2016-09-06 19:18:11.481429Z] Finished run-tests step (success) 12:18:11 INFO - Running post-run listener: _resource_record_post_run 12:18:11 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31935488; Write bytes: 707972096; Read time: 200; Write time: 2008 12:18:11 INFO - TinderboxPrint: CPU usage
54.5% 12:18:11 INFO - TinderboxPrint: I/O read bytes / time
31,935,488 / 200 12:18:11 INFO - TinderboxPrint: I/O write bytes / time
707,972,096 / 2,008 12:18:11 INFO - TinderboxPrint: CPU idle
2,199.9 (45.5%) 12:18:11 INFO - TinderboxPrint: CPU system
976.1 (20.2%) 12:18:11 INFO - TinderboxPrint: CPU user
1,661.7 (34.3%) 12:18:11 INFO - TinderboxPrint: Swap in / out
434,053,120 / 0 12:18:11 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392516096; Write bytes: 387581952; Read time: 29354; Write time: 808 12:18:11 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 30166016; Write bytes: 297395200; Read time: 187; Write time: 1144 12:18:11 INFO - Running post-run listener: _upload_blobber_files 12:18:11 INFO - Blob upload gear active. 12:18:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:18:11 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 12:18:11 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:18:11 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:18:12 INFO - (blobuploader) - INFO - Open directory for files ... 12:18:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:18:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:18:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:13 INFO - (blobuploader) - INFO - Done attempting. 12:18:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:18:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:14 INFO - (blobuploader) - INFO - Done attempting. 12:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:18:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:17 INFO - (blobuploader) - INFO - Done attempting. 12:18:17 INFO - (blobuploader) - INFO - Iteration through files over. 12:18:17 INFO - Return code: 0 12:18:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:18:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:18:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/010452e3250734443e1a88e13f5882973d982029ea8b396ef2bd6f3d1f514d4acf1323d235a48147e73c7938d4d3a3c7ad43cd439e0edd6b38cd1b930cb1f280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/875b78bf202fb7f598b2232198bb94ccc6accbdc41359e0a46cb2204ea3de5c8c5ec106ceae452b22f10b8d031eb78c326a0babbb3f73068202b79ce62b747ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/80660500810d329cfba640bc6e7de471adc99034df0b6d3c86cf42a61e3f4f80301ea5278566a5b1494132efa37397b29891a8f5dc823acc138f12db6a61cf2a"} 12:18:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:18:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:18:17 INFO - Contents: 12:18:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/010452e3250734443e1a88e13f5882973d982029ea8b396ef2bd6f3d1f514d4acf1323d235a48147e73c7938d4d3a3c7ad43cd439e0edd6b38cd1b930cb1f280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/875b78bf202fb7f598b2232198bb94ccc6accbdc41359e0a46cb2204ea3de5c8c5ec106ceae452b22f10b8d031eb78c326a0babbb3f73068202b79ce62b747ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/80660500810d329cfba640bc6e7de471adc99034df0b6d3c86cf42a61e3f4f80301ea5278566a5b1494132efa37397b29891a8f5dc823acc138f12db6a61cf2a"} 12:18:17 INFO - Running post-run listener: copy_logs_to_upload_dir 12:18:17 INFO - Copying logs to upload dir... 12:18:17 INFO - mkdir: /builds/slave/test/build/upload/logs 12:18:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.780903 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-06 12:18:17.833416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:17.836685) ========= 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.venuWayESr/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.EYqAz5TfW2/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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/Larch/sha512/010452e3250734443e1a88e13f5882973d982029ea8b396ef2bd6f3d1f514d4acf1323d235a48147e73c7938d4d3a3c7ad43cd439e0edd6b38cd1b930cb1f280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/875b78bf202fb7f598b2232198bb94ccc6accbdc41359e0a46cb2204ea3de5c8c5ec106ceae452b22f10b8d031eb78c326a0babbb3f73068202b79ce62b747ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/80660500810d329cfba640bc6e7de471adc99034df0b6d3c86cf42a61e3f4f80301ea5278566a5b1494132efa37397b29891a8f5dc823acc138f12db6a61cf2a"} build_url:https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011160 build_url: 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/010452e3250734443e1a88e13f5882973d982029ea8b396ef2bd6f3d1f514d4acf1323d235a48147e73c7938d4d3a3c7ad43cd439e0edd6b38cd1b930cb1f280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/875b78bf202fb7f598b2232198bb94ccc6accbdc41359e0a46cb2204ea3de5c8c5ec106ceae452b22f10b8d031eb78c326a0babbb3f73068202b79ce62b747ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/80660500810d329cfba640bc6e7de471adc99034df0b6d3c86cf42a61e3f4f80301ea5278566a5b1494132efa37397b29891a8f5dc823acc138f12db6a61cf2a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:17.873479) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:17.873795) ========= 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.venuWayESr/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.EYqAz5TfW2/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:17.922076) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 12:18:17.922393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 12:18:17.922721) ========= ========= Total master_lag: 0.19 =========