builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0385 starttime: 1467218168.73 results: success (0) buildid: 20160629072025 builduid: bdbe7998614c4d3a8967dfbc5fabbee7 revision: 7773873116e90a6cbf3de93d571b3df9aaee2e8c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:08.729576) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:08.730004) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:08.730307) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:08.755031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:08.755669) ========= 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.u0f4AchHqG/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.GNcbkpTEhc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-06-29 09:36:08-- 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% 18.6M=0.001s 2016-06-29 09:36:09 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.394739 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:09.172467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:09.172771) ========= 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.u0f4AchHqG/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.GNcbkpTEhc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.026153 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:09.221089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:36:09.221458) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7773873116e90a6cbf3de93d571b3df9aaee2e8c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 7773873116e90a6cbf3de93d571b3df9aaee2e8c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u0f4AchHqG/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.GNcbkpTEhc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-06-29 09:36:09,304 truncating revision to first 12 chars 2016-06-29 09:36:09,305 Setting DEBUG logging. 2016-06-29 09:36:09,305 attempt 1/10 2016-06-29 09:36:09,305 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7773873116e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 09:36:10,341 unpacking tar archive at: fx-team-7773873116e9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.309036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:36:10.552046) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:10.552391) ========= script_repo_revision: 7773873116e90a6cbf3de93d571b3df9aaee2e8c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:10.552762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:10.553023) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:36:10.570679) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-29 09:36:10.570972) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u0f4AchHqG/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.GNcbkpTEhc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:10 INFO - MultiFileLogger online at 20160629 09:36:10 in /builds/slave/test 09:36:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:36:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:10 INFO - {'append_to_log': False, 09:36:10 INFO - 'base_work_dir': '/builds/slave/test', 09:36:10 INFO - 'blob_upload_branch': 'Fx-Team', 09:36:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:10 INFO - 'branch': 'Fx-Team', 09:36:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:10 INFO - 'config_files': ('talos/mac_config.py',), 09:36:10 INFO - 'default_actions': ('clobber', 09:36:10 INFO - 'read-buildbot-config', 09:36:10 INFO - 'download-and-extract', 09:36:10 INFO - 'populate-webroot', 09:36:10 INFO - 'create-virtualenv', 09:36:10 INFO - 'install', 09:36:10 INFO - 'run-tests'), 09:36:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:10 INFO - 'download_minidump_stackwalk': True, 09:36:10 INFO - 'download_symbols': 'ondemand', 09:36:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:10 INFO - 'installer_path': 'installer.exe', 09:36:10 INFO - 'log_level': 'info', 09:36:10 INFO - 'log_name': 'talos', 09:36:10 INFO - 'log_to_console': True, 09:36:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:10 INFO - 'opt_config_files': (), 09:36:10 INFO - 'pip_index': False, 09:36:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:10 INFO - 'cmd': ('bash', 09:36:10 INFO - '-c', 09:36:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:10 INFO - 'enabled': True, 09:36:10 INFO - 'halt_on_failure': False, 09:36:10 INFO - 'name': 'check_screen_resolution'},), 09:36:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:10 INFO - 'cmd': ('bash', 09:36:10 INFO - '-c', 09:36:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:10 INFO - 'enabled': True, 09:36:10 INFO - 'halt_on_failure': False, 09:36:10 INFO - 'name': 'check_screen_resolution'},), 09:36:10 INFO - 'run_cmd_checks_enabled': True, 09:36:10 INFO - 'sps_profile': False, 09:36:10 INFO - 'sps_profile_interval': 0, 09:36:10 INFO - 'suite': 'dromaeojs', 09:36:10 INFO - 'system_bits': '32', 09:36:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:10 INFO - 'title': 't-yosemite-r7-0385', 09:36:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:10 INFO - 'use_talos_json': True, 09:36:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:10 INFO - 'work_dir': 'build'} 09:36:10 INFO - ##### 09:36:10 INFO - ##### Running clobber step. 09:36:10 INFO - ##### 09:36:10 INFO - Running pre-action listener: _resource_record_pre_action 09:36:10 INFO - Running main action method: clobber 09:36:10 INFO - rmtree: /builds/slave/test/build 09:36:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:12 INFO - Running post-action listener: _resource_record_post_action 09:36:12 INFO - ##### 09:36:12 INFO - ##### Finished clobber step (success) 09:36:12 INFO - ##### 09:36:12 INFO - ##### 09:36:12 INFO - ##### Running read-buildbot-config step. 09:36:12 INFO - ##### 09:36:12 INFO - Running pre-action listener: _resource_record_pre_action 09:36:12 INFO - Running main action method: read_buildbot_config 09:36:12 INFO - Using buildbot properties: 09:36:12 INFO - { 09:36:12 INFO - "project": "", 09:36:12 INFO - "product": "firefox", 09:36:12 INFO - "script_repo_revision": "production", 09:36:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:36:12 INFO - "repository": "", 09:36:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:36:12 INFO - "buildid": "20160629072025", 09:36:12 INFO - "pgo_build": "False", 09:36:12 INFO - "basedir": "/builds/slave/test", 09:36:12 INFO - "buildnumber": 67, 09:36:12 INFO - "slavename": "t-yosemite-r7-0385", 09:36:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:36:12 INFO - "platform": "yosemite_r7", 09:36:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:36:12 INFO - "branch": "fx-team", 09:36:12 INFO - "repo_path": "integration/fx-team", 09:36:12 INFO - "revision": "7773873116e90a6cbf3de93d571b3df9aaee2e8c", 09:36:12 INFO - "stage_platform": "macosx64", 09:36:12 INFO - "builduid": "bdbe7998614c4d3a8967dfbc5fabbee7", 09:36:12 INFO - "slavebuilddir": "test" 09:36:12 INFO - } 09:36:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:36:12 INFO - Running post-action listener: _resource_record_post_action 09:36:12 INFO - ##### 09:36:12 INFO - ##### Finished read-buildbot-config step (success) 09:36:12 INFO - ##### 09:36:12 INFO - ##### 09:36:12 INFO - ##### Running download-and-extract step. 09:36:12 INFO - ##### 09:36:12 INFO - Running pre-action listener: _resource_record_pre_action 09:36:12 INFO - Running main action method: download_and_extract 09:36:12 INFO - mkdir: /builds/slave/test/build/tests 09:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:12 INFO - https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:36:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:36:14 INFO - Downloaded 1199 bytes. 09:36:14 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:14 INFO - Using the following test package requirements: 09:36:14 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:36:14 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:14 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:36:14 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'jsshell-mac.zip'], 09:36:14 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:36:14 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:36:14 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:36:14 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:36:14 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:36:14 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:14 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:14 INFO - https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:16 INFO - Downloaded 18787882 bytes. 09:36:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:36:19 INFO - Return code: 0 09:36:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:19 INFO - https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:21 INFO - Downloaded 18787882 bytes. 09:36:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:36:24 INFO - Return code: 0 09:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:24 INFO - https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:36:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:36:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:36:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:36:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:36:25 INFO - Downloaded 14405245 bytes. 09:36:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:36:26 INFO - Return code: 0 09:36: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:26 INFO - https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:36:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:36:29 INFO - Downloaded 112379559 bytes. 09:36:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:29 INFO - mkdir: /builds/slave/test/properties 09:36:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:29 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:29 INFO - Contents: 09:36:29 INFO - build_url:https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:29 INFO - Running post-action listener: _resource_record_post_action 09:36:29 INFO - Running post-action listener: set_extra_try_arguments 09:36:29 INFO - ##### 09:36:29 INFO - ##### Finished download-and-extract step (success) 09:36:29 INFO - ##### 09:36:29 INFO - ##### 09:36:29 INFO - ##### Running populate-webroot step. 09:36:29 INFO - ##### 09:36:29 INFO - Running pre-action listener: _resource_record_pre_action 09:36:29 INFO - Running main action method: populate_webroot 09:36:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:30 INFO - '--tppagecycles', 09:36:30 INFO - '7'], 09:36:30 INFO - 'tests': ['tsvgm']}}, 09:36:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['damp', 'tps']}, 09:36:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'tests': ['damp', 'tps']}, 09:36:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['dromaeo_dom']}, 09:36:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['basic_compositor_video']}, 09:36:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:36:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['a11yr', 09:36:30 INFO - 'ts_paint', 09:36:30 INFO - 'tpaint', 09:36:30 INFO - 'sessionrestore', 09:36:30 INFO - 'sessionrestore_no_auto_restore', 09:36:30 INFO - 'tabpaint']}, 09:36:30 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:30 INFO - 'ts_paint', 09:36:30 INFO - 'tpaint', 09:36:30 INFO - 'sessionrestore', 09:36:30 INFO - 'sessionrestore_no_auto_restore', 09:36:30 INFO - 'tabpaint']}, 09:36:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:30 INFO - 'ts_paint', 09:36:30 INFO - 'tpaint', 09:36:30 INFO - 'sessionrestore', 09:36:30 INFO - 'sessionrestore_no_auto_restore', 09:36:30 INFO - 'tabpaint']}, 09:36:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:30 INFO - 'ts_paint', 09:36:30 INFO - 'tpaint', 09:36:30 INFO - 'sessionrestore', 09:36:30 INFO - 'sessionrestore_no_auto_restore', 09:36:30 INFO - 'tabpaint']}, 09:36:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['a11yr', 09:36:30 INFO - 'ts_paint', 09:36:30 INFO - 'tpaint', 09:36:30 INFO - 'sessionrestore', 09:36:30 INFO - 'sessionrestore_no_auto_restore', 09:36:30 INFO - 'tabpaint']}, 09:36:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['a11yr', 09:36:30 INFO - 'ts_paint', 09:36:30 INFO - 'tpaint', 09:36:30 INFO - 'sessionrestore', 09:36:30 INFO - 'sessionrestore_no_auto_restore', 09:36:30 INFO - 'tabpaint']}, 09:36:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['tsvgx', 09:36:30 INFO - 'tsvgr_opacity', 09:36:30 INFO - 'tart', 09:36:30 INFO - 'tscrollx', 09:36:30 INFO - 'cart']}, 09:36:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:30 INFO - 'tsvgr_opacity', 09:36:30 INFO - 'tart', 09:36:30 INFO - 'tscrollx', 09:36:30 INFO - 'cart']}, 09:36:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'talos_options': ['--disable-e10s'], 09:36:30 INFO - 'tests': ['tp5o']}, 09:36:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'tests': ['tp5o']}, 09:36:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'talos_options': ['--disable-e10s', 09:36:30 INFO - '--xperf_path', 09:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:30 INFO - 'tests': ['tp5n']}, 09:36:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:30 INFO - 'talos_options': ['--xperf_path', 09:36:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:30 INFO - 'tests': ['tp5n']}}, 09:36:30 INFO - 'talos.zip': {'path': '', 09:36:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:30 INFO - Running post-action listener: _resource_record_post_action 09:36:30 INFO - ##### 09:36:30 INFO - ##### Finished populate-webroot step (success) 09:36:30 INFO - ##### 09:36:30 INFO - ##### 09:36:30 INFO - ##### Running create-virtualenv step. 09:36:30 INFO - ##### 09:36:30 INFO - Running pre-action listener: _resource_record_pre_action 09:36:30 INFO - Running main action method: create_virtualenv 09:36:30 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36: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 09:36:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:30 INFO - Using real prefix '/tools/python27' 09:36:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:33 INFO - Installing pip.................done. 09:36:33 INFO - Return code: 0 09:36:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:33 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:33 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 09:36:33 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 09:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:33 INFO - 'HOME': '/Users/cltbld', 09:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:33 INFO - 'LOGNAME': 'cltbld', 09:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:33 INFO - 'MOZ_NO_REMOTE': '1', 09:36:33 INFO - 'NO_EM_RESTART': '1', 09:36:33 INFO - 'PAGER': '/bin/cat', 09:36:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:33 INFO - 'PWD': '/builds/slave/test', 09:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:33 INFO - 'SHELL': '/bin/bash', 09:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:33 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:33 INFO - 'USER': 'cltbld', 09:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:33 INFO - 'XPC_FLAGS': '0x0', 09:36:33 INFO - 'XPC_SERVICE_NAME': '0', 09:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36: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 09:36:34 INFO - Cleaning up... 09:36:34 INFO - Return code: 0 09:36:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36: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 09:36: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 09:36:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:34 INFO - 'HOME': '/Users/cltbld', 09:36:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:34 INFO - 'LOGNAME': 'cltbld', 09:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:34 INFO - 'MOZ_NO_REMOTE': '1', 09:36:34 INFO - 'NO_EM_RESTART': '1', 09:36:34 INFO - 'PAGER': '/bin/cat', 09:36:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:34 INFO - 'PWD': '/builds/slave/test', 09:36:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:34 INFO - 'SHELL': '/bin/bash', 09:36:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:34 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:34 INFO - 'USER': 'cltbld', 09:36:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:34 INFO - 'XPC_FLAGS': '0x0', 09:36:34 INFO - 'XPC_SERVICE_NAME': '0', 09:36:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:34 INFO - Downloading/unpacking psutil>=3.1.1 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:37 INFO - Installing collected packages: psutil 09:36:37 INFO - Running setup.py install for psutil 09:36:37 INFO - building 'psutil._psutil_osx' extension 09:36:37 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 09:36:37 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 09:36:37 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 09:36:37 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 09:36:38 INFO - building 'psutil._psutil_posix' extension 09:36:38 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 09:36:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:38 INFO - ^ 09:36:38 INFO - 1 warning generated. 09:36:38 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 09:36:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:38 INFO - Successfully installed psutil 09:36:38 INFO - Cleaning up... 09:36:38 INFO - Return code: 0 09:36:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:38 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:38 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 09:36:38 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 09:36:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:38 INFO - 'HOME': '/Users/cltbld', 09:36:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:38 INFO - 'LOGNAME': 'cltbld', 09:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:38 INFO - 'MOZ_NO_REMOTE': '1', 09:36:38 INFO - 'NO_EM_RESTART': '1', 09:36:38 INFO - 'PAGER': '/bin/cat', 09:36:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:38 INFO - 'PWD': '/builds/slave/test', 09:36:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:38 INFO - 'SHELL': '/bin/bash', 09:36:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:38 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:38 INFO - 'USER': 'cltbld', 09:36:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:38 INFO - 'XPC_FLAGS': '0x0', 09:36:38 INFO - 'XPC_SERVICE_NAME': '0', 09:36:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:38 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 09:36:38 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 09:36:38 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 09:36:38 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 09:36:38 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 09:36:38 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 09:36:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:41 INFO - Installing collected packages: mozsystemmonitor 09:36:41 INFO - Running setup.py install for mozsystemmonitor 09:36:41 INFO - Successfully installed mozsystemmonitor 09:36:41 INFO - Cleaning up... 09:36:41 INFO - Return code: 0 09:36:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:41 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:41 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 09:36:41 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 09:36:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:41 INFO - 'HOME': '/Users/cltbld', 09:36:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:41 INFO - 'LOGNAME': 'cltbld', 09:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:41 INFO - 'MOZ_NO_REMOTE': '1', 09:36:41 INFO - 'NO_EM_RESTART': '1', 09:36:41 INFO - 'PAGER': '/bin/cat', 09:36:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:41 INFO - 'PWD': '/builds/slave/test', 09:36:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:41 INFO - 'SHELL': '/bin/bash', 09:36:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:41 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:41 INFO - 'USER': 'cltbld', 09:36:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:41 INFO - 'XPC_FLAGS': '0x0', 09:36:41 INFO - 'XPC_SERVICE_NAME': '0', 09:36:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:41 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:41 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 09:36:41 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 09:36:41 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 09:36:41 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 09:36:41 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 09:36:41 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 09:36:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:44 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:44 INFO - warning: no files found matching '*.txt' 09:36:44 INFO - no previously-included directories found matching 'build' 09:36:44 INFO - no previously-included directories found matching 'dist' 09:36:44 INFO - no previously-included directories found matching '.git*' 09:36:44 INFO - Installing collected packages: jsonschema, functools32 09:36:44 INFO - Running setup.py install for functools32 09:36:44 INFO - warning: no files found matching '*.txt' 09:36:44 INFO - no previously-included directories found matching 'build' 09:36:44 INFO - no previously-included directories found matching 'dist' 09:36:44 INFO - no previously-included directories found matching '.git*' 09:36:44 INFO - Successfully installed jsonschema functools32 09:36:44 INFO - Cleaning up... 09:36:44 INFO - Return code: 0 09:36:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:44 INFO - 'HOME': '/Users/cltbld', 09:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:44 INFO - 'LOGNAME': 'cltbld', 09:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:44 INFO - 'MOZ_NO_REMOTE': '1', 09:36:44 INFO - 'NO_EM_RESTART': '1', 09:36:44 INFO - 'PAGER': '/bin/cat', 09:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:44 INFO - 'PWD': '/builds/slave/test', 09:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:44 INFO - 'SHELL': '/bin/bash', 09:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:44 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:44 INFO - 'USER': 'cltbld', 09:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:44 INFO - 'XPC_FLAGS': '0x0', 09:36:44 INFO - 'XPC_SERVICE_NAME': '0', 09:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:48 INFO - Downloading docopt-0.6.1.tar.gz 09:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:48 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:48 INFO - Running setup.py install for blobuploader 09:36:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:48 INFO - Running setup.py install for requests 09:36:49 INFO - Running setup.py install for docopt 09:36:49 INFO - Successfully installed blobuploader requests docopt 09:36:49 INFO - Cleaning up... 09:36:49 INFO - Return code: 0 09:36:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:49 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:49 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 09:36:49 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 09:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:49 INFO - 'HOME': '/Users/cltbld', 09:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:49 INFO - 'LOGNAME': 'cltbld', 09:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:49 INFO - 'MOZ_NO_REMOTE': '1', 09:36:49 INFO - 'NO_EM_RESTART': '1', 09:36:49 INFO - 'PAGER': '/bin/cat', 09:36:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:49 INFO - 'PWD': '/builds/slave/test', 09:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:49 INFO - 'SHELL': '/bin/bash', 09:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:49 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:49 INFO - 'USER': 'cltbld', 09:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:49 INFO - 'XPC_FLAGS': '0x0', 09:36:49 INFO - 'XPC_SERVICE_NAME': '0', 09:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-3H8W1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-l7uxzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-GMWQ5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-mgA0DE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-TM2ast-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ZNa61n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9HT9tN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-m8eFV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2R1mUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-xYzTRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:50 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-pAQYkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-FFtYDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-c30Xta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-OVVv5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-qvsyNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-H4zMaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:51 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9Bsc7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:51 INFO - Running setup.py install for manifestparser 09:36:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Running setup.py install for mozcrash 09:36:51 INFO - Running setup.py install for mozdebug 09:36:52 INFO - Running setup.py install for mozdevice 09:36:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Running setup.py install for mozfile 09:36:52 INFO - Running setup.py install for mozhttpd 09:36:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Running setup.py install for mozinfo 09:36:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Running setup.py install for mozInstall 09:36:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Running setup.py install for mozleak 09:36:53 INFO - Running setup.py install for mozlog 09:36:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:53 INFO - Running setup.py install for moznetwork 09:36:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:53 INFO - Running setup.py install for mozprocess 09:36:53 INFO - Running setup.py install for mozprofile 09:36:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:53 INFO - Running setup.py install for mozrunner 09:36:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:53 INFO - Running setup.py install for mozscreenshot 09:36:53 INFO - Running setup.py install for moztest 09:36:54 INFO - Running setup.py install for mozversion 09:36:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:54 INFO - Cleaning up... 09:36:54 INFO - Return code: 0 09:36:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:54 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:54 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 09:36:54 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 09:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:54 INFO - 'HOME': '/Users/cltbld', 09:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:54 INFO - 'LOGNAME': 'cltbld', 09:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:54 INFO - 'MOZ_NO_REMOTE': '1', 09:36:54 INFO - 'NO_EM_RESTART': '1', 09:36:54 INFO - 'PAGER': '/bin/cat', 09:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:54 INFO - 'PWD': '/builds/slave/test', 09:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:54 INFO - 'SHELL': '/bin/bash', 09:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:54 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:54 INFO - 'USER': 'cltbld', 09:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:54 INFO - 'XPC_FLAGS': '0x0', 09:36:54 INFO - 'XPC_SERVICE_NAME': '0', 09:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-qVW4sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2Zp8CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-0i9_7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Lg5Ffd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-jmuAz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-mKs15y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-uIYBYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-xqc3lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-u1UbuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-nkB1Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-auuO6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Rkobgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-x3Li9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:55 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)) 09:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:55 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-uoQHky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-WWo7ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:56 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)) 09:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ay0v_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:56 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)) 09:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:56 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-gbhI09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:56 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)) 09:36:56 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)) 09:36:56 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)) 09:36:56 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)) 09:36:56 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)) 09:36:56 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)) 09:36:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:56 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 09:36:56 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 09:36:56 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 09:36:56 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 09:36:56 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 09:36:56 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 09:36:58 INFO - Downloading blessings-1.6.tar.gz 09:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:58 INFO - Installing collected packages: blessings 09:36:58 INFO - Running setup.py install for blessings 09:36:59 INFO - Successfully installed blessings 09:36:59 INFO - Cleaning up... 09:36:59 INFO - Return code: 0 09:36:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:59 INFO - Reading from file tmpfile_stdout 09:36:59 INFO - Current package versions: 09:36:59 INFO - blessings == 1.6 09:36:59 INFO - blobuploader == 1.2.4 09:36:59 INFO - docopt == 0.6.1 09:36:59 INFO - functools32 == 3.2.3-2 09:36:59 INFO - jsonschema == 2.5.1 09:36:59 INFO - manifestparser == 1.1 09:36:59 INFO - mozInstall == 1.12 09:36:59 INFO - mozcrash == 0.17 09:36:59 INFO - mozdebug == 0.1 09:36:59 INFO - mozdevice == 0.48 09:36:59 INFO - mozfile == 1.2 09:36:59 INFO - mozhttpd == 0.7 09:36:59 INFO - mozinfo == 0.9 09:36:59 INFO - mozleak == 0.1 09:36:59 INFO - mozlog == 3.2 09:36:59 INFO - moznetwork == 0.27 09:36:59 INFO - mozprocess == 0.22 09:36:59 INFO - mozprofile == 0.28 09:36:59 INFO - mozrunner == 6.11 09:36:59 INFO - mozscreenshot == 0.1 09:36:59 INFO - mozsystemmonitor == 0.3 09:36:59 INFO - moztest == 0.7 09:36:59 INFO - mozversion == 1.4 09:36:59 INFO - psutil == 3.1.1 09:36:59 INFO - requests == 1.2.3 09:36:59 INFO - wsgiref == 0.1.2 09:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:59 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:59 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 09:36:59 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 09:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:59 INFO - 'HOME': '/Users/cltbld', 09:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:59 INFO - 'LOGNAME': 'cltbld', 09:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:59 INFO - 'MOZ_NO_REMOTE': '1', 09:36:59 INFO - 'NO_EM_RESTART': '1', 09:36:59 INFO - 'PAGER': '/bin/cat', 09:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:59 INFO - 'PWD': '/builds/slave/test', 09:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:59 INFO - 'SHELL': '/bin/bash', 09:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:59 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:59 INFO - 'USER': 'cltbld', 09:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:59 INFO - 'XPC_FLAGS': '0x0', 09:36:59 INFO - 'XPC_SERVICE_NAME': '0', 09:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 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)) 09:36:59 INFO - Cleaning up... 09:36:59 INFO - Return code: 0 09:36:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:59 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 0x10a520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93e9556680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, '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.u0f4AchHqG/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.GNcbkpTEhc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 09:36:59 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 09:36:59 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 09:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:59 INFO - 'HOME': '/Users/cltbld', 09:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:59 INFO - 'LOGNAME': 'cltbld', 09:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:59 INFO - 'MOZ_NO_REMOTE': '1', 09:36:59 INFO - 'NO_EM_RESTART': '1', 09:36:59 INFO - 'PAGER': '/bin/cat', 09:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:59 INFO - 'PWD': '/builds/slave/test', 09:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:59 INFO - 'SHELL': '/bin/bash', 09:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:36:59 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:59 INFO - 'USER': 'cltbld', 09:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:59 INFO - 'XPC_FLAGS': '0x0', 09:36:59 INFO - 'XPC_SERVICE_NAME': '0', 09:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:00 INFO - Cleaning up... 09:37:00 INFO - Return code: 0 09:37:00 INFO - Running post-action listener: _resource_record_post_action 09:37:00 INFO - Running post-action listener: _start_resource_monitoring 09:37:00 INFO - Starting resource monitoring. 09:37:00 INFO - ##### 09:37:00 INFO - ##### Finished create-virtualenv step (success) 09:37:00 INFO - ##### 09:37:00 INFO - ##### 09:37:00 INFO - ##### Running install step. 09:37:00 INFO - ##### 09:37:00 INFO - Running pre-action listener: _resource_record_pre_action 09:37:00 INFO - Running main action method: install 09:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:00 INFO - Reading from file tmpfile_stdout 09:37:00 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:00 INFO - Reading from file tmpfile_stdout 09:37:00 INFO - Output received: 09:37:00 INFO - Usage: mozinstall [options] installer 09:37:00 INFO - Options: 09:37:00 INFO - -h, --help show this help message and exit 09:37:00 INFO - -d DEST, --destination=DEST 09:37:00 INFO - Directory to install application into. [default: 09:37:00 INFO - "/builds/slave/test"] 09:37:00 INFO - --app=APP Application being installed. [default: firefox] 09:37:00 INFO - mkdir: /builds/slave/test/build/application 09:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:37:35 INFO - Reading from file tmpfile_stdout 09:37:35 INFO - Output received: 09:37:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:37:35 INFO - Running post-action listener: _resource_record_post_action 09:37:35 INFO - ##### 09:37:35 INFO - ##### Finished install step (success) 09:37:35 INFO - ##### 09:37:35 INFO - ##### 09:37:35 INFO - ##### Running run-tests step. 09:37:35 INFO - ##### 09:37:35 INFO - Running pre-action listener: _resource_record_pre_action 09:37:35 INFO - Running main action method: run_tests 09:37:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:35 INFO - 2016-06-29 09:37:35.398 screenresolution[1643:7472] starting screenresolution argv=screenresolution get 09:37:35 INFO - 2016-06-29 09:37:35.401 screenresolution[1643:7472] Display 0: 1600x1200x32@60 09:37:35 INFO - 2016-06-29 09:37:35.408 screenresolution[1644:7474] starting screenresolution argv=screenresolution list 09:37:35 INFO - Available Modes on Display 0 09:37:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:35 INFO - Intel Iris: 09:37:35 INFO - Chipset Model: Intel Iris 09:37:35 INFO - Type: GPU 09:37:35 INFO - Bus: Built-In 09:37:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:35 INFO - Vendor: Intel (0x8086) 09:37:35 INFO - Device ID: 0x0a2e 09:37:35 INFO - Revision ID: 0x0009 09:37:35 INFO - Displays: 09:37:35 INFO - Display: 09:37:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:35 INFO - Display Serial Number: 1600x1200 60 09:37:35 INFO - Main Display: Yes 09:37:35 INFO - Mirror: Off 09:37:35 INFO - Online: Yes 09:37:35 INFO - Rotation: Supported 09:37:35 INFO - Return code: 0 09:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:35 INFO - Python 2.7.3 09:37:35 INFO - Return code: 0 09:37:35 INFO - grabbing minidump binary from tooltool 09:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a484d30>, '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 0x10a1e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a47cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:37:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:37:35 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 09:37:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:35 INFO - Return code: 0 09:37:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u0f4AchHqG/Render', 09:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:35 INFO - 'HOME': '/Users/cltbld', 09:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:35 INFO - 'LOGNAME': 'cltbld', 09:37:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:35 INFO - 'MOZ_NO_REMOTE': '1', 09:37:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:35 INFO - 'NO_EM_RESTART': '1', 09:37:35 INFO - 'PAGER': '/bin/cat', 09:37:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:35 INFO - 'PWD': '/builds/slave/test', 09:37:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:35 INFO - 'SHELL': '/bin/bash', 09:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNcbkpTEhc/Listeners', 09:37:35 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 09:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:35 INFO - 'USER': 'cltbld', 09:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:35 INFO - 'XPC_FLAGS': '0x0', 09:37:35 INFO - 'XPC_SERVICE_NAME': '0', 09:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:37:35 INFO - mozversion application_buildid: 20160629072025 09:37:35 INFO - mozversion application_changeset: 7773873116e90a6cbf3de93d571b3df9aaee2e8c 09:37:35 INFO - mozversion application_display_name: Nightly 09:37:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:35 INFO - mozversion application_name: Firefox 09:37:35 INFO - mozversion application_remotingname: firefox 09:37:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:37:35 INFO - mozversion application_vendor: Mozilla 09:37:35 INFO - mozversion application_version: 50.0a1 09:37:35 INFO - mozversion platform_buildid: 20160629072025 09:37:35 INFO - mozversion platform_changeset: 7773873116e90a6cbf3de93d571b3df9aaee2e8c 09:37:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:37:35 INFO - mozversion platform_version: 50.0a1 09:37:35 INFO - using testdate: 1467218255 09:37:35 INFO - actual date: 1467218255 09:37:35 INFO - starting webserver on 'localhost:49266' 09:37:35 INFO - SUITE-START | Running 2 tests 09:37:35 INFO - TEST-START | dromaeo_css 09:37:35 INFO - operating with platform_type : mac_ 09:37:35 INFO - Initialising browser for dromaeo_css test... 09:37:36 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpj3XZiG/profile http://localhost:49266/getInfo.html) 09:37:38 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:37:38 INFO - PROCESS | 1654 | colorDepth:24 09:37:38 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:37:38 INFO - PROCESS | 1654 | __metrics 09:37:38 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:37:38 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:39 INFO - TEST-INFO | 1654: exit 0 09:37:39 INFO - Browser initialized. 09:37:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:39 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpj3XZiG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:50 INFO - PROCESS | 1656 | RSS: Main: 243957760 09:37:50 INFO - PROCESS | 1656 | 09:38:42 INFO - PROCESS | 1656 | 1467218322714 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] 09:40:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 09:40:54 INFO - PROCESS | 1656 | RSS: Main: 366739456 09:40:54 INFO - PROCESS | 1656 | 09:43:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 09:43:58 INFO - PROCESS | 1656 | RSS: Main: 378114048 09:43:58 INFO - PROCESS | 1656 | 09:47:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 09:47:01 INFO - PROCESS | 1656 | RSS: Main: 376406016 09:47:01 INFO - PROCESS | 1656 | 09:50:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 09:50:04 INFO - PROCESS | 1656 | RSS: Main: 374005760 09:50:04 INFO - PROCESS | 1656 | 09:53:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 09:53:07 INFO - PROCESS | 1656 | RSS: Main: 342573056 09:53:07 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 09:56:11 INFO - PROCESS | 1656 | RSS: Main: 414879744 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | __start_tp_report 09:56:11 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:56:11 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:56:11 INFO - PROCESS | 1656 | |i|pagename|runs| 09:56:11 INFO - PROCESS | 1656 | |0;dojo.html;3396.8641254349814;3400.183955850596;3403.540522029526;3428.9485657715154;3481.7214622830174;5700.743466544005;5758.856028599287;5817.563682723791;5882.205902203206;5910.2316698829145;5769.451902069307;5795.768169273235;5915.1718759373725;5933.673647949372;5950.04799232123;5199.688018718889;5212.817551385702;5299.496547827969;5305.363356397238;5419.376771671269;5342.893142137155;5401.4868587484125;5406.297181366436;5420.24116623673;5440.755166017519;5240.292560504321;5246.580273578104;5280.49835265653;5292.735363231862;5304.469553044703;5276.050310944029;5279;5282.550983166436;5306.177542480922;5347.732613369316;5262.552683021948;5274.5780337572905;5325.147976323769;5368.8389348319615;5384.353877534721;5731.312242530923;5789.768409263625;5885.087811389241;5904.881902361929;5978.312494063195;6226.377362263783;6237.906431403532;6240.812775616739;6264.749410023594;6266.843328916813;5281.392639846428;6245.500359971192;6283.120363149163;6296.559240853142;6299.748010079591;5832.6792026438525;5943.405659434108;5993.430624090704;5994.130851026664;6030.09548567714;6262.279837818618;6288.339724328971;6324.936750632527;6327.335629758901;6335.461485773669;6192.690365481708;6231.283402408781;6233.96883015582;6246.937530624636;6256.749730010749;6133.946794548644;6150.938490615036;6182.227221597301;6256;6272.40412160844;6166.352532983972;6188.81433556994;6256.6871656417;6279.686015699197;6328.778492752732;6292.748290068437;6295.18162638854;6309.211348581427;6311.1795466589965;6319.33646967071;4950.2079667253065;5021.6735912165595;5067.391912970394;5071.290019397287;5120.487951204924;4467.419235499429;4489.304157855538;4505.5269196734525;4535.183666939983;4540.523245059288;5762.7694892203845;5768.394318596487;5779.190913272145;5805.622634528742;5837;6185.628862268279;6221.377862213742;6311.495080393557;6332.1135041094285;6348.873022539523;5117.746152192736;5767.625104368202;5837.970810145922;5937.792177273861;5955;5501.229827824108;5520.530754885799;5526.06056970316;5539.889202215933;5549.833504994856;6125.723854043132;6210.441060304594;6252.062190671436;6323.52573556985;6341.8731625368155;6248.562600617913;6260.467860231931;6272.341404152498;6272.843178920563;6300.338464461165;6300.401461861115;6321.399467050623;6324.272708638566;6328.14570033058;6334.556581039283;5080.923786143285;5361.410244873139;6191.4118158774845;6278.246610406781;6307.274663413767;6236.376362363727;6275.435210831047;6283.497320214463;6291.874162516632;6295.18162638854;6116.082587611716;6188.3502232266765;6216.160818289654;6231.283402408781;6234.096056071935;6102.4812890902995;6112.26032301701;6114.57197996136;6135.416406698409;6172.5679202457195;6096.708114891155;6098.2850756173875;6105.186826067751;6107.707416288245;6125.876495711744;6136.2636483622255;6145.477634400947;6146.078088286793;6158.661273629993;6159.414855588712;5903.793367232126;5985.700714964148;6011.188489553854;6042.818715438544;6118.663473509 09:56:11 INFO - PROCESS | 1656 | |1;ext.html;505.3481009497748;511.9206522988936;524.714030853185;533.1336578060651;534.5242733966769;30541.694583054163;30921;31695.99999999997;31709;31787.887423940167;32009.519857202104;36787.63212367882;43259;43970.34044489335;46213.53786462134;9222.631094756218;9422.010688877637;9453.385529940566;9459.574319155654;9474.478903660296;8793.076726943642;9578;9706.902930970706;9709.660161894335;9732.854007189862;8785.692500762474;11904.464299106561;12095.213811102294;12132.696682582962;12293.754124917496;12090.093243006766;12153.088518361159;12163.817542736866;12170;12176.39118044103;8731.563421828883;11478.942605287004;11817.881821181763;11842.644720658394;11915.761684766343;41154.58845411537;41564.79217603893;41570;41737.165256695;41755.956101097414;3432.9828350858334;3471.218975730466;3515.3145136698354;3618.3306088373733;4043.312636851713;31577.526337104962;32330.676693233236;32382;32785;33476;3237.9115470521615;3239.627442844056;3248.545203671488;3264.5103234515013;3388.102152929476;491687.99999999645;492543;493971;494878;496134;59067.99999999914;59328.81342373215;59598.70200649056;59802.40197597969;59814.10278845821;62003.6899815498;62003.6899815498;62032;62093.379066209665;62146;16029;16493.505194844172;16535.83464165343;16541.338346466247;16546.58633534147;12803.807942880865;12855;12876.291803950686;12876.80684789729;12935.28855912915;13732.725345493034;14119.435222591186;14134.85865141356;14288.85711142896;15548.76676849848;5772.365039845614;6894.415832879588;6980;7019.368256856806;7031.960731387942;717.8169566760442;882.7351794461636;902.9097090290918;969.8739163908788;991.8660980767648;29767.958121465646;30136.397272054874;30232.639531221146;30410.39179216403;31817.681823181472;1012.3875055591409;1052.8157572424795;1055.6094245129198;1065.9680209593726;1113.9888601114046;15219.771703424458;16039.518814435585;16079.919600402158;16163.838361616234;16464.588385290463;681136;683921;687363;687951;687957;59271;59572.51068723317;59682.10476842851;59821;59821.40178598245;1393.2337214531967;1438.180237264749;1448.8116544849102;1449.4854326713864;1453.4840131753288;30265.697343026288;30473;30710.539341910335;31329.8433507831;31687.366252675278;50588.24117638165;50612.74693626508;50783;50968.49031509786;50978.74510627423;607240;610117.9494102679;610801.9459902672;611520;611678;33226.833865830515;40429.989250268976;41155;41288;41349.79325103355;48809.51190488192;49051.509484904695;49763;50000.999980000925;50102.49897501124;38746.4188037174;40030;40768.59231407648;41287.587124128375;42738.78630606827;38647.6942769056;38891;39307.80346098251;39448;40295.79852100838;38284;39315.60684393119;39652.603473966046;39671.60328396679;39738.2052358957;29381.97163099281;34799.65200347964;35413.29173416465;35569.11077222987;35643.574257030465;26076.86961565256;26104.73895261023;26119;26296.8685156573;26502.734972650025 09:56:11 INFO - PROCESS | 1656 | |2;jquery.html;346.3056571573994;355.75097431797747;382.24315854607875;383.78124469052653;399.63233824881104;11892.583759568417;13137.605871823836;16465.670686586276;20810.16759329625;21676.2413315534;20210.89894550525;21327.25354612585;21712.457188570294;21743.67384489234;21951.341459756233;5283.788648454057;5675.602707810455;5927.110933360009;5966.820995370146;5987.820365389045;5832.387599302076;5900.498457631106;5908.852278693045;5914.349421563625;5956.7319470623925;5586.720663966806;5841.386654401291;5868;5909.6158749681135;5909.852253693671;5746.942530574683;5752.568557358215;5786.1610066540325;5786.161006654053;5788.1896534484995;5038.269450929594;5055.747212639353;5060.620453466005;5395.622306438551;5407.026735187665;13083.672908177372;13101.803472947913;13286.468541258339;13350.198988060747;13371.53199638008;1935.699966505194;2105.0842883345795;2278.8860556972086;2304.8706133994224;2620.227033025243;8249.83500329996;9739.56171972263;9892.60429582823;9922.751931201778;10355.326903751233;1615.8949668271525;1807.5390775352332;1838.2371315903883;1856.9164387602455;1947.5228569000544;117347;128270;130016.6998330014;130059.34970325087;134744.74594572972;11391.943040284912;11661.300321980709;11723.4138293085;11724.531018759324;11922.10584206188;14694.485693000694;14748.041377310441;14784.63038424048;14813.703725925421;14825.77761333581;6388.520222330878;8451.746447606582;8743.475391476532;8836.248918841971;8842.204201621757;8788.95605521981;8804.295656347476;8815.955920220485;8934.463932164092;8962.955185224033;3923.705722070856;4538.205813982539;4615;4615.123126605934;4705.294205869147;1933.1397528100142;1937.5253062999373;1943.2712732725229;1973.506623344164;1985.851061170418;700.4046560423599;705.7881548186882;730.302561054192;873.785922449004;949.8290307744534;18482.445526634223;21882.562348752937;22202.44493887666;22509.43726406853;22869.19957801469;888.7022847346211;988.7379844341256;990.3414229537422;1007.8236308646104;1019.4902548725637;11254.099672026217;11589.536418543164;11697.76604467906;11746.709391668714;11753.4710938008;600381;614698;618685;627614;628366;113206;118078;119337;119796.40101799609;120294;936.4662142578665;955.1594596754811;969.0842154164388;972.3290929258789;1001.7145113642576;15367.231638418258;17174.39889603858;17536.298548057934;18476.076196190406;18859.057047147315;57627.42372576221;57664.71167644135;57843.42156578381;58842.4115758854;59226.51933701692;80711.59644201741;85686.71469928081;86665.56667216624;86871;87861;8245.216704413071;8288.50268983863;8356.373272004503;8462.280706140047;8567.971843378835;12950.093493455368;13229.206247625174;13466.124701894345;13636.386362613905;13772.449102035805;9511.619535218513;11170;11351.545938162712;11463.598774042868;11514.94242528782;9758.902410976083;11522.769544608893;11710.765784684429;11872.287662740264;11933.880661193278;11765.235259708092;11869.94065029704;11889.524419023142;11989.22070065443;12057.758844823229;8177.4684645497855;8247.092819789937;8348.833023339621;8428.915710842813;8430.494170349799;8316.126806685454;9989.300748947504;10654.946725266323;11149.219554631098;11410.372429516456 09:56:11 INFO - PROCESS | 1656 | |3;mootools.html;1388.3544151969331;1423.8504956979514;1584.350416329305;1805.837474627284;2006.8896210708415;7888.645010974506;8629.26651234645;8866.911330886689;8879.002224499493;8995.370324077317;6981.965090174542;8823.691170809023;8832.955835220831;8833.690820821272;8860.955695221532;2075.2321640992855;2154.687570302305;2157.8165855902266;2359.7404285528537;2393.9162129325478;2174.184680744721;2201.03154611971;2290.129750694742;2380.238323736405;2386.4153282445827;2380.143148466551;2384.5707772600927;2386.4630458146953;2387.200287903546;2387.343480542856;2323.0707716913284;2363.1847012780563;2364.373441038109;2365.5268946210826;2366.325597204797;2086.123827992247;2273.4316420894775;2349.4596242864236;2363.054778088778;2371.27676058802;8872.290216782641;8951.68669096585;8968.551572421418;8973.865392019125;8984.236339911115;4602.700824446434;5202.635815492917;5357.866053348697;5376.327959005124;5386.48828361309;3448.6034106077873;3579.6420357964257;3649.7627654202392;3651.7808931463933;3655.342038433081;996.5764550066193;1029.433811403725;1054.6572363981736;1075.0754351257913;1091.5742860284495;11949.103817213743;12019.278843269345;12019.459124339426;12030.81953770694;12045.457954392074;4173.728707633994;4647.838040489877;4707.364505791675;4796.25658023007;4897.681650692693;10031.899681003242;10081.19350451962;10087.646932357333;10090.899091009143;10103.292769506064;5393.352797664227;5403.162509810985;5410.91883621746;5414.458554144554;5417.783288668488;4403.8238470461465;4724.71651700899;4831.710097394168;4897.191963326082;4998.375203099613;3772.5472943246987;3902.941455878164;3903.921921561553;3911.3546264866545;3919.1377896863214;1244.5208594690928;1284.6916739982337;1308.483149156078;1309.7773378525674;1310.0829419406264;756.9772906812805;867.8524650809378;941.4351389166445;958.8178246133212;961.3991255465335;6101.664408457577;6114.938850611437;6171.352008039181;6172.259328880563;6213;946.4841661294611;949.2074118111454;951.3483263964207;1002.6791426743519;1010.540204207084;4087.6116768906904;4131.338985762323;4147.440095587118;4199.958000419957;4203.369494575838;10637.46812659364;10894.420309055142;10897.346159230472;10980.121590272758;11006.394648294261;10945.90540945899;10953.671389858317;10961.671149865517;10978.725531861608;10980.615678451215;7873.448858579844;8249.546274954935;8252.257296843312;8281.461704989157;8420.8315833683;5583.720813959205;5931.110333450017;6091.664958427329;6118.20463339763;6296.433321001132;10127.949360253151;10350.171986240932;10350.585976560853;10368.377897326185;10374.221933354878;9789.412635241908;10222.284440089123;10373.942117847788;10461.633842815465;10494.632687855888;7621.1616722161625;7693.576853273124;7772.300492955661;7789.493682910593;7797.805054873674;7547.28300811422;7850.293473587404;8305.12796156419;8431.536265505456;8446.873296900429;7399.963000184965;7469.7385591504035;7610.9238907612435;7649.235076492307;7798.727044553413;6440.42036216724;7479.476436649382;7487.064116985377;7643.464957452925;7647.158812530506;6251.874962500816;6811;7143;7209.243029481829;7214.639268036683;4959.9008019838675;5013.473585273639;5035.370578677665;5087.109755792795;5099.796008159632;5103.719295438638;5173.741312934263;5200.583953283653;5229.921551176812;5273.050850846885 09:56:11 INFO - PROCESS | 1656 | |4;prototype.html;269.7733903521042;291.66895573524056;306.188600209445;311.72879594752567;311.8113541307509;4240.809163587642;4264.786760661966;5202.3497062867145;5264.39459462162;5313.548348390392;4939.555440010398;5142.614303927202;5167.328247327842;5172.017316709823;5237.659552129109;3346.514755360471;3575.302815950891;3583.677469027787;3920.4514216884313;3931.6461518463475;3953.169834334793;3970.8014599270064;3985.521737391517;3987.4616926714957;4000.4599379083743;3124.9375412227932;3863.643177661624;3937.4093885917055;3953.2291203215386;3955.9208815823667;3643.8116647933057;3808.552749955027;3893.649571538575;3940.0937784309626;3975.463312452811;2693.501702185102;2920.2407374082677;3061.6479104902996;3246.7240284575605;3408.7954722716718;4139.834406623761;4337.934930976038;5116.2069879168785;5118.667286626357;5141.511556402136;4225.7887105644595;4263.360495925636;4331.047169622678;4494.876280929768;4508.106595230011;4612.215923293048;5367.516923476866;5390.838274851761;5442.619016668835;5445.53712934401;3208.9197770055703;3291.325278317939;3603.5315408996926;3756.605556416564;3771.057235691077;5225.085610018232;5494.32423110951;5670.886582268332;5755.366909639936;5757.942420575824;3594.964050359515;3741.345264578688;3758.9060273492832;3761.5298087739034;3763.8870833875026;3432.541170002739;3819.6753275971855;3833.731638785314;3837.520309961255;3871.8838434847;3829.425586162098;3991.8004099794894;4323.48118225815;4469.25698977399;4472;4269.658427325807;4691.812327506861;5214.165733482625;5475.7262136893;5604.887902241932;1124.100719424457;1263.993680031594;1299.6880748620263;1306.5296493262417;1309.2390734462133;764.6291548598925;786.7333592915402;790.4308897593723;793.90870049945;801.7354273089867;3678.6137455567314;3700.703943684499;3896.2272166164325;4218.2196293685765;4306;3726.9627303727157;4078.9184216315507;4337.436133302714;4664.090502352009;4714.6464015199;3616.8010759408485;3928.508936382952;4255.87232383029;4281.957180428218;4337.0458499130145;2178.3009252782854;2198.6482162853868;2210.027587861336;2519.009982114146;2521.0546045233036;4095.201435720006;4632.490426053131;4716.995277007258;4773.95226047742;4842.806287748521;4013.7591744495426;4440.666949978699;4835.492273311322;4836.564709176191;5117.923231151536;4552.6813123081065;4764.857054288377;5409.348689634481;5693.715314234271;5796.217510636095;4995.950040499549;5271.103912334989;5487;5608.923923848924;5614.747336369792;5499.202615620793;5550.4449555045785;5562.164485719783;5587.776488940397;5596.244506991504;4014.763909604106;5455.918161227664;5646.830595082154;5784.942150578609;5828.766849326149;3709.8516059357326;3724.497192878927;3742.9064273393383;3757.3236817373136;3759.360908645482;4698.177818881613;5141.018209438442;5639.830805075854;5671.546276297969;5718.313802343745;4879.941886499591;5222.7127507987425;5496.4503549646315;5500;5520.66875987442;4542.591796543083;5429.429909859566;5488.945110548843;5491.270249871278;5518.779248830002;4526.298423744325;4760.285957106462;5140.151874940593;5188.299579556711;5366.946330536644;5026.547610715053;5407.297051383295;5490.396056433869;5502.0371434999515;5510.559155267489;4786.856394308176;5141.71720555373;5404.459554044564;5423.701696406735;5470.753816078206 09:56:11 INFO - PROCESS | 1656 | |5;yui.html;694.8540806430652;712.4407340237913;726.7311094894883;780.157429975626;854.6666799948022;15349.769753453693;15785.842141578582;15804;15806.051636901793;16624.66750664986;16054.197290135477;16058.116803575798;16079.356825726956;16080.678386432264;16142.435014774484;721.4444877444365;741.7700512841036;890.7105190813012;894.8926128864529;899.8020435504211;769.9153093159748;779.3297763923022;926.6478738079554;934.2712684106408;935.0229010683852;967.5694316029335;978.021978021978;978.427619842393;979.666913249495;981.224832382417;743.1528058013869;764.5298141642905;771.8533478639097;972.8200282947678;973.3575839945637;559.6977632078673;689.8896176611718;705.4885208224048;709.3544874164486;710.7867639708066;14746.778798318033;14792.630184245372;14798.334074966653;14807.777883331759;14811.407543698238;14160.36278367476;14378.856211437856;14391;14452.421903123863;14547;13020.674483137997;13177.538786142535;13379.665508362272;13599.52401665937;13688.384022719001;4536.206163921301;4590;4623.630109591259;4637.443506779175;4773.403324584427;90288.54855725745;112030;112214;113410;113554;10979.945100274448;11051.502682379312;11147.944260278648;11329.660110196708;11634.941825290989;22387.776122238894;22493.550128997656;22573;22658.320250392513;22669.546609067726;7368.447366447538;7877.842443151219;8452.408331416866;8467.957660211783;8579;4535.138323718483;4580.816767329269;4672.509386514435;4722.315264286659;4726.456457507362;3720.5907350191455;3882.048898020003;3882.5340959085092;3886.417037444406;3887.028242939265;707.989380159298;709.1844378964232;718.5699863412239;730.4594599995964;731.4660297982502;582.8892510423007;587.4566026425624;632.6457183976895;671.1542465128783;711.8149281186825;9111.681091161778;9631.422114673001;9632.325737198329;9710.368826026286;9924.255680823968;498.5936461783635;621.7295476467751;641.7817941899776;647.4173244080271;657.8059472455637;4552.157850797613;4602.631789456902;4670.031558303194;4697.389339385858;4764.690295130806;125456.37271813583;139141;151559;151940.40297985057;157917.73534720208;128539;131203.34398328137;131415;132611;135652.3217383907;84436.57781711052;100421.95780422192;112329.43835280772;115888.42055789666;115978.42010789891;8729.86511753468;8799.956000219958;9523.428594284365;9929.156021738234;9979.151772099453;62100.37899620946;62769.74460510856;65139.67430163008;65316.65495992263;65614;93929.5303523478;97768;98804;99698;101205.49397252967;13206.933965330112;13247.403866825824;13352.99852511046;13396.464141434233;13859.861401385857;14953.02805317651;14980.775288370902;15032.473863414729;15342.23288835576;15572.29924653416;13673.931630341785;13909.930450347683;14197.574072777834;14205.28973551339;14370.209638469567;13703.45186192541;13874.375653095432;13974.510892118727;14609.488667896572;14831.33259003369;13694.000837370466;13878.514252001132;13884.930575347058;13892.374843132286;13911.443542258196;11252.212345135762;11359.318440893572;11374.530508821435;11545.076393888638;11689.766204676029;9973.501324933579;11371.943140284246;11397.772044559228;11518.424078796193;11620.941895290469 09:56:11 INFO - PROCESS | 1656 | __end_tp_report 09:56:11 INFO - PROCESS | 1656 | __start_cc_report 09:56:11 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1355 09:56:11 INFO - PROCESS | 1656 | __end_cc_report 09:56:11 INFO - PROCESS | 1656 | __startTimestamp1467219371568__endTimestamp 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:56:11 INFO - PROCESS | 1656 | Number of tests: 6 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5804.81 Median:6096.71 stddev:624.56 (10.2%) stddev-sans-first:597.23 09:56:11 INFO - PROCESS | 1656 | Values: 3396.9 3400.2 3403.5 3428.9 3481.7 5700.7 5758.9 5817.6 5882.2 5910.2 5769.5 5795.8 5915.2 5933.7 5950.0 5199.7 5212.8 5299.5 5305.4 5419.4 5342.9 5401.5 5406.3 5420.2 5440.8 5240.3 5246.6 5280.5 5292.7 5304.5 5276.1 5279.0 5282.6 5306.2 5347.7 5262.6 5274.6 5325.1 5368.8 5384.4 5731.3 5789.8 5885.1 5904.9 5978.3 6226.4 6237.9 6240.8 6264.7 6266.8 5281.4 6245.5 6283.1 6296.6 6299.7 5832.7 5943.4 5993.4 5994.1 6030.1 6262.3 6288.3 6324.9 6327.3 6335.5 6192.7 6231.3 6234.0 6246.9 6256.7 6133.9 6150.9 6182.2 6256.0 6272.4 6166.4 6188.8 6256.7 6279.7 6328.8 6292.7 6295.2 6309.2 6311.2 6319.3 4950.2 5021.7 5067.4 5071.3 5120.5 4467.4 4489.3 4505.5 4535.2 4540.5 5762.8 5768.4 5779.2 5805.6 5837.0 6185.6 6221.4 6311.5 6332.1 6348.9 5117.7 5767.6 5838.0 5937.8 5955.0 5501.2 5520.5 5526.1 5539.9 5549.8 6125.7 6210.4 6252.1 6323.5 6341.9 6248.6 6260.5 6272.3 6272.8 6300.3 6300.4 6321.4 6324.3 6328.1 6334.6 5080.9 5361.4 6191.4 6278.2 6307.3 6236.4 6275.4 6283.5 6291.9 6295.2 6116.1 6188.4 6216.2 6231.3 6234.1 6102.5 6112.3 6114.6 6135.4 6172.6 6096.7 6098.3 6105.2 6107.7 6125.9 6136.3 6145.5 6146.1 6158.7 6159.4 5903.8 5985.7 6011.2 6042.8 6118.7 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:73017.59 Median:39381.80 stddev:160964.58 (408.7%) stddev-sans-first:161324.06 09:56:11 INFO - PROCESS | 1656 | Values: 505.3 511.9 524.7 533.1 534.5 30541.7 30921.0 31696.0 31709.0 31787.9 32009.5 36787.6 43259.0 43970.3 46213.5 9222.6 9422.0 9453.4 9459.6 9474.5 8793.1 9578.0 9706.9 9709.7 9732.9 8785.7 11904.5 12095.2 12132.7 12293.8 12090.1 12153.1 12163.8 12170.0 12176.4 8731.6 11478.9 11817.9 11842.6 11915.8 41154.6 41564.8 41570.0 41737.2 41756.0 3433.0 3471.2 3515.3 3618.3 4043.3 31577.5 32330.7 32382.0 32785.0 33476.0 3237.9 3239.6 3248.5 3264.5 3388.1 491688.0 492543.0 493971.0 494878.0 496134.0 59068.0 59328.8 59598.7 59802.4 59814.1 62003.7 62003.7 62032.0 62093.4 62146.0 16029.0 16493.5 16535.8 16541.3 16546.6 12803.8 12855.0 12876.3 12876.8 12935.3 13732.7 14119.4 14134.9 14288.9 15548.8 5772.4 6894.4 6980.0 7019.4 7032.0 717.8 882.7 902.9 969.9 991.9 29768.0 30136.4 30232.6 30410.4 31817.7 1012.4 1052.8 1055.6 1066.0 1114.0 15219.8 16039.5 16079.9 16163.8 16464.6 681136.0 683921.0 687363.0 687951.0 687957.0 59271.0 59572.5 59682.1 59821.0 59821.4 1393.2 1438.2 1448.8 1449.5 1453.5 30265.7 30473.0 30710.5 31329.8 31687.4 50588.2 50612.7 50783.0 50968.5 50978.7 607240.0 610117.9 610801.9 611520.0 611678.0 33226.8 40430.0 41155.0 41288.0 41349.8 48809.5 49051.5 49763.0 50001.0 50102.5 38746.4 40030.0 40768.6 41287.6 42738.8 38647.7 38891.0 39307.8 39448.0 40295.8 38284.0 39315.6 39652.6 39671.6 39738.2 29382.0 34799.7 35413.3 35569.1 35643.6 26076.9 26104.7 26119.0 26296.9 26502.7 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35557.06 Median:351.03 stddev:103166.09 (29389.7%) stddev-sans-first:103421.62 09:56:11 INFO - PROCESS | 1656 | Values: 346.3 355.8 382.2 383.8 399.6 11892.6 13137.6 16465.7 20810.2 21676.2 20210.9 21327.3 21712.5 21743.7 21951.3 5283.8 5675.6 5927.1 5966.8 5987.8 5832.4 5900.5 5908.9 5914.3 5956.7 5586.7 5841.4 5868.0 5909.6 5909.9 5746.9 5752.6 5786.2 5786.2 5788.2 5038.3 5055.7 5060.6 5395.6 5407.0 13083.7 13101.8 13286.5 13350.2 13371.5 1935.7 2105.1 2278.9 2304.9 2620.2 8249.8 9739.6 9892.6 9922.8 10355.3 1615.9 1807.5 1838.2 1856.9 1947.5 117347.0 128270.0 130016.7 130059.3 134744.7 11391.9 11661.3 11723.4 11724.5 11922.1 14694.5 14748.0 14784.6 14813.7 14825.8 6388.5 8451.7 8743.5 8836.2 8842.2 8789.0 8804.3 8816.0 8934.5 8963.0 3923.7 4538.2 4615.0 4615.1 4705.3 1933.1 1937.5 1943.3 1973.5 1985.9 700.4 705.8 730.3 873.8 949.8 18482.4 21882.6 22202.4 22509.4 22869.2 888.7 988.7 990.3 1007.8 1019.5 11254.1 11589.5 11697.8 11746.7 11753.5 600381.0 614698.0 618685.0 627614.0 628366.0 113206.0 118078.0 119337.0 119796.4 120294.0 936.5 955.2 969.1 972.3 1001.7 15367.2 17174.4 17536.3 18476.1 18859.1 57627.4 57664.7 57843.4 58842.4 59226.5 80711.6 85686.7 86665.6 86871.0 87861.0 8245.2 8288.5 8356.4 8462.3 8568.0 12950.1 13229.2 13466.1 13636.4 13772.4 9511.6 11170.0 11351.5 11463.6 11514.9 9758.9 11522.8 11710.8 11872.3 11933.9 11765.2 11869.9 11889.5 11989.2 12057.8 8177.5 8247.1 8348.8 8428.9 8430.5 8316.1 9989.3 10654.9 11149.2 11410.4 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5776.15 Median:4760.49 stddev:3267.15 (68.6%) stddev-sans-first:3259.67 09:56:11 INFO - PROCESS | 1656 | Values: 1388.4 1423.9 1584.4 1805.8 2006.9 7888.6 8629.3 8866.9 8879.0 8995.4 6982.0 8823.7 8833.0 8833.7 8861.0 2075.2 2154.7 2157.8 2359.7 2393.9 2174.2 2201.0 2290.1 2380.2 2386.4 2380.1 2384.6 2386.5 2387.2 2387.3 2323.1 2363.2 2364.4 2365.5 2366.3 2086.1 2273.4 2349.5 2363.1 2371.3 8872.3 8951.7 8968.6 8973.9 8984.2 4602.7 5202.6 5357.9 5376.3 5386.5 3448.6 3579.6 3649.8 3651.8 3655.3 996.6 1029.4 1054.7 1075.1 1091.6 11949.1 12019.3 12019.5 12030.8 12045.5 4173.7 4647.8 4707.4 4796.3 4897.7 10031.9 10081.2 10087.6 10090.9 10103.3 5393.4 5403.2 5410.9 5414.5 5417.8 4403.8 4724.7 4831.7 4897.2 4998.4 3772.5 3902.9 3903.9 3911.4 3919.1 1244.5 1284.7 1308.5 1309.8 1310.1 757.0 867.9 941.4 958.8 961.4 6101.7 6114.9 6171.4 6172.3 6213.0 946.5 949.2 951.3 1002.7 1010.5 4087.6 4131.3 4147.4 4200.0 4203.4 10637.5 10894.4 10897.3 10980.1 11006.4 10945.9 10953.7 10961.7 10978.7 10980.6 7873.4 8249.5 8252.3 8281.5 8420.8 5583.7 5931.1 6091.7 6118.2 6296.4 10127.9 10350.2 10350.6 10368.4 10374.2 9789.4 10222.3 10373.9 10461.6 10494.6 7621.2 7693.6 7772.3 7789.5 7797.8 7547.3 7850.3 8305.1 8431.5 8446.9 7400.0 7469.7 7610.9 7649.2 7798.7 6440.4 7479.5 7487.1 7643.5 7647.2 6251.9 6811.0 7143.0 7209.2 7214.6 4959.9 5013.5 5035.4 5087.1 5099.8 5103.7 5173.7 5200.6 5229.9 5273.1 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4215.48 Median:4501.49 stddev:1333.00 (29.6%) stddev-sans-first:1303.42 09:56:11 INFO - PROCESS | 1656 | Values: 269.8 291.7 306.2 311.7 311.8 4240.8 4264.8 5202.3 5264.4 5313.5 4939.6 5142.6 5167.3 5172.0 5237.7 3346.5 3575.3 3583.7 3920.5 3931.6 3953.2 3970.8 3985.5 3987.5 4000.5 3124.9 3863.6 3937.4 3953.2 3955.9 3643.8 3808.6 3893.6 3940.1 3975.5 2693.5 2920.2 3061.6 3246.7 3408.8 4139.8 4337.9 5116.2 5118.7 5141.5 4225.8 4263.4 4331.0 4494.9 4508.1 4612.2 5367.5 5390.8 5442.6 5445.5 3208.9 3291.3 3603.5 3756.6 3771.1 5225.1 5494.3 5670.9 5755.4 5757.9 3595.0 3741.3 3758.9 3761.5 3763.9 3432.5 3819.7 3833.7 3837.5 3871.9 3829.4 3991.8 4323.5 4469.3 4472.0 4269.7 4691.8 5214.2 5475.7 5604.9 1124.1 1264.0 1299.7 1306.5 1309.2 764.6 786.7 790.4 793.9 801.7 3678.6 3700.7 3896.2 4218.2 4306.0 3727.0 4078.9 4337.4 4664.1 4714.6 3616.8 3928.5 4255.9 4282.0 4337.0 2178.3 2198.6 2210.0 2519.0 2521.1 4095.2 4632.5 4717.0 4774.0 4842.8 4013.8 4440.7 4835.5 4836.6 5117.9 4552.7 4764.9 5409.3 5693.7 5796.2 4996.0 5271.1 5487.0 5608.9 5614.7 5499.2 5550.4 5562.2 5587.8 5596.2 4014.8 5455.9 5646.8 5784.9 5828.8 3709.9 3724.5 3742.9 3757.3 3759.4 4698.2 5141.0 5639.8 5671.5 5718.3 4879.9 5222.7 5496.5 5500.0 5520.7 4542.6 5429.4 5488.9 5491.3 5518.8 4526.3 4760.3 5140.2 5188.3 5366.9 5026.5 5407.3 5490.4 5502.0 5510.6 4786.9 5141.7 5404.5 5423.7 5470.8 09:56:11 INFO - PROCESS | 1656 | 09:56:11 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25198.42 Median:3884.48 stddev:39564.80 (1018.5%) stddev-sans-first:39633.01 09:56:11 INFO - PROCESS | 1656 | Values: 694.9 712.4 726.7 780.2 854.7 15349.8 15785.8 15804.0 15806.1 16624.7 16054.2 16058.1 16079.4 16080.7 16142.4 721.4 741.8 890.7 894.9 899.8 769.9 779.3 926.6 934.3 935.0 967.6 978.0 978.4 979.7 981.2 743.2 764.5 771.9 972.8 973.4 559.7 689.9 705.5 709.4 710.8 14746.8 14792.6 14798.3 14807.8 14811.4 14160.4 14378.9 14391.0 14452.4 14547.0 13020.7 13177.5 13379.7 13599.5 13688.4 4536.2 4590.0 4623.6 4637.4 4773.4 90288.5 112030.0 112214.0 113410.0 113554.0 10979.9 11051.5 11147.9 11329.7 11634.9 22387.8 22493.6 22573.0 22658.3 22669.5 7368.4 7877.8 8452.4 8468.0 8579.0 4535.1 4580.8 4672.5 4722.3 4726.5 3720.6 3882.0 3882.5 3886.4 3887.0 708.0 709.2 718.6 730.5 731.5 582.9 587.5 632.6 671.2 711.8 9111.7 9631.4 9632.3 9710.4 9924.3 498.6 621.7 641.8 647.4 657.8 4552.2 4602.6 4670.0 4697.4 4764.7 125456.4 139141.0 151559.0 151940.4 157917.7 128539.0 131203.3 131415.0 132611.0 135652.3 84436.6 100422.0 112329.4 115888.4 115978.4 8729.9 8800.0 9523.4 9929.2 9979.2 62100.4 62769.7 65139.7 65316.7 65614.0 93929.5 97768.0 98804.0 99698.0 101205.5 13206.9 13247.4 13353.0 13396.5 13859.9 14953.0 14980.8 15032.5 15342.2 15572.3 13673.9 13909.9 14197.6 14205.3 14370.2 13703.5 13874.4 13974.5 14609.5 14831.3 13694.0 13878.5 13884.9 13892.4 13911.4 11252.2 11359.3 11374.5 11545.1 11689.8 9973.5 11371.9 11397.8 11518.4 11620.9 09:56:11 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:56:11 INFO - PROCESS | 1656 | 09:56:12 INFO - TEST-INFO | 1656: exit 0 09:56:12 INFO - TEST-OK | dromaeo_css | took 1116188ms 09:56:12 INFO - TEST-START | kraken 09:56:12 INFO - operating with platform_type : mac_ 09:56:12 INFO - Initialising browser for kraken test... 09:56:12 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpUPMlkv/profile http://localhost:49266/getInfo.html) 09:56:14 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:56:14 INFO - PROCESS | 1663 | colorDepth:24 09:56:14 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:56:14 INFO - PROCESS | 1663 | __metrics 09:56:14 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:56:14 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:15 INFO - TEST-INFO | 1663: exit 0 09:56:15 INFO - Browser initialized. 09:56:15 INFO - Running cycle 1/1 for kraken test... 09:56:15 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpUPMlkv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:27 INFO - PROCESS | 1664 | RSS: Main: 243269632 09:56:27 INFO - PROCESS | 1664 | 09:56:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:30 INFO - PROCESS | 1664 | RSS: Main: 293597184 09:56:30 INFO - PROCESS | 1664 | 09:56:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 09:56:34 INFO - PROCESS | 1664 | RSS: Main: 304271360 09:56:34 INFO - PROCESS | 1664 | 09:56:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 09:56:38 INFO - PROCESS | 1664 | RSS: Main: 389341184 09:56:38 INFO - PROCESS | 1664 | 09:56:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 09:56:41 INFO - PROCESS | 1664 | RSS: Main: 288894976 09:56:41 INFO - PROCESS | 1664 | 09:56:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:43 INFO - PROCESS | 1664 | RSS: Main: 292630528 09:56:43 INFO - PROCESS | 1664 | 09:56:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:47 INFO - PROCESS | 1664 | RSS: Main: 329662464 09:56:47 INFO - PROCESS | 1664 | 09:56:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:50 INFO - PROCESS | 1664 | RSS: Main: 332271616 09:56:50 INFO - PROCESS | 1664 | 09:56:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 09:56:54 INFO - PROCESS | 1664 | RSS: Main: 329232384 09:56:54 INFO - PROCESS | 1664 | 09:56:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:56:56 INFO - PROCESS | 1664 | RSS: Main: 311234560 09:56:56 INFO - PROCESS | 1664 | 09:56:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:56:58 INFO - PROCESS | 1664 | RSS: Main: 317718528 09:56:58 INFO - PROCESS | 1664 | 09:57:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:01 INFO - PROCESS | 1664 | RSS: Main: 321736704 09:57:01 INFO - PROCESS | 1664 | 09:57:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:05 INFO - PROCESS | 1664 | RSS: Main: 324435968 09:57:05 INFO - PROCESS | 1664 | 09:57:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:08 INFO - PROCESS | 1664 | RSS: Main: 325091328 09:57:08 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 09:57:10 INFO - PROCESS | 1664 | RSS: Main: 368607232 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | __start_tp_report 09:57:10 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 09:57:10 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 09:57:10 INFO - PROCESS | 1664 | |i|pagename|runs| 09:57:10 INFO - PROCESS | 1664 | |0;ai-astar;102;164;165;113;113;113;112;110;113;112 09:57:10 INFO - PROCESS | 1664 | |1;audio-beat-detection;157;122;125;201;140;121;121;142;156;123 09:57:10 INFO - PROCESS | 1664 | |2;audio-dft;182;183;183;183;183;182;182;184;181;182 09:57:10 INFO - PROCESS | 1664 | |3;audio-fft;79;74;57;88;58;58;63;65;82;71 09:57:10 INFO - PROCESS | 1664 | |4;audio-oscillator;86;90;93;73;70;70;95;69;68;101 09:57:10 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;85;90;86;88;86;90;86;89;85 09:57:10 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;95;95;93;94;95;95;95;101;94 09:57:10 INFO - PROCESS | 1664 | |7;imaging-desaturate;83;91;72;124;134;137;86;85;87;94 09:57:10 INFO - PROCESS | 1664 | |8;json-parse-financial;55;54;55;56;54;54;54;54;54;54 09:57:10 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;76;74;77;75;71;69;73;71;72 09:57:10 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;85;79;84;84;84;88;85;87;100;86 09:57:10 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;138;142;138;131;134;139;137;156;122 09:57:10 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;168;166;169;167;167;167;165;212;158;196 09:57:10 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;65;66;67;66;66;64;67;63;66 09:57:10 INFO - PROCESS | 1664 | __end_tp_report 09:57:10 INFO - PROCESS | 1664 | __start_cc_report 09:57:10 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1984 09:57:10 INFO - PROCESS | 1664 | __end_cc_report 09:57:10 INFO - PROCESS | 1664 | __startTimestamp1467219430600__endTimestamp 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | ------- Summary: start ------- 09:57:10 INFO - PROCESS | 1664 | Number of tests: 14 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:121.70 Median:113.00 stddev:22.80 (20.2%) stddev-sans-first:23.05 09:57:10 INFO - PROCESS | 1664 | Values: 102.0 164.0 165.0 113.0 113.0 113.0 112.0 110.0 113.0 112.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:141.00 stddev:25.44 (18.0%) stddev-sans-first:26.30 09:57:10 INFO - PROCESS | 1664 | Values: 157.0 122.0 125.0 201.0 140.0 121.0 121.0 142.0 156.0 123.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 09:57:10 INFO - PROCESS | 1664 | Values: 182.0 183.0 183.0 183.0 183.0 182.0 182.0 184.0 181.0 182.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:69.50 Median:72.50 stddev:11.03 (15.2%) stddev-sans-first:11.15 09:57:10 INFO - PROCESS | 1664 | Values: 79.0 74.0 57.0 88.0 58.0 58.0 63.0 65.0 82.0 71.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:79.50 stddev:12.75 (16.0%) stddev-sans-first:13.42 09:57:10 INFO - PROCESS | 1664 | Values: 86.0 90.0 93.0 73.0 70.0 70.0 95.0 69.0 68.0 101.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:2.01 (2.3%) stddev-sans-first:2.05 09:57:10 INFO - PROCESS | 1664 | Values: 89.0 85.0 90.0 86.0 88.0 86.0 90.0 86.0 89.0 85.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:2.18 (2.3%) stddev-sans-first:2.28 09:57:10 INFO - PROCESS | 1664 | Values: 94.0 95.0 95.0 93.0 94.0 95.0 95.0 95.0 101.0 94.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:99.30 Median:85.50 stddev:23.28 (27.2%) stddev-sans-first:23.93 09:57:10 INFO - PROCESS | 1664 | Values: 83.0 91.0 72.0 124.0 134.0 137.0 86.0 85.0 87.0 94.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 09:57:10 INFO - PROCESS | 1664 | Values: 55.0 54.0 55.0 56.0 54.0 54.0 54.0 54.0 54.0 54.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.50 stddev:3.34 (4.5%) stddev-sans-first:2.62 09:57:10 INFO - PROCESS | 1664 | Values: 66.0 76.0 74.0 77.0 75.0 71.0 69.0 73.0 71.0 72.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.00 stddev:5.41 (6.4%) stddev-sans-first:5.72 09:57:10 INFO - PROCESS | 1664 | Values: 85.0 79.0 84.0 84.0 84.0 88.0 85.0 87.0 100.0 86.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:137.40 Median:138.00 stddev:8.57 (6.2%) stddev-sans-first:9.08 09:57:10 INFO - PROCESS | 1664 | Values: 137.0 138.0 142.0 138.0 131.0 134.0 139.0 137.0 156.0 122.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:167.50 stddev:16.78 (10.0%) stddev-sans-first:17.68 09:57:10 INFO - PROCESS | 1664 | Values: 168.0 166.0 169.0 167.0 167.0 167.0 165.0 212.0 158.0 196.0 09:57:10 INFO - PROCESS | 1664 | 09:57:10 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 09:57:10 INFO - PROCESS | 1664 | Values: 66.0 65.0 66.0 67.0 66.0 66.0 64.0 67.0 63.0 66.0 09:57:10 INFO - PROCESS | 1664 | -------- Summary: end -------- 09:57:10 INFO - PROCESS | 1664 | 09:57:11 INFO - TEST-INFO | 1664: exit 0 09:57:11 INFO - TEST-OK | kraken | took 59237ms 09:57:11 INFO - SUITE-END | took 1175s 09:57:11 INFO - Completed test suite (00:19:35) 09:57:11 INFO - javascript benchmark 09:57:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.8641254349814, 3400.183955850596, 3403.540522029526, 3428.9485657715154, 3481.7214622830174, 5700.743466544005, 5758.856028599287, 5817.563682723791, 5882.205902203206, 5910.2316698829145, 5769.451902069307, 5795.768169273235, 5915.1718759373725, 5933.673647949372, 5950.04799232123, 5199.688018718889, 5212.817551385702, 5299.496547827969, 5305.363356397238, 5419.376771671269, 5342.893142137155, 5401.4868587484125, 5406.297181366436, 5420.24116623673, 5440.755166017519, 5240.292560504321, 5246.580273578104, 5280.49835265653, 5292.735363231862, 5304.469553044703, 5276.050310944029, 5279.0, 5282.550983166436, 5306.177542480922, 5347.732613369316, 5262.552683021948, 5274.5780337572905, 5325.147976323769, 5368.8389348319615, 5384.353877534721, 5731.312242530923, 5789.768409263625, 5885.087811389241, 5904.881902361929, 5978.312494063195, 6226.377362263783, 6237.906431403532, 6240.812775616739, 6264.749410023594, 6266.843328916813, 5281.392639846428, 6245.500359971192, 6283.120363149163, 6296.559240853142, 6299.748010079591, 5832.6792026438525, 5943.405659434108, 5993.430624090704, 5994.130851026664, 6030.09548567714, 6262.279837818618, 6288.339724328971, 6324.936750632527, 6327.335629758901, 6335.461485773669, 6192.690365481708, 6231.283402408781, 6233.96883015582, 6246.937530624636, 6256.749730010749, 6133.946794548644, 6150.938490615036, 6182.227221597301, 6256.0, 6272.40412160844, 6166.352532983972, 6188.81433556994, 6256.6871656417, 6279.686015699197, 6328.778492752732, 6292.748290068437, 6295.18162638854, 6309.211348581427, 6311.1795466589965, 6319.33646967071, 4950.2079667253065, 5021.6735912165595, 5067.391912970394, 5071.290019397287, 5120.487951204924, 4467.419235499429, 4489.304157855538, 4505.5269196734525, 4535.183666939983, 4540.523245059288, 5762.7694892203845, 5768.394318596487, 5779.190913272145, 5805.622634528742, 5837.0, 6185.628862268279, 6221.377862213742, 6311.495080393557, 6332.1135041094285, 6348.873022539523, 5117.746152192736, 5767.625104368202, 5837.970810145922, 5937.792177273861, 5955.0, 5501.229827824108, 5520.530754885799, 5526.06056970316, 5539.889202215933, 5549.833504994856, 6125.723854043132, 6210.441060304594, 6252.062190671436, 6323.52573556985, 6341.8731625368155, 6248.562600617913, 6260.467860231931, 6272.341404152498, 6272.843178920563, 6300.338464461165, 6300.401461861115, 6321.399467050623, 6324.272708638566, 6328.14570033058, 6334.556581039283, 5080.923786143285, 5361.410244873139, 6191.4118158774845, 6278.246610406781, 6307.274663413767, 6236.376362363727, 6275.435210831047, 6283.497320214463, 6291.874162516632, 6295.18162638854, 6116.082587611716, 6188.3502232266765, 6216.160818289654, 6231.283402408781, 6234.096056071935, 6102.4812890902995, 6112.26032301701, 6114.57197996136, 6135.416406698409, 6172.5679202457195, 6096.708114891155, 6098.2850756173875, 6105.186826067751, 6107.707416288245, 6125.876495711744, 6136.2636483622255, 6145.477634400947, 6146.078088286793, 6158.661273629993, 6159.414855588712, 5903.793367232126, 5985.700714964148, 6011.188489553854, 6042.818715438544, 6118.663473509], "value": 5766.402498965953, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.3481009497748, 511.9206522988936, 524.714030853185, 533.1336578060651, 534.5242733966769, 30541.694583054163, 30921.0, 31695.99999999997, 31709.0, 31787.887423940167, 32009.519857202104, 36787.63212367882, 43259.0, 43970.34044489335, 46213.53786462134, 9222.631094756218, 9422.010688877637, 9453.385529940566, 9459.574319155654, 9474.478903660296, 8793.076726943642, 9578.0, 9706.902930970706, 9709.660161894335, 9732.854007189862, 8785.692500762474, 11904.464299106561, 12095.213811102294, 12132.696682582962, 12293.754124917496, 12090.093243006766, 12153.088518361159, 12163.817542736866, 12170.0, 12176.39118044103, 8731.563421828883, 11478.942605287004, 11817.881821181763, 11842.644720658394, 11915.761684766343, 41154.58845411537, 41564.79217603893, 41570.0, 41737.165256695, 41755.956101097414, 3432.9828350858334, 3471.218975730466, 3515.3145136698354, 3618.3306088373733, 4043.312636851713, 31577.526337104962, 32330.676693233236, 32382.0, 32785.0, 33476.0, 3237.9115470521615, 3239.627442844056, 3248.545203671488, 3264.5103234515013, 3388.102152929476, 491687.99999999645, 492543.0, 493971.0, 494878.0, 496134.0, 59067.99999999914, 59328.81342373215, 59598.70200649056, 59802.40197597969, 59814.10278845821, 62003.6899815498, 62003.6899815498, 62032.0, 62093.379066209665, 62146.0, 16029.0, 16493.505194844172, 16535.83464165343, 16541.338346466247, 16546.58633534147, 12803.807942880865, 12855.0, 12876.291803950686, 12876.80684789729, 12935.28855912915, 13732.725345493034, 14119.435222591186, 14134.85865141356, 14288.85711142896, 15548.76676849848, 5772.365039845614, 6894.415832879588, 6980.0, 7019.368256856806, 7031.960731387942, 717.8169566760442, 882.7351794461636, 902.9097090290918, 969.8739163908788, 991.8660980767648, 29767.958121465646, 30136.397272054874, 30232.639531221146, 30410.39179216403, 31817.681823181472, 1012.3875055591409, 1052.8157572424795, 1055.6094245129198, 1065.9680209593726, 1113.9888601114046, 15219.771703424458, 16039.518814435585, 16079.919600402158, 16163.838361616234, 16464.588385290463, 681136.0, 683921.0, 687363.0, 687951.0, 687957.0, 59271.0, 59572.51068723317, 59682.10476842851, 59821.0, 59821.40178598245, 1393.2337214531967, 1438.180237264749, 1448.8116544849102, 1449.4854326713864, 1453.4840131753288, 30265.697343026288, 30473.0, 30710.539341910335, 31329.8433507831, 31687.366252675278, 50588.24117638165, 50612.74693626508, 50783.0, 50968.49031509786, 50978.74510627423, 607240.0, 610117.9494102679, 610801.9459902672, 611520.0, 611678.0, 33226.833865830515, 40429.989250268976, 41155.0, 41288.0, 41349.79325103355, 48809.51190488192, 49051.509484904695, 49763.0, 50000.999980000925, 50102.49897501124, 38746.4188037174, 40030.0, 40768.59231407648, 41287.587124128375, 42738.78630606827, 38647.6942769056, 38891.0, 39307.80346098251, 39448.0, 40295.79852100838, 38284.0, 39315.60684393119, 39652.603473966046, 39671.60328396679, 39738.2052358957, 29381.97163099281, 34799.65200347964, 35413.29173416465, 35569.11077222987, 35643.574257030465, 26076.86961565256, 26104.73895261023, 26119.0, 26296.8685156573, 26502.734972650025], "value": 20466.9578059573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.3056571573994, 355.75097431797747, 382.24315854607875, 383.78124469052653, 399.63233824881104, 11892.583759568417, 13137.605871823836, 16465.670686586276, 20810.16759329625, 21676.2413315534, 20210.89894550525, 21327.25354612585, 21712.457188570294, 21743.67384489234, 21951.341459756233, 5283.788648454057, 5675.602707810455, 5927.110933360009, 5966.820995370146, 5987.820365389045, 5832.387599302076, 5900.498457631106, 5908.852278693045, 5914.349421563625, 5956.7319470623925, 5586.720663966806, 5841.386654401291, 5868.0, 5909.6158749681135, 5909.852253693671, 5746.942530574683, 5752.568557358215, 5786.1610066540325, 5786.161006654053, 5788.1896534484995, 5038.269450929594, 5055.747212639353, 5060.620453466005, 5395.622306438551, 5407.026735187665, 13083.672908177372, 13101.803472947913, 13286.468541258339, 13350.198988060747, 13371.53199638008, 1935.699966505194, 2105.0842883345795, 2278.8860556972086, 2304.8706133994224, 2620.227033025243, 8249.83500329996, 9739.56171972263, 9892.60429582823, 9922.751931201778, 10355.326903751233, 1615.8949668271525, 1807.5390775352332, 1838.2371315903883, 1856.9164387602455, 1947.5228569000544, 117347.0, 128270.0, 130016.6998330014, 130059.34970325087, 134744.74594572972, 11391.943040284912, 11661.300321980709, 11723.4138293085, 11724.531018759324, 11922.10584206188, 14694.485693000694, 14748.041377310441, 14784.63038424048, 14813.703725925421, 14825.77761333581, 6388.520222330878, 8451.746447606582, 8743.475391476532, 8836.248918841971, 8842.204201621757, 8788.95605521981, 8804.295656347476, 8815.955920220485, 8934.463932164092, 8962.955185224033, 3923.705722070856, 4538.205813982539, 4615.0, 4615.123126605934, 4705.294205869147, 1933.1397528100142, 1937.5253062999373, 1943.2712732725229, 1973.506623344164, 1985.851061170418, 700.4046560423599, 705.7881548186882, 730.302561054192, 873.785922449004, 949.8290307744534, 18482.445526634223, 21882.562348752937, 22202.44493887666, 22509.43726406853, 22869.19957801469, 888.7022847346211, 988.7379844341256, 990.3414229537422, 1007.8236308646104, 1019.4902548725637, 11254.099672026217, 11589.536418543164, 11697.76604467906, 11746.709391668714, 11753.4710938008, 600381.0, 614698.0, 618685.0, 627614.0, 628366.0, 113206.0, 118078.0, 119337.0, 119796.40101799609, 120294.0, 936.4662142578665, 955.1594596754811, 969.0842154164388, 972.3290929258789, 1001.7145113642576, 15367.231638418258, 17174.39889603858, 17536.298548057934, 18476.076196190406, 18859.057047147315, 57627.42372576221, 57664.71167644135, 57843.42156578381, 58842.4115758854, 59226.51933701692, 80711.59644201741, 85686.71469928081, 86665.56667216624, 86871.0, 87861.0, 8245.216704413071, 8288.50268983863, 8356.373272004503, 8462.280706140047, 8567.971843378835, 12950.093493455368, 13229.206247625174, 13466.124701894345, 13636.386362613905, 13772.449102035805, 9511.619535218513, 11170.0, 11351.545938162712, 11463.598774042868, 11514.94242528782, 9758.902410976083, 11522.769544608893, 11710.765784684429, 11872.287662740264, 11933.880661193278, 11765.235259708092, 11869.94065029704, 11889.524419023142, 11989.22070065443, 12057.758844823229, 8177.4684645497855, 8247.092819789937, 8348.833023339621, 8428.915710842813, 8430.494170349799, 8316.126806685454, 9989.300748947504, 10654.946725266323, 11149.219554631098, 11410.372429516456], "value": 9384.043029408233, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.3544151969331, 1423.8504956979514, 1584.350416329305, 1805.837474627284, 2006.8896210708415, 7888.645010974506, 8629.26651234645, 8866.911330886689, 8879.002224499493, 8995.370324077317, 6981.965090174542, 8823.691170809023, 8832.955835220831, 8833.690820821272, 8860.955695221532, 2075.2321640992855, 2154.687570302305, 2157.8165855902266, 2359.7404285528537, 2393.9162129325478, 2174.184680744721, 2201.03154611971, 2290.129750694742, 2380.238323736405, 2386.4153282445827, 2380.143148466551, 2384.5707772600927, 2386.4630458146953, 2387.200287903546, 2387.343480542856, 2323.0707716913284, 2363.1847012780563, 2364.373441038109, 2365.5268946210826, 2366.325597204797, 2086.123827992247, 2273.4316420894775, 2349.4596242864236, 2363.054778088778, 2371.27676058802, 8872.290216782641, 8951.68669096585, 8968.551572421418, 8973.865392019125, 8984.236339911115, 4602.700824446434, 5202.635815492917, 5357.866053348697, 5376.327959005124, 5386.48828361309, 3448.6034106077873, 3579.6420357964257, 3649.7627654202392, 3651.7808931463933, 3655.342038433081, 996.5764550066193, 1029.433811403725, 1054.6572363981736, 1075.0754351257913, 1091.5742860284495, 11949.103817213743, 12019.278843269345, 12019.459124339426, 12030.81953770694, 12045.457954392074, 4173.728707633994, 4647.838040489877, 4707.364505791675, 4796.25658023007, 4897.681650692693, 10031.899681003242, 10081.19350451962, 10087.646932357333, 10090.899091009143, 10103.292769506064, 5393.352797664227, 5403.162509810985, 5410.91883621746, 5414.458554144554, 5417.783288668488, 4403.8238470461465, 4724.71651700899, 4831.710097394168, 4897.191963326082, 4998.375203099613, 3772.5472943246987, 3902.941455878164, 3903.921921561553, 3911.3546264866545, 3919.1377896863214, 1244.5208594690928, 1284.6916739982337, 1308.483149156078, 1309.7773378525674, 1310.0829419406264, 756.9772906812805, 867.8524650809378, 941.4351389166445, 958.8178246133212, 961.3991255465335, 6101.664408457577, 6114.938850611437, 6171.352008039181, 6172.259328880563, 6213.0, 946.4841661294611, 949.2074118111454, 951.3483263964207, 1002.6791426743519, 1010.540204207084, 4087.6116768906904, 4131.338985762323, 4147.440095587118, 4199.958000419957, 4203.369494575838, 10637.46812659364, 10894.420309055142, 10897.346159230472, 10980.121590272758, 11006.394648294261, 10945.90540945899, 10953.671389858317, 10961.671149865517, 10978.725531861608, 10980.615678451215, 7873.448858579844, 8249.546274954935, 8252.257296843312, 8281.461704989157, 8420.8315833683, 5583.720813959205, 5931.110333450017, 6091.664958427329, 6118.20463339763, 6296.433321001132, 10127.949360253151, 10350.171986240932, 10350.585976560853, 10368.377897326185, 10374.221933354878, 9789.412635241908, 10222.284440089123, 10373.942117847788, 10461.633842815465, 10494.632687855888, 7621.1616722161625, 7693.576853273124, 7772.300492955661, 7789.493682910593, 7797.805054873674, 7547.28300811422, 7850.293473587404, 8305.12796156419, 8431.536265505456, 8446.873296900429, 7399.963000184965, 7469.7385591504035, 7610.9238907612435, 7649.235076492307, 7798.727044553413, 6440.42036216724, 7479.476436649382, 7487.064116985377, 7643.464957452925, 7647.158812530506, 6251.874962500816, 6811.0, 7143.0, 7209.243029481829, 7214.639268036683, 4959.9008019838675, 5013.473585273639, 5035.370578677665, 5087.109755792795, 5099.796008159632, 5103.719295438638, 5173.741312934263, 5200.583953283653, 5229.921551176812, 5273.050850846885], "value": 4626.248552686732, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.7733903521042, 291.66895573524056, 306.188600209445, 311.72879594752567, 311.8113541307509, 4240.809163587642, 4264.786760661966, 5202.3497062867145, 5264.39459462162, 5313.548348390392, 4939.555440010398, 5142.614303927202, 5167.328247327842, 5172.017316709823, 5237.659552129109, 3346.514755360471, 3575.302815950891, 3583.677469027787, 3920.4514216884313, 3931.6461518463475, 3953.169834334793, 3970.8014599270064, 3985.521737391517, 3987.4616926714957, 4000.4599379083743, 3124.9375412227932, 3863.643177661624, 3937.4093885917055, 3953.2291203215386, 3955.9208815823667, 3643.8116647933057, 3808.552749955027, 3893.649571538575, 3940.0937784309626, 3975.463312452811, 2693.501702185102, 2920.2407374082677, 3061.6479104902996, 3246.7240284575605, 3408.7954722716718, 4139.834406623761, 4337.934930976038, 5116.2069879168785, 5118.667286626357, 5141.511556402136, 4225.7887105644595, 4263.360495925636, 4331.047169622678, 4494.876280929768, 4508.106595230011, 4612.215923293048, 5367.516923476866, 5390.838274851761, 5442.619016668835, 5445.53712934401, 3208.9197770055703, 3291.325278317939, 3603.5315408996926, 3756.605556416564, 3771.057235691077, 5225.085610018232, 5494.32423110951, 5670.886582268332, 5755.366909639936, 5757.942420575824, 3594.964050359515, 3741.345264578688, 3758.9060273492832, 3761.5298087739034, 3763.8870833875026, 3432.541170002739, 3819.6753275971855, 3833.731638785314, 3837.520309961255, 3871.8838434847, 3829.425586162098, 3991.8004099794894, 4323.48118225815, 4469.25698977399, 4472.0, 4269.658427325807, 4691.812327506861, 5214.165733482625, 5475.7262136893, 5604.887902241932, 1124.100719424457, 1263.993680031594, 1299.6880748620263, 1306.5296493262417, 1309.2390734462133, 764.6291548598925, 786.7333592915402, 790.4308897593723, 793.90870049945, 801.7354273089867, 3678.6137455567314, 3700.703943684499, 3896.2272166164325, 4218.2196293685765, 4306.0, 3726.9627303727157, 4078.9184216315507, 4337.436133302714, 4664.090502352009, 4714.6464015199, 3616.8010759408485, 3928.508936382952, 4255.87232383029, 4281.957180428218, 4337.0458499130145, 2178.3009252782854, 2198.6482162853868, 2210.027587861336, 2519.009982114146, 2521.0546045233036, 4095.201435720006, 4632.490426053131, 4716.995277007258, 4773.95226047742, 4842.806287748521, 4013.7591744495426, 4440.666949978699, 4835.492273311322, 4836.564709176191, 5117.923231151536, 4552.6813123081065, 4764.857054288377, 5409.348689634481, 5693.715314234271, 5796.217510636095, 4995.950040499549, 5271.103912334989, 5487.0, 5608.923923848924, 5614.747336369792, 5499.202615620793, 5550.4449555045785, 5562.164485719783, 5587.776488940397, 5596.244506991504, 4014.763909604106, 5455.918161227664, 5646.830595082154, 5784.942150578609, 5828.766849326149, 3709.8516059357326, 3724.497192878927, 3742.9064273393383, 3757.3236817373136, 3759.360908645482, 4698.177818881613, 5141.018209438442, 5639.830805075854, 5671.546276297969, 5718.313802343745, 4879.941886499591, 5222.7127507987425, 5496.4503549646315, 5500.0, 5520.66875987442, 4542.591796543083, 5429.429909859566, 5488.945110548843, 5491.270249871278, 5518.779248830002, 4526.298423744325, 4760.285957106462, 5140.151874940593, 5188.299579556711, 5366.946330536644, 5026.547610715053, 5407.297051383295, 5490.396056433869, 5502.0371434999515, 5510.559155267489, 4786.856394308176, 5141.71720555373, 5404.459554044564, 5423.701696406735, 5470.753816078206], "value": 3796.546645998471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.8540806430652, 712.4407340237913, 726.7311094894883, 780.157429975626, 854.6666799948022, 15349.769753453693, 15785.842141578582, 15804.0, 15806.051636901793, 16624.66750664986, 16054.197290135477, 16058.116803575798, 16079.356825726956, 16080.678386432264, 16142.435014774484, 721.4444877444365, 741.7700512841036, 890.7105190813012, 894.8926128864529, 899.8020435504211, 769.9153093159748, 779.3297763923022, 926.6478738079554, 934.2712684106408, 935.0229010683852, 967.5694316029335, 978.021978021978, 978.427619842393, 979.666913249495, 981.224832382417, 743.1528058013869, 764.5298141642905, 771.8533478639097, 972.8200282947678, 973.3575839945637, 559.6977632078673, 689.8896176611718, 705.4885208224048, 709.3544874164486, 710.7867639708066, 14746.778798318033, 14792.630184245372, 14798.334074966653, 14807.777883331759, 14811.407543698238, 14160.36278367476, 14378.856211437856, 14391.0, 14452.421903123863, 14547.0, 13020.674483137997, 13177.538786142535, 13379.665508362272, 13599.52401665937, 13688.384022719001, 4536.206163921301, 4590.0, 4623.630109591259, 4637.443506779175, 4773.403324584427, 90288.54855725745, 112030.0, 112214.0, 113410.0, 113554.0, 10979.945100274448, 11051.502682379312, 11147.944260278648, 11329.660110196708, 11634.941825290989, 22387.776122238894, 22493.550128997656, 22573.0, 22658.320250392513, 22669.546609067726, 7368.447366447538, 7877.842443151219, 8452.408331416866, 8467.957660211783, 8579.0, 4535.138323718483, 4580.816767329269, 4672.509386514435, 4722.315264286659, 4726.456457507362, 3720.5907350191455, 3882.048898020003, 3882.5340959085092, 3886.417037444406, 3887.028242939265, 707.989380159298, 709.1844378964232, 718.5699863412239, 730.4594599995964, 731.4660297982502, 582.8892510423007, 587.4566026425624, 632.6457183976895, 671.1542465128783, 711.8149281186825, 9111.681091161778, 9631.422114673001, 9632.325737198329, 9710.368826026286, 9924.255680823968, 498.5936461783635, 621.7295476467751, 641.7817941899776, 647.4173244080271, 657.8059472455637, 4552.157850797613, 4602.631789456902, 4670.031558303194, 4697.389339385858, 4764.690295130806, 125456.37271813583, 139141.0, 151559.0, 151940.40297985057, 157917.73534720208, 128539.0, 131203.34398328137, 131415.0, 132611.0, 135652.3217383907, 84436.57781711052, 100421.95780422192, 112329.43835280772, 115888.42055789666, 115978.42010789891, 8729.86511753468, 8799.956000219958, 9523.428594284365, 9929.156021738234, 9979.151772099453, 62100.37899620946, 62769.74460510856, 65139.67430163008, 65316.65495992263, 65614.0, 93929.5303523478, 97768.0, 98804.0, 99698.0, 101205.49397252967, 13206.933965330112, 13247.403866825824, 13352.99852511046, 13396.464141434233, 13859.861401385857, 14953.02805317651, 14980.775288370902, 15032.473863414729, 15342.23288835576, 15572.29924653416, 13673.931630341785, 13909.930450347683, 14197.574072777834, 14205.28973551339, 14370.209638469567, 13703.45186192541, 13874.375653095432, 13974.510892118727, 14609.488667896572, 14831.33259003369, 13694.000837370466, 13878.514252001132, 13884.930575347058, 13892.374843132286, 13911.443542258196, 11252.212345135762, 11359.318440893572, 11374.530508821435, 11545.076393888638, 11689.766204676029, 9973.501324933579, 11371.943140284246, 11397.772044559228, 11518.424078796193, 11620.941895290469], "value": 8072.104164323802, "unit": "score"}], "name": "dromaeo_css", "value": 7345.146595750226, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 164.0, 165.0, 113.0, 113.0, 113.0, 112.0, 110.0, 113.0, 112.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 125.0, 201.0, 140.0, 121.0, 121.0, 142.0, 156.0, 123.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 183.0, 183.0, 183.0, 182.0, 182.0, 184.0, 181.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 88.0, 58.0, 58.0, 63.0, 65.0, 82.0, 71.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 93.0, 73.0, 70.0, 70.0, 95.0, 69.0, 68.0, 101.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 90.0, 86.0, 88.0, 86.0, 90.0, 86.0, 89.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 93.0, 94.0, 95.0, 95.0, 95.0, 101.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 72.0, 124.0, 134.0, 137.0, 86.0, 85.0, 87.0, 94.0], "value": 99.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 74.0, 77.0, 75.0, 71.0, 69.0, 73.0, 71.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 79.0, 84.0, 84.0, 84.0, 88.0, 85.0, 87.0, 100.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 142.0, 138.0, 131.0, 134.0, 139.0, 137.0, 156.0, 122.0], "value": 137.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 169.0, 167.0, 167.0, 167.0, 165.0, 212.0, 158.0, 196.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 67.0, 66.0, 66.0, 64.0, 67.0, 63.0, 66.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1467.3, "alertThreshold": 2.0}]} 09:57:11 INFO - Return code: 0 09:57:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:11 INFO - # TBPL SUCCESS # 09:57:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:11 INFO - 2016-06-29 09:57:11.938 screenresolution[1668:9610] starting screenresolution argv=screenresolution get 09:57:11 INFO - 2016-06-29 09:57:11.941 screenresolution[1668:9610] Display 0: 1600x1200x32@60 09:57:11 INFO - 2016-06-29 09:57:11.946 screenresolution[1669:9613] starting screenresolution argv=screenresolution list 09:57:11 INFO - Available Modes on Display 0 09:57:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:12 INFO - Intel Iris: 09:57:12 INFO - Chipset Model: Intel Iris 09:57:12 INFO - Type: GPU 09:57:12 INFO - Bus: Built-In 09:57:12 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:12 INFO - Vendor: Intel (0x8086) 09:57:12 INFO - Device ID: 0x0a2e 09:57:12 INFO - Revision ID: 0x0009 09:57:12 INFO - Displays: 09:57:12 INFO - Display: 09:57:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:12 INFO - Display Serial Number: 1600x1200 60 09:57:12 INFO - Main Display: Yes 09:57:12 INFO - Mirror: Off 09:57:12 INFO - Online: Yes 09:57:12 INFO - Rotation: Supported 09:57:12 INFO - Return code: 0 09:57:12 INFO - Running post-action listener: _resource_record_post_action 09:57:12 INFO - ##### 09:57:12 INFO - ##### Finished run-tests step (success) 09:57:12 INFO - ##### 09:57:12 INFO - Running post-run listener: _resource_record_post_run 09:57:12 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36955648; Write bytes: 705214464; Read time: 241; Write time: 1910 09:57:12 INFO - TinderboxPrint: CPU usage
53.7% 09:57:12 INFO - TinderboxPrint: I/O read bytes / time
36,955,648 / 241 09:57:12 INFO - TinderboxPrint: I/O write bytes / time
705,214,464 / 1,910 09:57:12 INFO - TinderboxPrint: CPU idle
2,244.1 (46.3%) 09:57:12 INFO - TinderboxPrint: CPU system
964.7 (19.9%) 09:57:12 INFO - TinderboxPrint: CPU user
1,636.8 (33.8%) 09:57:12 INFO - TinderboxPrint: Swap in / out
443,813,888 / 0 09:57:12 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407399936; Write bytes: 398260224; Read time: 30605; Write time: 800 09:57:12 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29531648; Write bytes: 295227392; Read time: 176; Write time: 1088 09:57:12 INFO - Running post-run listener: _upload_blobber_files 09:57:12 INFO - Blob upload gear active. 09:57:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:12 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 09:57:12 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:57:12 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:57:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:57:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:57:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:13 INFO - (blobuploader) - INFO - Done attempting. 09:57:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:57:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:57:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:14 INFO - (blobuploader) - INFO - Done attempting. 09:57:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:57:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:57:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:17 INFO - (blobuploader) - INFO - Done attempting. 09:57:17 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:17 INFO - Return code: 0 09:57:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09a2d63bae2e2f22e3076ce9ee8a86cbaaa3901f2bc2a3fa463d28f9d340f492c66c0e8f636e7c04c138a40e2e5217854deb396b72f92a1f9346be977acc3023", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecbce2499cbcb8b1fbc02384bb80155296b489a401aace0e577a422af437d3de8f77820b47f9e89c869099f7c6b9c571a1b107ecea88bf471d7826fd4f3757e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8c37bc7e2a9259b73aeed360fb6d96a185bc983ec0bcf435b67b8f1e93f53ec35157334eb35799d532dd4f3fdc7b5a76effb705cc6c78ac92c8574d716181cb"} 09:57:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:17 INFO - Contents: 09:57:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09a2d63bae2e2f22e3076ce9ee8a86cbaaa3901f2bc2a3fa463d28f9d340f492c66c0e8f636e7c04c138a40e2e5217854deb396b72f92a1f9346be977acc3023", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecbce2499cbcb8b1fbc02384bb80155296b489a401aace0e577a422af437d3de8f77820b47f9e89c869099f7c6b9c571a1b107ecea88bf471d7826fd4f3757e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8c37bc7e2a9259b73aeed360fb6d96a185bc983ec0bcf435b67b8f1e93f53ec35157334eb35799d532dd4f3fdc7b5a76effb705cc6c78ac92c8574d716181cb"} 09:57:17 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:17 INFO - Copying logs to upload dir... 09:57:17 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.642787 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-29 09:57:17.251038) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 09:57:17.256668) ========= 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.u0f4AchHqG/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.GNcbkpTEhc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09a2d63bae2e2f22e3076ce9ee8a86cbaaa3901f2bc2a3fa463d28f9d340f492c66c0e8f636e7c04c138a40e2e5217854deb396b72f92a1f9346be977acc3023", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecbce2499cbcb8b1fbc02384bb80155296b489a401aace0e577a422af437d3de8f77820b47f9e89c869099f7c6b9c571a1b107ecea88bf471d7826fd4f3757e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8c37bc7e2a9259b73aeed360fb6d96a185bc983ec0bcf435b67b8f1e93f53ec35157334eb35799d532dd4f3fdc7b5a76effb705cc6c78ac92c8574d716181cb"} build_url:https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011025 build_url: 'https://queue.taskcluster.net/v1/task/SvZsCQ9uTsKyZ6_JaTTHJQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09a2d63bae2e2f22e3076ce9ee8a86cbaaa3901f2bc2a3fa463d28f9d340f492c66c0e8f636e7c04c138a40e2e5217854deb396b72f92a1f9346be977acc3023", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecbce2499cbcb8b1fbc02384bb80155296b489a401aace0e577a422af437d3de8f77820b47f9e89c869099f7c6b9c571a1b107ecea88bf471d7826fd4f3757e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8c37bc7e2a9259b73aeed360fb6d96a185bc983ec0bcf435b67b8f1e93f53ec35157334eb35799d532dd4f3fdc7b5a76effb705cc6c78ac92c8574d716181cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 09:57:17.289943) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:57:17.290279) ========= 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.u0f4AchHqG/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.GNcbkpTEhc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:57:17.338860) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 09:57:17.339177) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 09:57:17.339491) ========= ========= Total master_lag: 0.17 =========