builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0303 starttime: 1467417777.91 results: success (0) buildid: 20160701144422 builduid: d19b79251a564eae846c859540176f40 revision: 21585b3e48141dc0ecb55fece8f424ba5855b08f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:57.908353) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:57.908889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:57.909165) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:57.952160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:57.952461) ========= 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.kjzys4fwXo/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.YNrVU6at9Y/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-01 17:02:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2016-07-01 17:02:58 (190 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.569863 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:58.551073) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:58.551466) ========= 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.kjzys4fwXo/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.YNrVU6at9Y/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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.026499 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:58.599273) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-01 17:02:58.599635) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 21585b3e48141dc0ecb55fece8f424ba5855b08f --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 21585b3e48141dc0ecb55fece8f424ba5855b08f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kjzys4fwXo/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.YNrVU6at9Y/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-01 17:02:58,679 truncating revision to first 12 chars 2016-07-01 17:02:58,679 Setting DEBUG logging. 2016-07-01 17:02:58,679 attempt 1/10 2016-07-01 17:02:58,679 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/21585b3e4814?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-01 17:02:59,515 unpacking tar archive at: fx-team-21585b3e4814/testing/mozharness/ program finished with exit code 0 elapsedTime=1.105868 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-01 17:02:59.727995) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:59.728342) ========= script_repo_revision: 21585b3e48141dc0ecb55fece8f424ba5855b08f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:59.728784) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:59.729053) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 17:02:59.748037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-01 17:02:59.748312) ========= /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.kjzys4fwXo/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.YNrVU6at9Y/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:02:59 INFO - MultiFileLogger online at 20160701 17:02:59 in /builds/slave/test 17:02:59 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 17:02:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:02:59 INFO - {'append_to_log': False, 17:02:59 INFO - 'base_work_dir': '/builds/slave/test', 17:02:59 INFO - 'blob_upload_branch': 'Fx-Team', 17:02:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:02:59 INFO - 'branch': 'Fx-Team', 17:02:59 INFO - 'buildbot_json_path': 'buildprops.json', 17:02:59 INFO - 'config_files': ('talos/mac_config.py',), 17:02:59 INFO - 'default_actions': ('clobber', 17:02:59 INFO - 'read-buildbot-config', 17:02:59 INFO - 'download-and-extract', 17:02:59 INFO - 'populate-webroot', 17:02:59 INFO - 'create-virtualenv', 17:02:59 INFO - 'install', 17:02:59 INFO - 'run-tests'), 17:02:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:02:59 INFO - 'download_minidump_stackwalk': True, 17:02:59 INFO - 'download_symbols': 'ondemand', 17:02:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:02:59 INFO - 'tooltool.py': '/tools/tooltool.py', 17:02:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:02:59 INFO - '/tools/misc-python/virtualenv.py')}, 17:02:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:02:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:02:59 INFO - 'installer_path': 'installer.exe', 17:02:59 INFO - 'log_level': 'info', 17:02:59 INFO - 'log_name': 'talos', 17:02:59 INFO - 'log_to_console': True, 17:02:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:02:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:02:59 INFO - 'opt_config_files': (), 17:02:59 INFO - 'pip_index': False, 17:02:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:02:59 INFO - 'cmd': ('bash', 17:02:59 INFO - '-c', 17:02:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:02:59 INFO - 'enabled': True, 17:02:59 INFO - 'halt_on_failure': False, 17:02:59 INFO - 'name': 'check_screen_resolution'},), 17:02:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:02:59 INFO - 'cmd': ('bash', 17:02:59 INFO - '-c', 17:02:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:02:59 INFO - 'enabled': True, 17:02:59 INFO - 'halt_on_failure': False, 17:02:59 INFO - 'name': 'check_screen_resolution'},), 17:02:59 INFO - 'run_cmd_checks_enabled': True, 17:02:59 INFO - 'sps_profile': False, 17:02:59 INFO - 'sps_profile_interval': 0, 17:02:59 INFO - 'suite': 'dromaeojs', 17:02:59 INFO - 'system_bits': '32', 17:02:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:02:59 INFO - 'title': 't-yosemite-r7-0303', 17:02:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:02:59 INFO - 'use_talos_json': True, 17:02:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:02:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:02:59 INFO - 'work_dir': 'build'} 17:02:59 INFO - ##### 17:02:59 INFO - ##### Running clobber step. 17:02:59 INFO - ##### 17:02:59 INFO - Running pre-action listener: _resource_record_pre_action 17:02:59 INFO - Running main action method: clobber 17:02:59 INFO - rmtree: /builds/slave/test/build 17:02:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:03:01 INFO - Running post-action listener: _resource_record_post_action 17:03:01 INFO - ##### 17:03:01 INFO - ##### Finished clobber step (success) 17:03:01 INFO - ##### 17:03:01 INFO - ##### 17:03:01 INFO - ##### Running read-buildbot-config step. 17:03:01 INFO - ##### 17:03:01 INFO - Running pre-action listener: _resource_record_pre_action 17:03:01 INFO - Running main action method: read_buildbot_config 17:03:01 INFO - Using buildbot properties: 17:03:01 INFO - { 17:03:01 INFO - "project": "", 17:03:01 INFO - "product": "firefox", 17:03:01 INFO - "script_repo_revision": "production", 17:03:01 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:03:01 INFO - "repository": "", 17:03:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:03:01 INFO - "buildid": "20160701144422", 17:03:01 INFO - "pgo_build": "False", 17:03:01 INFO - "basedir": "/builds/slave/test", 17:03:01 INFO - "buildnumber": 68, 17:03:01 INFO - "slavename": "t-yosemite-r7-0303", 17:03:01 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:03:01 INFO - "platform": "yosemite_r7", 17:03:01 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:03:01 INFO - "branch": "fx-team", 17:03:01 INFO - "repo_path": "integration/fx-team", 17:03:01 INFO - "revision": "21585b3e48141dc0ecb55fece8f424ba5855b08f", 17:03:01 INFO - "stage_platform": "macosx64", 17:03:01 INFO - "builduid": "d19b79251a564eae846c859540176f40", 17:03:01 INFO - "slavebuilddir": "test" 17:03:01 INFO - } 17:03:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 17:03:01 INFO - Running post-action listener: _resource_record_post_action 17:03:01 INFO - ##### 17:03:01 INFO - ##### Finished read-buildbot-config step (success) 17:03:01 INFO - ##### 17:03:01 INFO - ##### 17:03:01 INFO - ##### Running download-and-extract step. 17:03:01 INFO - ##### 17:03:01 INFO - Running pre-action listener: _resource_record_pre_action 17:03:01 INFO - Running main action method: download_and_extract 17:03:01 INFO - mkdir: /builds/slave/test/build/tests 17:03:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:03:01 INFO - https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:03:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 17:03:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 17:03:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:03 INFO - Downloaded 1199 bytes. 17:03:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 17:03:03 INFO - Using the following test package requirements: 17:03:03 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 17:03:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 17:03:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 17:03:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'jsshell-mac.zip'], 17:03:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 17:03:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 17:03:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 17:03:03 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 17:03:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 17:03:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:03:03 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 17:03:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:03:03 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')]} 17:03:03 INFO - https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:03:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:03:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:03:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:05 INFO - Downloaded 18789585 bytes. 17:03:05 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'] 17:03:05 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 17:03:08 INFO - Return code: 0 17:03:08 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 17:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:03:08 INFO - https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:03:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:03:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:03:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:14 INFO - Downloaded 18789585 bytes. 17:03:14 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'] 17:03:14 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 17:03:18 INFO - Return code: 0 17:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:03:18 INFO - https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:03:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 17:03:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 17:03:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:19 INFO - Downloaded 14405242 bytes. 17:03:19 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'] 17:03:19 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 17:03:20 INFO - Return code: 0 17:03:20 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')]} 17:03:20 INFO - https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:03:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:03:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:03:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 17:03:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XxtCCM2wQMmwA9BoEGgecA/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 17:03:23 INFO - Downloaded 112416906 bytes. 17:03:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:03:23 INFO - mkdir: /builds/slave/test/properties 17:03:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:03:23 INFO - Writing to file /builds/slave/test/properties/build_url 17:03:23 INFO - Contents: 17:03:23 INFO - build_url:https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:03:25 INFO - Running post-action listener: _resource_record_post_action 17:03:25 INFO - Running post-action listener: set_extra_try_arguments 17:03:25 INFO - ##### 17:03:25 INFO - ##### Finished download-and-extract step (success) 17:03:25 INFO - ##### 17:03:25 INFO - ##### 17:03:25 INFO - ##### Running populate-webroot step. 17:03:25 INFO - ##### 17:03:25 INFO - Running pre-action listener: _resource_record_pre_action 17:03:25 INFO - Running main action method: populate_webroot 17:03:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:03:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:03:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:03:25 INFO - '--tppagecycles', 17:03:25 INFO - '7'], 17:03:25 INFO - 'tests': ['tsvgm']}}, 17:03:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:03:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:03:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:03:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:03:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:03:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:03:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['damp', 'tps']}, 17:03:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'tests': ['damp', 'tps']}, 17:03:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['dromaeo_dom']}, 17:03:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:03:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['basic_compositor_video']}, 17:03:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:03:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['a11yr', 17:03:25 INFO - 'ts_paint', 17:03:25 INFO - 'tpaint', 17:03:25 INFO - 'sessionrestore', 17:03:25 INFO - 'sessionrestore_no_auto_restore', 17:03:25 INFO - 'tabpaint']}, 17:03:25 INFO - 'other-e10s': {'tests': ['a11yr', 17:03:25 INFO - 'ts_paint', 17:03:25 INFO - 'tpaint', 17:03:25 INFO - 'sessionrestore', 17:03:25 INFO - 'sessionrestore_no_auto_restore', 17:03:25 INFO - 'tabpaint']}, 17:03:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:03:25 INFO - 'ts_paint', 17:03:25 INFO - 'tpaint', 17:03:25 INFO - 'sessionrestore', 17:03:25 INFO - 'sessionrestore_no_auto_restore', 17:03:25 INFO - 'tabpaint']}, 17:03:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:03:25 INFO - 'ts_paint', 17:03:25 INFO - 'tpaint', 17:03:25 INFO - 'sessionrestore', 17:03:25 INFO - 'sessionrestore_no_auto_restore', 17:03:25 INFO - 'tabpaint']}, 17:03:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['a11yr', 17:03:25 INFO - 'ts_paint', 17:03:25 INFO - 'tpaint', 17:03:25 INFO - 'sessionrestore', 17:03:25 INFO - 'sessionrestore_no_auto_restore', 17:03:25 INFO - 'tabpaint']}, 17:03:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['a11yr', 17:03:25 INFO - 'ts_paint', 17:03:25 INFO - 'tpaint', 17:03:25 INFO - 'sessionrestore', 17:03:25 INFO - 'sessionrestore_no_auto_restore', 17:03:25 INFO - 'tabpaint']}, 17:03:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['tsvgx', 17:03:25 INFO - 'tsvgr_opacity', 17:03:25 INFO - 'tart', 17:03:25 INFO - 'tscrollx', 17:03:25 INFO - 'cart']}, 17:03:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:03:25 INFO - 'tsvgr_opacity', 17:03:25 INFO - 'tart', 17:03:25 INFO - 'tscrollx', 17:03:25 INFO - 'cart']}, 17:03:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'talos_options': ['--disable-e10s'], 17:03:25 INFO - 'tests': ['tp5o']}, 17:03:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'tests': ['tp5o']}, 17:03:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'talos_options': ['--disable-e10s', 17:03:25 INFO - '--xperf_path', 17:03:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:03:25 INFO - 'tests': ['tp5n']}, 17:03:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:25 INFO - 'talos_options': ['--xperf_path', 17:03:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:03:25 INFO - 'tests': ['tp5n']}}, 17:03:25 INFO - 'talos.zip': {'path': '', 17:03:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:03:25 INFO - Running post-action listener: _resource_record_post_action 17:03:25 INFO - ##### 17:03:25 INFO - ##### Finished populate-webroot step (success) 17:03:25 INFO - ##### 17:03:25 INFO - ##### 17:03:25 INFO - ##### Running create-virtualenv step. 17:03:25 INFO - ##### 17:03:25 INFO - Running pre-action listener: _resource_record_pre_action 17:03:25 INFO - Running main action method: create_virtualenv 17:03:25 INFO - Creating virtualenv /builds/slave/test/build/venv 17:03:25 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 17:03:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:03:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:03:25 INFO - Using real prefix '/tools/python27' 17:03:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:03:26 INFO - Installing distribute.............................................................................................................................................................................................done. 17:03:28 INFO - Installing pip.................done. 17:03:28 INFO - Return code: 0 17:03:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:03:28 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')]} 17:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:28 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')]} 17:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:29 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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:29 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 17:03:29 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 17:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:29 INFO - 'HOME': '/Users/cltbld', 17:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:29 INFO - 'LOGNAME': 'cltbld', 17:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:29 INFO - 'MOZ_NO_REMOTE': '1', 17:03:29 INFO - 'NO_EM_RESTART': '1', 17:03:29 INFO - 'PAGER': '/bin/cat', 17:03:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:29 INFO - 'PWD': '/builds/slave/test', 17:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:29 INFO - 'SHELL': '/bin/bash', 17:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:29 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:29 INFO - 'USER': 'cltbld', 17:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:29 INFO - 'XPC_FLAGS': '0x0', 17:03:29 INFO - 'XPC_SERVICE_NAME': '0', 17:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:29 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 17:03:29 INFO - Cleaning up... 17:03:29 INFO - Return code: 0 17:03:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:03:29 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')]} 17:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:29 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')]} 17:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:29 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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:29 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 17:03:29 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 17:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:29 INFO - 'HOME': '/Users/cltbld', 17:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:29 INFO - 'LOGNAME': 'cltbld', 17:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:29 INFO - 'MOZ_NO_REMOTE': '1', 17:03:29 INFO - 'NO_EM_RESTART': '1', 17:03:29 INFO - 'PAGER': '/bin/cat', 17:03:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:29 INFO - 'PWD': '/builds/slave/test', 17:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:29 INFO - 'SHELL': '/bin/bash', 17:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:29 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:29 INFO - 'USER': 'cltbld', 17:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:29 INFO - 'XPC_FLAGS': '0x0', 17:03:29 INFO - 'XPC_SERVICE_NAME': '0', 17:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:29 INFO - Downloading/unpacking psutil>=3.1.1 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:03:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:03:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:03:32 INFO - Installing collected packages: psutil 17:03:32 INFO - Running setup.py install for psutil 17:03:32 INFO - building 'psutil._psutil_osx' extension 17:03:32 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 17:03:32 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 17:03:32 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 17:03:32 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 17:03:32 INFO - building 'psutil._psutil_posix' extension 17:03:32 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 17:03:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:03:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:03:33 INFO - ^ 17:03:33 INFO - 1 warning generated. 17:03:33 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 17:03:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:03:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:03:33 INFO - Successfully installed psutil 17:03:33 INFO - Cleaning up... 17:03:33 INFO - Return code: 0 17:03:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:03: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 mozsystemmonitor==0.3 17:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:33 INFO - 'HOME': '/Users/cltbld', 17:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:33 INFO - 'LOGNAME': 'cltbld', 17:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:33 INFO - 'MOZ_NO_REMOTE': '1', 17:03:33 INFO - 'NO_EM_RESTART': '1', 17:03:33 INFO - 'PAGER': '/bin/cat', 17:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:33 INFO - 'PWD': '/builds/slave/test', 17:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:33 INFO - 'SHELL': '/bin/bash', 17:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:33 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:33 INFO - 'USER': 'cltbld', 17:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:33 INFO - 'XPC_FLAGS': '0x0', 17:03:33 INFO - 'XPC_SERVICE_NAME': '0', 17:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:03:33 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 17:03:33 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 17:03:33 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 17:03:33 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 17:03:33 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 17:03:33 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 17:03:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:03:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:03:36 INFO - Installing collected packages: mozsystemmonitor 17:03:36 INFO - Running setup.py install for mozsystemmonitor 17:03:36 INFO - Successfully installed mozsystemmonitor 17:03:36 INFO - Cleaning up... 17:03:36 INFO - Return code: 0 17:03:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:03:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:36 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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:36 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 17:03:36 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 17:03:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:36 INFO - 'HOME': '/Users/cltbld', 17:03:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:36 INFO - 'LOGNAME': 'cltbld', 17:03:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:36 INFO - 'MOZ_NO_REMOTE': '1', 17:03:36 INFO - 'NO_EM_RESTART': '1', 17:03:36 INFO - 'PAGER': '/bin/cat', 17:03:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:36 INFO - 'PWD': '/builds/slave/test', 17:03:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:36 INFO - 'SHELL': '/bin/bash', 17:03:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:36 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:36 INFO - 'USER': 'cltbld', 17:03:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:36 INFO - 'XPC_FLAGS': '0x0', 17:03:36 INFO - 'XPC_SERVICE_NAME': '0', 17:03:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:36 INFO - Downloading/unpacking jsonschema==2.5.1 17:03:36 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 17:03:36 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 17:03:36 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 17:03:36 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 17:03:36 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 17:03:36 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 17:03:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:03:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:39 INFO - Downloading functools32-3.2.3-2.tar.gz 17:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:03:39 INFO - warning: no files found matching '*.txt' 17:03:39 INFO - no previously-included directories found matching 'build' 17:03:39 INFO - no previously-included directories found matching 'dist' 17:03:39 INFO - no previously-included directories found matching '.git*' 17:03:39 INFO - Installing collected packages: jsonschema, functools32 17:03:39 INFO - Running setup.py install for functools32 17:03:39 INFO - warning: no files found matching '*.txt' 17:03:39 INFO - no previously-included directories found matching 'build' 17:03:39 INFO - no previously-included directories found matching 'dist' 17:03:39 INFO - no previously-included directories found matching '.git*' 17:03:39 INFO - Successfully installed jsonschema functools32 17:03:39 INFO - Cleaning up... 17:03:39 INFO - Return code: 0 17:03:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:39 INFO - 'HOME': '/Users/cltbld', 17:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:39 INFO - 'LOGNAME': 'cltbld', 17:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:39 INFO - 'MOZ_NO_REMOTE': '1', 17:03:39 INFO - 'NO_EM_RESTART': '1', 17:03:39 INFO - 'PAGER': '/bin/cat', 17:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:39 INFO - 'PWD': '/builds/slave/test', 17:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:39 INFO - 'SHELL': '/bin/bash', 17:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:39 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:39 INFO - 'USER': 'cltbld', 17:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:39 INFO - 'XPC_FLAGS': '0x0', 17:03:39 INFO - 'XPC_SERVICE_NAME': '0', 17:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:40 INFO - Downloading/unpacking blobuploader==1.2.4 17:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:42 INFO - Downloading blobuploader-1.2.4.tar.gz 17:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:03:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:03:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:03:43 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 17:03:43 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 17:03:43 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 17:03:43 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 17:03:43 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 17:03:43 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 17:03:43 INFO - Downloading docopt-0.6.1.tar.gz 17:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:03:43 INFO - Installing collected packages: blobuploader, requests, docopt 17:03:43 INFO - Running setup.py install for blobuploader 17:03:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:03:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:03:43 INFO - Running setup.py install for requests 17:03:44 INFO - Running setup.py install for docopt 17:03:44 INFO - Successfully installed blobuploader requests docopt 17:03:44 INFO - Cleaning up... 17:03:44 INFO - Return code: 0 17:03:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:44 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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:44 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 17:03:44 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 17:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:44 INFO - 'HOME': '/Users/cltbld', 17:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:44 INFO - 'LOGNAME': 'cltbld', 17:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:44 INFO - 'MOZ_NO_REMOTE': '1', 17:03:44 INFO - 'NO_EM_RESTART': '1', 17:03:44 INFO - 'PAGER': '/bin/cat', 17:03:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:44 INFO - 'PWD': '/builds/slave/test', 17:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:44 INFO - 'SHELL': '/bin/bash', 17:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:44 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:44 INFO - 'USER': 'cltbld', 17:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:44 INFO - 'XPC_FLAGS': '0x0', 17:03:44 INFO - 'XPC_SERVICE_NAME': '0', 17:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:03:44 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-NLgGZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-3EjFwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-tSA4S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-HB1Gta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-_Q6M_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-n4QCyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-H_rdvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-KqDc1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-KceoAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-6AdyYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:03:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-AtrMYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:03:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-lMJfoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:03:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-MRHBMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:03:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-3G6Gth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-4Z8sLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:03:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-QKLLWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:03:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-N3uNl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:03:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:03:46 INFO - Running setup.py install for manifestparser 17:03:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:03:46 INFO - Running setup.py install for mozcrash 17:03:46 INFO - Running setup.py install for mozdebug 17:03:47 INFO - Running setup.py install for mozdevice 17:03:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Running setup.py install for mozfile 17:03:47 INFO - Running setup.py install for mozhttpd 17:03:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Running setup.py install for mozinfo 17:03:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Running setup.py install for mozInstall 17:03:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:03:47 INFO - Running setup.py install for mozleak 17:03:47 INFO - Running setup.py install for mozlog 17:03:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:03:48 INFO - Running setup.py install for moznetwork 17:03:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:03:48 INFO - Running setup.py install for mozprocess 17:03:48 INFO - Running setup.py install for mozprofile 17:03:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:03:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:03:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:03:48 INFO - Running setup.py install for mozrunner 17:03:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:03:48 INFO - Running setup.py install for mozscreenshot 17:03:48 INFO - Running setup.py install for moztest 17:03:49 INFO - Running setup.py install for mozversion 17:03:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:03:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:03:49 INFO - Cleaning up... 17:03:49 INFO - Return code: 0 17:03:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:49 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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:49 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 17:03:49 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 17:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:49 INFO - 'HOME': '/Users/cltbld', 17:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:49 INFO - 'LOGNAME': 'cltbld', 17:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:49 INFO - 'MOZ_NO_REMOTE': '1', 17:03:49 INFO - 'NO_EM_RESTART': '1', 17:03:49 INFO - 'PAGER': '/bin/cat', 17:03:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:49 INFO - 'PWD': '/builds/slave/test', 17:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:49 INFO - 'SHELL': '/bin/bash', 17:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:49 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:49 INFO - 'USER': 'cltbld', 17:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:49 INFO - 'XPC_FLAGS': '0x0', 17:03:49 INFO - 'XPC_SERVICE_NAME': '0', 17:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:03:49 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-bm0srD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:03:49 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)) 17:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:03:49 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-RNStPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:03:49 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)) 17:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:03:49 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-Blb2YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:03:49 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)) 17:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:03:49 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-fn5kuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:03:49 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)) 17:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:03:49 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-zuvsET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-soANuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-elmVsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-9NQuoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-50D9Ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-9wQ6xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-ppQpAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-iyMiak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-x7GCy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-BipbF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:03:50 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)) 17:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:50 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-2qxW6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:51 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)) 17:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:03:51 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-Fyd_Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:03:51 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)) 17:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:03:51 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-YS313z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:03:51 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)) 17:03:51 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)) 17:03:51 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)) 17:03:51 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)) 17:03:51 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)) 17:03:51 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)) 17:03:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:53 INFO - Downloading blessings-1.6.tar.gz 17:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:03:53 INFO - Installing collected packages: blessings 17:03:53 INFO - Running setup.py install for blessings 17:03:53 INFO - Successfully installed blessings 17:03:53 INFO - Cleaning up... 17:03:54 INFO - Return code: 0 17:03:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:03:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:03:54 INFO - Reading from file tmpfile_stdout 17:03:54 INFO - Current package versions: 17:03:54 INFO - blessings == 1.6 17:03:54 INFO - blobuploader == 1.2.4 17:03:54 INFO - docopt == 0.6.1 17:03:54 INFO - functools32 == 3.2.3-2 17:03:54 INFO - jsonschema == 2.5.1 17:03:54 INFO - manifestparser == 1.1 17:03:54 INFO - mozInstall == 1.12 17:03:54 INFO - mozcrash == 0.17 17:03:54 INFO - mozdebug == 0.1 17:03:54 INFO - mozdevice == 0.48 17:03:54 INFO - mozfile == 1.2 17:03:54 INFO - mozhttpd == 0.7 17:03:54 INFO - mozinfo == 0.9 17:03:54 INFO - mozleak == 0.1 17:03:54 INFO - mozlog == 3.2 17:03:54 INFO - moznetwork == 0.27 17:03:54 INFO - mozprocess == 0.22 17:03:54 INFO - mozprofile == 0.28 17:03:54 INFO - mozrunner == 6.11 17:03:54 INFO - mozscreenshot == 0.1 17:03:54 INFO - mozsystemmonitor == 0.3 17:03:54 INFO - moztest == 0.7 17:03:54 INFO - mozversion == 1.4 17:03:54 INFO - psutil == 3.1.1 17:03:54 INFO - requests == 1.2.3 17:03:54 INFO - wsgiref == 0.1.2 17:03:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:54 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 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:54 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 17:03:54 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 17:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:54 INFO - 'HOME': '/Users/cltbld', 17:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:54 INFO - 'LOGNAME': 'cltbld', 17:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:54 INFO - 'MOZ_NO_REMOTE': '1', 17:03:54 INFO - 'NO_EM_RESTART': '1', 17:03:54 INFO - 'PAGER': '/bin/cat', 17:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:54 INFO - 'PWD': '/builds/slave/test', 17:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:54 INFO - 'SHELL': '/bin/bash', 17:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:54 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:54 INFO - 'USER': 'cltbld', 17:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:54 INFO - 'XPC_FLAGS': '0x0', 17:03:54 INFO - 'XPC_SERVICE_NAME': '0', 17:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 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)) 17:03:54 INFO - Cleaning up... 17:03:54 INFO - Return code: 0 17:03:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c705a87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, '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.kjzys4fwXo/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.YNrVU6at9Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 17:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:54 INFO - 'HOME': '/Users/cltbld', 17:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:54 INFO - 'LOGNAME': 'cltbld', 17:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:54 INFO - 'MOZ_NO_REMOTE': '1', 17:03:54 INFO - 'NO_EM_RESTART': '1', 17:03:54 INFO - 'PAGER': '/bin/cat', 17:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:54 INFO - 'PWD': '/builds/slave/test', 17:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:54 INFO - 'SHELL': '/bin/bash', 17:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:03:54 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:54 INFO - 'USER': 'cltbld', 17:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:54 INFO - 'XPC_FLAGS': '0x0', 17:03:54 INFO - 'XPC_SERVICE_NAME': '0', 17:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:03:54 INFO - Cleaning up... 17:03:54 INFO - Return code: 0 17:03:54 INFO - Running post-action listener: _resource_record_post_action 17:03:54 INFO - Running post-action listener: _start_resource_monitoring 17:03:54 INFO - Starting resource monitoring. 17:03:54 INFO - ##### 17:03:54 INFO - ##### Finished create-virtualenv step (success) 17:03:54 INFO - ##### 17:03:54 INFO - ##### 17:03:54 INFO - ##### Running install step. 17:03:54 INFO - ##### 17:03:54 INFO - Running pre-action listener: _resource_record_pre_action 17:03:54 INFO - Running main action method: install 17:03:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:03:55 INFO - Reading from file tmpfile_stdout 17:03:55 INFO - Detecting whether we're running mozinstall >=1.0... 17:03:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:03:55 INFO - Reading from file tmpfile_stdout 17:03:55 INFO - Output received: 17:03:55 INFO - Usage: mozinstall [options] installer 17:03:55 INFO - Options: 17:03:55 INFO - -h, --help show this help message and exit 17:03:55 INFO - -d DEST, --destination=DEST 17:03:55 INFO - Directory to install application into. [default: 17:03:55 INFO - "/builds/slave/test"] 17:03:55 INFO - --app=APP Application being installed. [default: firefox] 17:03:55 INFO - mkdir: /builds/slave/test/build/application 17:03:55 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'] 17:03:55 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 17:04:29 INFO - Reading from file tmpfile_stdout 17:04:29 INFO - Output received: 17:04:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:04:29 INFO - Running post-action listener: _resource_record_post_action 17:04:29 INFO - ##### 17:04:29 INFO - ##### Finished install step (success) 17:04:29 INFO - ##### 17:04:29 INFO - ##### 17:04:29 INFO - ##### Running run-tests step. 17:04:29 INFO - ##### 17:04:29 INFO - Running pre-action listener: _resource_record_pre_action 17:04:29 INFO - Running main action method: run_tests 17:04:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:04:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:04:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:04:29 INFO - 2016-07-01 17:04:29.175 screenresolution[1636:7719] starting screenresolution argv=screenresolution get 17:04:29 INFO - 2016-07-01 17:04:29.178 screenresolution[1636:7719] Display 0: 1600x1200x32@60 17:04:29 INFO - 2016-07-01 17:04:29.183 screenresolution[1637:7721] starting screenresolution argv=screenresolution list 17:04:29 INFO - Available Modes on Display 0 17:04:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:04:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:04:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:04:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:04:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:04:29 INFO - Intel Iris: 17:04:29 INFO - Chipset Model: Intel Iris 17:04:29 INFO - Type: GPU 17:04:29 INFO - Bus: Built-In 17:04:29 INFO - VRAM (Dynamic, Max): 1536 MB 17:04:29 INFO - Vendor: Intel (0x8086) 17:04:29 INFO - Device ID: 0x0a2e 17:04:29 INFO - Revision ID: 0x0009 17:04:29 INFO - Displays: 17:04:29 INFO - Display: 17:04:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:04:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:04:29 INFO - Display Serial Number: 1600x1200 60 17:04:29 INFO - Main Display: Yes 17:04:29 INFO - Mirror: Off 17:04:29 INFO - Online: Yes 17:04:29 INFO - Rotation: Supported 17:04:29 INFO - Return code: 0 17:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:04:29 INFO - Python 2.7.3 17:04:29 INFO - Return code: 0 17:04:29 INFO - grabbing minidump binary from tooltool 17:04:29 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')]} 17:04:29 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 0x10ebead30>, '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 0x10e94fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:04:29 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 17:04:29 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 17:04:29 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 17:04:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:04:29 INFO - Return code: 0 17:04:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:04:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:04:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:04:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:04:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:04:29 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/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0303', '--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 17:04:29 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/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0303 --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 17:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kjzys4fwXo/Render', 17:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:29 INFO - 'HOME': '/Users/cltbld', 17:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:29 INFO - 'LOGNAME': 'cltbld', 17:04:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:04:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:29 INFO - 'MOZ_NO_REMOTE': '1', 17:04:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:04:29 INFO - 'NO_EM_RESTART': '1', 17:04:29 INFO - 'PAGER': '/bin/cat', 17:04:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:29 INFO - 'PWD': '/builds/slave/test', 17:04:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:29 INFO - 'SHELL': '/bin/bash', 17:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YNrVU6at9Y/Listeners', 17:04:29 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 17:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:29 INFO - 'USER': 'cltbld', 17:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:29 INFO - 'XPC_FLAGS': '0x0', 17:04:29 INFO - 'XPC_SERVICE_NAME': '0', 17:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:29 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/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0303', '--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 17:04:29 INFO - mozversion application_buildid: 20160701144422 17:04:29 INFO - mozversion application_changeset: 21585b3e48141dc0ecb55fece8f424ba5855b08f 17:04:29 INFO - mozversion application_display_name: Nightly 17:04:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:04:29 INFO - mozversion application_name: Firefox 17:04:29 INFO - mozversion application_remotingname: firefox 17:04:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:04:29 INFO - mozversion application_vendor: Mozilla 17:04:29 INFO - mozversion application_version: 50.0a1 17:04:29 INFO - mozversion platform_buildid: 20160701144422 17:04:29 INFO - mozversion platform_changeset: 21585b3e48141dc0ecb55fece8f424ba5855b08f 17:04:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:04:29 INFO - mozversion platform_version: 50.0a1 17:04:29 INFO - using testdate: 1467417869 17:04:29 INFO - actual date: 1467417869 17:04:29 INFO - starting webserver on 'localhost:49271' 17:04:29 INFO - SUITE-START | Running 2 tests 17:04:29 INFO - TEST-START | dromaeo_css 17:04:29 INFO - operating with platform_type : mac_ 17:04:29 INFO - Initialising browser for dromaeo_css test... 17:04:29 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpHVowN9/profile http://localhost:49271/getInfo.html) 17:04:32 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 17:04:32 INFO - PROCESS | 1647 | colorDepth:24 17:04:32 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 17:04:32 INFO - PROCESS | 1647 | __metrics 17:04:32 INFO - PROCESS | 1647 | 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" 17:04:32 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:04:32 INFO - TEST-INFO | 1647: exit 0 17:04:32 INFO - Browser initialized. 17:04:32 INFO - Running cycle 1/1 for dromaeo_css test... 17:04:32 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpHVowN9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:44 INFO - PROCESS | 1649 | RSS: Main: 243699712 17:04:44 INFO - PROCESS | 1649 | 17:05:35 INFO - PROCESS | 1649 | 1467417935710 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] 17:07:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 17:07:47 INFO - PROCESS | 1649 | RSS: Main: 340967424 17:07:47 INFO - PROCESS | 1649 | 17:10:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 17:10:50 INFO - PROCESS | 1649 | RSS: Main: 371871744 17:10:50 INFO - PROCESS | 1649 | 17:13:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 17:13:53 INFO - PROCESS | 1649 | RSS: Main: 370176000 17:13:53 INFO - PROCESS | 1649 | 17:16:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 17:16:56 INFO - PROCESS | 1649 | RSS: Main: 372432896 17:16:56 INFO - PROCESS | 1649 | 17:19:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 17:20:00 INFO - PROCESS | 1649 | RSS: Main: 338112512 17:20:00 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 17:23:03 INFO - PROCESS | 1649 | RSS: Main: 385241088 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | __start_tp_report 17:23:03 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 17:23:03 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 17:23:03 INFO - PROCESS | 1649 | |i|pagename|runs| 17:23:03 INFO - PROCESS | 1649 | |0;dojo.html;2863.8854445822167;3308.4375656138454;3317.9668203317965;3340.6492318306564;3366.596008478983;5551.139573366125;5565.4434556544375;5592.664440133584;5616.691081990489;5648.011597970349;4350.695451318409;5644.858878528039;5690.914636280449;5731.512821410185;5742.511886489653;5039.294498770175;5058.342415485981;5119.539241468267;5129.871753206181;5260.947390526084;5171.301874246966;5193.428722840485;5204.193350030751;5229.267902493654;5243.816466423676;5026.4219614744225;5045.899082018339;5068.214426763858;5104.719240441774;5128.53843154116;5110.9744451277875;5120.539151476385;5129.461406552333;5131.384233891919;5144.202648589475;4208.452901122834;5087.262346959707;5117.462666420047;5129.461406552333;5166.638335316529;5499.917501237484;5546.251256080458;5573.526250268732;5573.804916827891;5618.943810561883;6148.508119350441;6160.507159427235;6168.599041062316;6180.505559555225;6197.442230199259;6209.96895015522;6254.155688981975;6263.279722831887;6265.498760099181;6275.497960163176;5364.22218778275;5595;5739.913901291442;5742.13867919807;5777.74000169985;6258.561900666909;6265.4361107500545;6266.122742816009;6279.058141278845;6290.654014029273;5818.010938140526;6081.326545338661;6083.676678410109;6085;6123.387661233841;6100.3899610039525;6103.05402662588;6138.447539721446;6143.938560614337;6144.893919094606;6127.036821038878;6168.568200226031;6178.598391104564;6180.969095154585;6199;6167.136600875881;6175.042868355412;6182.28903676074;6194.287656919422;6208.658523781235;4765.8570242892765;4778.832740854054;4823.782929768169;4868.805247790048;4929.852104436873;4310.849120280838;4408.713433626804;4417.536158703354;4429.7785110744335;4446.5331140230455;5542.224088627595;5616.66300022;5618.578606604521;5626.943730562723;5701.287339082614;6154.323024467305;6161.137440758297;6165.506759459233;6167.907481387782;6176.166217560572;5553.583481238842;5608.49523542875;5633.295838020247;5670.659760414388;5684.914726279109;5339.3058902342445;5379.085555455582;5397.8110766123;5404.243405923174;5406.567474602022;6253.030780229072;6265.091561723616;6271.874562508816;6280.371962803683;6287.434130928147;6196.907046394398;6209.782657606962;6231.376862313732;6261.3112557617715;6262.749490020348;5637.9718101409235;5838.095095260242;6196.752129914753;6243.531735119975;6251.874962500634;6146.938530614637;6146.938530614637;6151.477124444473;6182.319944805978;6196.504279657526;6120.938790612036;6225.875482490415;6229.719662615284;6230.968845155745;6252.406021427957;5222.511584198485;5872.970635146797;5956.987312156857;5966.373530779206;6022.126791615279;5464.153056276284;6018.458338749533;6057.666828324484;6066.059760737093;6066.817995460143;5727.942720572741;5884.764609415746;5893.02765043763;5904.498117660047;5923.4076592340425;5971.313298970674;6016.037434010537;6051.364606716232;6084.60450070744;6091.0558863376245;5868.647881127146;5907.556933229939;5913.645181289136;5917.142014407801;5922.674252916131 17:23:03 INFO - PROCESS | 1649 | |1;ext.html;519.9454057323981;535.5153586004666;538.2894579155513;559.0886854427285;565.9473379514105;40451.39322910159;42045.369319460195;42056.57943420569;44304.556954430525;44359.3346099808;42853;43232.56767432325;43347;43393.34909976345;43410;9579.425234485918;9760.609575616967;9765.974572669875;9780.462074585896;9781.657641982532;9665.806683866318;9777.511124443748;9780.021997800199;9782.315237933348;9783.657571984982;9433.962264150938;9475.905240947572;9491.620335186584;9500.049995000514;9533.90466095337;9731.708048758548;9786.021397860228;9787.559559819825;9801.215902727765;9807.65673201438;9655.131038206595;9730.270229732798;9732.367396119229;9736.3671361362;9742.805143897152;41447.792761036;41635.791821041006;41642.58357416417;41692.791536042125;41693.583064169274;3351.798892066484;3419.350323438539;3591.4612808078996;3847.211321679049;3997.14061476781;31305.686943130506;32247;32557.02328930136;32645.18387040319;32734.6726532734;3058.1284333964713;3146.732527735145;3174.206448387903;3232.208109013307;3249.366373557157;451532;452485;452530.73734631116;453951.73024134996;454643.9999999967;62255;62503;62534.68732656308;62552.748945021754;62851.685741571;64378.35621643817;64705;64826.351736482975;64907;64910.67544662341;16657.91671041637;16885.40901068457;16904.239309231114;16905.83094169067;16929.06890121055;12879;12930.482780688855;12942.676433089247;12956.416961236768;12964.611061668165;16323.755143672854;16569.25438355277;17690.734638980426;17696.911515442596;17704.822951770577;6853.965730171317;6869.622170780655;6905.965470172617;6995.615241161683;7017.438604911595;849.7975364858779;891.4740303221001;924.1682485762733;938.8310104181177;999.9450579638406;26601.866990664923;29758.25604359865;30201.848990754905;30803;31454.37091258208;1054.6097943760858;1076.2197406880107;1081.5727787523883;1134.313740187175;1140.7376303450253;15881;15976.360945562048;16059.87580869328;16458.588535286715;16462.506124816275;638658.8067059728;903549;921233;923940;926846;60030.69984650049;60041;60715.39284607186;60779.69610151921;60818.391816081275;1210.3040751567883;1263.368315842079;1296.805479178112;1421.2396367943102;1432.6919712261913;30073.849630752582;31317.217069573442;32450;32513.34973300568;32610.836945815117;56738;57485.425145748006;58091.70954145202;58097.54756131131;58190;577139;585732;585834;587133;588061;24039;26621.7337826627;26725.06462273811;26966;27175;47291.527084730085;47352.7632361836;47794.52205477901;47978.76010620064;48123.75938120287;39945.40081898832;39974.20051598894;40485;41205.793971029954;41240.76277711673;43013.1397372057;43044.78477607592;43596.56403435925;44896.32655510105;46444.53555464537;39690;42291;42898.92752681083;42904;44419.77790111029;40478;42771;43022.35466468068;43380.56619433891;43399.34900976425;32288.35423291568;32617.673823261463;32716.854910078033;32770.50844237386;33199.21818661036 17:23:03 INFO - PROCESS | 1649 | |2;jquery.html;366.2290877702436;390.78506821248317;390.89836642472966;399.53454225826914;410.52174217037134;17786.82213177871;18851.340203092896;21181.258655947044;21531.13875444982;21535.892320538394;20214.393568192976;20871.686924696143;21086.367408977716;21151.73089614624;21242.36272911811;5395.163749618796;6002.1596976423225;6109.297430795449;6148.169997050408;6157.7536898524;4236.089240813225;4521.638268938494;4529.977350113245;4532.818687252506;4544.568266014723;4027.2750904837117;4318.870433886973;4503.504614492404;6867.656617169147;6974;5459.344878614592;5829.533637309005;6597.571157874723;6913.89629155563;6940.993555934387;5840.941590584082;5863.8534036649;5867.530597552186;5872.236609240771;5878.206442130343;18084.095795210185;19443;25596.616050759254;25641.35896602581;25804;1918.7409699690502;2173.7717539658265;2186.5954798362195;2260.4412864992864;2517.0309430869247;10009.89990100097;10493.160547156209;10768.946155269174;11021.897810218994;16405.91797041007;1844.1516902224992;1893.943181704551;1909.3412772593433;1942.8348590369835;1970.753655793026;96346;96679;98422.01577984272;108222.45888770584;115348.42325788402;7992.560409177435;9173.77065573366;9707.70876873695;9974.301798874008;10050.497475126214;12294.631161065183;12310.199837010565;12377.133600647869;12377.690557735948;12401.813972790414;7956.283934445928;8143.389245806588;8146.103928567853;8207.056188538276;8208.138145494757;6945.617991010543;8509.914900851036;8521.190486903733;8546.786330341667;8631.741047768577;3907.7069219808627;3930.155016671429;3953.525576930729;4007.8396864125693;4017.4978127734034;1619.5222409389023;1942.5920556682972;1951.707243913396;1959.314240015983;1995.8802471851736;808.3492788305471;917.6647977193168;946.7301818981557;960.8222478841436;996.3175224970918;17078.914605426893;20866.47833804137;21284.893575532333;21379.931003450078;21428;930.2465003347311;940.8165407745561;946.2666433514082;957.3872721458272;977.7360112769512;10872.67381978542;11219.102471802236;11241.943790280997;11264.267822591506;11269.66191014271;533729;536660;575512;578297;587299;123903.14145287828;130591;140315;142186.1562768739;144010;1006.7885743993697;1013.2147585621202;1031.3554028732042;1074.070928646715;1080.2384319055047;18877.90561047186;19213.943233122314;19286.517837054187;19324.80675193286;19356.70964935555;53652.195217072556;55490;57057.14414283872;57095.71452142713;57197;53538;69591.30408695848;74931.87874666946;79333;83308;7895.6841726332605;8212.3430125591;8221.054578723284;8337.999440066988;8444.366672499464;12174.330411827434;13159.868401315864;13320.467181312639;13427.932860335635;13576.728465430833;11426.460752113646;11446.828297575377;11537.01935335506;11540.769184616429;11584.710382240175;11295.887041129812;11329.886701132884;11403.258788178742;11706.004989575982;11722.120840936794;11623.418829058683;11624.5350185995;11796;11976.341301228167;12078.939605301917;7029.261927497743;7382.4094072475145;7411.62941852916;7661.697511423183;8550.78623034429;10399.53202105892;11244.437778111225;11443.713907152387;11469.31184128955;11510.82733759011 17:23:03 INFO - PROCESS | 1649 | |3;mootools.html;1388.8472268050507;1492.0823693428545;1574.9291281892313;1584.7939767830187;1893.6591413545557;7870.173631768661;8561.400701950866;8843.999999999984;8851.822963540733;8979.775505612346;8161.755147345589;8212.260896519312;8566.571671416435;8805.119488051192;8817.515036672981;2361.633946738254;2387.8209134314907;2391.3065211088765;2395.317334559651;2397.6643269942224;1953.9706904396446;2336.427575244059;2374.1453076892303;2376.275236053003;2382.4163080045414;2343.60158773009;2362.303120579433;2372.4306166519996;2374.893129809163;2383.976160238393;2315.814734821227;2351.4239011442137;2356.7996720278798;2362.5038741864228;2371.9525609487887;2327.7323107842476;2327.976720232793;2328.7904088631935;2330.2892617751577;2331.3355693627404;8649.481031138152;8831.470111793313;8844.55777211137;8912.554372281425;8917.643294268222;5307.946920530784;5454.290942177491;5454.59090568209;5456.263404440429;5458.344998600193;3498.265364273506;3513.5608048993877;3538.752287339887;3599.6040435552068;3606.04439823447;1010.5907107621351;1027.7790275090817;1719.8538124259453;1778.8310110539608;1819.4905426480607;11637.88362116385;11760.000399966013;11765.941170294094;11798.528058877635;11806.763864722743;4425.032930247335;4509.526499717548;4642.53574642533;4643.233866412072;4658.883527911829;9022.593983270768;9126.634934602675;9145;9152.084791520927;9175.541222938826;5240.213967904845;5248.790048398021;5253.632245742761;5254.343207099113;5256.316678831728;3953.9214200883093;4426.424564806554;4820.566149046533;4834.178189707758;4922.507749225048;3719.6094410087094;3742.3263812513464;3803.23935212953;3841.4045822896937;3844.1927195289304;1073.388168743824;1143.1826244235413;1157.242006485753;1169.6374124021581;1178.452019810792;790.7864876483317;840.3949156607233;923.7690577355661;950.8288508068475;971.4710989828826;6019.187409699722;6033.577649564576;6102.267727872683;6194.876102478015;6200.507993425986;828.4325237212529;925.8426966292135;944.0795224656043;952.8904176019846;990.346371394876;3262.331222099464;3926.54844692864;4113.670906327487;4182.958170418317;4195.056112374752;10633.202509811796;10857.565697372174;10881.564737410416;10975.50610222542;11023.559057637605;10107;10904.454777261264;10907.236493445384;10912.017918387382;10921.726956825984;7955.801104972422;8149.103598604267;8314.41799074059;8316.459430137022;8317.958410208032;6050.15297858309;6121.47967422774;6289.339619339903;6326.563185826569;6351.904721429275;9865.901340986786;10015.298929074896;10266.845997309896;10277.794444111225;10313.9484302578;9377.390469619453;9736.75658108553;10146.441945692763;10331.741706457398;10460.895391046297;7441.367483763941;7679.19368466303;7684.269994350528;7685.269899359552;7790.8831367530665;8495.490270583785;8568.871466928125;8588.742337729878;8602.096779838026;8608.139186081342;7527.247275272429;7567.31894129531;7606.657700403606;7638.007059082283;7758.146603873683;7493.925060749323;7581.3176814087;7600.50596711212;7646.770596882103;7718.652660630173;6020.819375418744;6945.895811562932;7108.715651374094;7111.288871112847;7127.821804454723;5094.745262736774;5192.792288308577;5318.255444237732;5331.866703332293;5448.155535891943;5292.7353632319;5433.592480563894;5433.701146436984;5518.310211223597;5588.944110558842 17:23:03 INFO - PROCESS | 1649 | |4;prototype.html;245.2018679199207;258.72704296966714;297.68891508373747;301.96727192999936;302.3212887068531;4792.418501894375;5094.18493041113;5199.272101905732;5235.86910327242;5236.554892834104;4326.8918277043085;5016.097102521545;5080.771365288561;5139.511746384097;5154.819581314655;3378.459446488562;3730.5523337199506;3754.230382771539;3816.9427458588143;3930.547986981491;3863.652271295583;3947.1908258806875;3952.288588054149;3965.20695860829;3970.1464185200175;3891.435741817435;3893.785841778701;3894.571597124314;3902.512185976753;3907.4138879168067;3187.490001599756;3714.015785816747;3789.6399842014966;3813.599572044951;3936.9803150984203;3441.7074548663395;3452.6374730653424;3456.274182421669;3471.1495683557437;3487.1456493159085;5057.848264552033;5084.593232541425;5125.104316061414;5125.56484184429;5182.507661772125;4344.1963236800975;4575.130725162208;4580.908381832378;4585.633149348044;4599.264117741179;4682.6253899687945;5454.754536045887;5474;5484.203056441203;5525.944740552583;3191.664875188095;3847.2882516734494;3879.359905615598;3880.417937309398;3883.825227864753;5030.195168772979;5335.039692855285;5735.77056917727;5747.15515819672;5756.7985120521;3531.2407832316176;3729.6083911189476;3766.5856755756845;3774.075091803874;3789.0716774390453;3195.6964088411564;3801.258754542893;3837.808109594509;3851.8459261629782;3857.7847977886877;4103.712740108223;4381.058072514425;4412.492563355255;4417.226985277628;4456.866294011184;5240.397354304304;5377.489138531833;5393.7572809223675;5485.725713714299;5502.394736578973;1278.284160869916;1296.623979046085;1302.2121616422119;1302.9609111726663;1303.7066660001612;692.7125243024088;712.9251428600026;800.3957012455675;810.7527204202775;811.6104269950339;4225.549181474737;4727.692699974491;4947.678400903929;4948.158813001798;5005.324281221989;4652.813887444464;5127.462051184745;5277.736113194402;5430.619856609999;5472.480114389126;4506.2564676828615;4683.704984557542;5014.420457555858;5041.269015992661;5100.387953445536;2775.653043369579;2889;2900.941981160365;2903.637045369329;2912.6796052434215;4547.3633691282585;5622.803201887996;5685.167607533564;5731.770729170787;5810.738516766755;4710.599599034052;4881.755912204376;4970.353853998957;5381.7578208980685;5607.635503692247;5170.922436163385;5358.91961620576;5700.6009579329875;5735.483806457355;5736.225609542742;4538.842595138219;5481.671099734029;5485.369182544058;5487.835364939058;5505.311836020497;4989.850304490871;5078.669886457368;5112.565431938327;5428.83713488596;5439.184122381675;4689.835855745033;4725.314829349793;5636.633618814764;5662.66024038559;5686.516646085129;3187.5218717192606;3823.044238940265;3853.865114720971;3867;3872.9349428907276;4554.9316760249285;4705.364775755229;5242.790288388422;5423.511883930308;5434.483724046209;4891.315215869852;5323.494268044529;5343.599230057684;5348.465153484621;5366.382865970463;4233.936490952699;4735.431748190239;5253.106971814725;5272.996261420635;5309.495597918191;4945.010997800527;5145.761593706749;5162.8967420652125;5162.9483705162465;5185.248139019745;4420.336949457607;4905.258633185168;4936.259561065869;5279.604029697711;5293.470652934676;4453.686162581981;4650.325702773147;4837.37114175155;4951.133551628523;5191.377034755854 17:23:03 INFO - PROCESS | 1649 | |5;yui.html;708.5111273221478;714.4320265389015;750.5871770526211;775.8913752074707;813.8819871118684;15018.924905375472;15609.453669121584;15935.123568203744;15939.362425502966;15941.20293985302;14462.493812716557;14660.41358345665;15423.691526169498;15984.040957542527;15997;835.795230168608;850.8681154421043;860.0195776814405;861.5864385095158;862.7584276402586;847.8643417053273;849.1847826086964;850.0437008365589;850.4897061762955;852.1138016462871;892.9864603674844;893.3924931046886;893.4996402014892;893.7363477774072;894.7941973346102;891.1534042659499;892.4868200784575;892.995535022324;894.6108442827358;895.4403497813867;657.621867426227;658.427168363522;658.5192809249227;658.5456035335603;663.1644128398204;13602.31988400576;14482.637934051627;15340.846591534053;15350.385984560604;15373.385064597403;13146.276954767483;13533.120347177402;13974.161550306913;14167.033230695422;14569.854301457062;11735.471903764352;11837;11894;11928.58249961256;12033.759324813542;3914.3541315683165;3926.3325234709882;3945.2701250268506;4068.837246510136;4075.4701888754566;106992;109691;110275.89724102737;110358;110926;9549.236061115094;11073.944630276797;11081.33511989267;11104.944475277573;11447.828282575769;22228;22401.327960161223;22422.887885560467;22427;22453.550928981327;7588.696452141963;7828;7884.132745398002;8042;8235.21765432283;4467.767464387227;4537;4563.977180114078;4580.358749774968;4730.597899178609;3536.504889315498;3655.0862284428895;3656.9634303657153;3659.0852286928266;3920.4707364506;713.9036230108973;715.588536591462;720.4668545276458;729.6023667101337;729.923358047408;522.7281813456981;560.3723829311198;632.2855173653742;676.9932300677028;677.9830254618073;7800.92199078002;7979.84040319202;8902.376833621714;9055.81888362229;9704.320697551104;633.996830015847;671.4326394196896;671.4896678524259;676.7428377216725;698.1482591238681;4501.527339629357;4737.45519265282;4763.632568057211;4775.307580400892;4861.9027619447415;101214.48178277332;105362;150257;156911.21544392206;161942;110048.4497577507;128088;137463.3088227965;138863.30568347094;139141;119108;121717;121747.56504869853;121750.39124804319;125382;9017.384958362001;9023.774405639911;9024.864627030469;9097.545122743968;9528.333016688764;63967;64772;64886.67556662187;65301.34698652953;65848;61104;61174.38825611687;61847.690761545906;89249.55375223083;100232;11736.88629239365;11792.29246245228;11937.880621194025;11946.163768536118;12017.4592143352;13170.47318107265;13319.33403329849;13383.598492045254;13442.865571344553;13468.5286014989;12128.636140915787;12350.197237179555;12397.44211510466;12854.035947303802;12888.742225155256;12257.387130643254;12495.812562811383;12551.811722824348;12645.87354126447;13058.869411306145;11909.285442873455;12282.385880706108;12351.876481235073;12499.875001250235;12615.30615816139;10556.577736890438;10564.366138031743;10585.206109541661;10805.567777288821;10889.059953812584;9609.807803844024;10533.683989480327;10533.9473302633;10538.683839484827;10562.87984575086 17:23:03 INFO - PROCESS | 1649 | __end_tp_report 17:23:03 INFO - PROCESS | 1649 | __start_cc_report 17:23:03 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1149 17:23:03 INFO - PROCESS | 1649 | __end_cc_report 17:23:03 INFO - PROCESS | 1649 | __startTimestamp1467418983289__endTimestamp 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | ------- Summary: start ------- 17:23:03 INFO - PROCESS | 1649 | Number of tests: 6 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5656.81 Median:5872.97 stddev:665.63 (11.3%) stddev-sans-first:630.58 17:23:03 INFO - PROCESS | 1649 | Values: 2863.9 3308.4 3318.0 3340.6 3366.6 5551.1 5565.4 5592.7 5616.7 5648.0 4350.7 5644.9 5690.9 5731.5 5742.5 5039.3 5058.3 5119.5 5129.9 5260.9 5171.3 5193.4 5204.2 5229.3 5243.8 5026.4 5045.9 5068.2 5104.7 5128.5 5111.0 5120.5 5129.5 5131.4 5144.2 4208.5 5087.3 5117.5 5129.5 5166.6 5499.9 5546.3 5573.5 5573.8 5618.9 6148.5 6160.5 6168.6 6180.5 6197.4 6210.0 6254.2 6263.3 6265.5 6275.5 5364.2 5595.0 5739.9 5742.1 5777.7 6258.6 6265.4 6266.1 6279.1 6290.7 5818.0 6081.3 6083.7 6085.0 6123.4 6100.4 6103.1 6138.4 6143.9 6144.9 6127.0 6168.6 6178.6 6181.0 6199.0 6167.1 6175.0 6182.3 6194.3 6208.7 4765.9 4778.8 4823.8 4868.8 4929.9 4310.8 4408.7 4417.5 4429.8 4446.5 5542.2 5616.7 5618.6 5626.9 5701.3 6154.3 6161.1 6165.5 6167.9 6176.2 5553.6 5608.5 5633.3 5670.7 5684.9 5339.3 5379.1 5397.8 5404.2 5406.6 6253.0 6265.1 6271.9 6280.4 6287.4 6196.9 6209.8 6231.4 6261.3 6262.7 5638.0 5838.1 6196.8 6243.5 6251.9 6146.9 6146.9 6151.5 6182.3 6196.5 6120.9 6225.9 6229.7 6231.0 6252.4 5222.5 5873.0 5957.0 5966.4 6022.1 5464.2 6018.5 6057.7 6066.1 6066.8 5727.9 5884.8 5893.0 5904.5 5923.4 5971.3 6016.0 6051.4 6084.6 6091.1 5868.6 5907.6 5913.6 5917.1 5922.7 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:77036.73 Median:43386.96 stddev:177425.82 (408.9%) stddev-sans-first:177830.53 17:23:03 INFO - PROCESS | 1649 | Values: 519.9 535.5 538.3 559.1 565.9 40451.4 42045.4 42056.6 44304.6 44359.3 42853.0 43232.6 43347.0 43393.3 43410.0 9579.4 9760.6 9766.0 9780.5 9781.7 9665.8 9777.5 9780.0 9782.3 9783.7 9434.0 9475.9 9491.6 9500.0 9533.9 9731.7 9786.0 9787.6 9801.2 9807.7 9655.1 9730.3 9732.4 9736.4 9742.8 41447.8 41635.8 41642.6 41692.8 41693.6 3351.8 3419.4 3591.5 3847.2 3997.1 31305.7 32247.0 32557.0 32645.2 32734.7 3058.1 3146.7 3174.2 3232.2 3249.4 451532.0 452485.0 452530.7 453951.7 454644.0 62255.0 62503.0 62534.7 62552.7 62851.7 64378.4 64705.0 64826.4 64907.0 64910.7 16657.9 16885.4 16904.2 16905.8 16929.1 12879.0 12930.5 12942.7 12956.4 12964.6 16323.8 16569.3 17690.7 17696.9 17704.8 6854.0 6869.6 6906.0 6995.6 7017.4 849.8 891.5 924.2 938.8 999.9 26601.9 29758.3 30201.8 30803.0 31454.4 1054.6 1076.2 1081.6 1134.3 1140.7 15881.0 15976.4 16059.9 16458.6 16462.5 638658.8 903549.0 921233.0 923940.0 926846.0 60030.7 60041.0 60715.4 60779.7 60818.4 1210.3 1263.4 1296.8 1421.2 1432.7 30073.8 31317.2 32450.0 32513.3 32610.8 56738.0 57485.4 58091.7 58097.5 58190.0 577139.0 585732.0 585834.0 587133.0 588061.0 24039.0 26621.7 26725.1 26966.0 27175.0 47291.5 47352.8 47794.5 47978.8 48123.8 39945.4 39974.2 40485.0 41205.8 41240.8 43013.1 43044.8 43596.6 44896.3 46444.5 39690.0 42291.0 42898.9 42904.0 44419.8 40478.0 42771.0 43022.4 43380.6 43399.3 32288.4 32617.7 32716.9 32770.5 33199.2 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:33629.04 Median:25618.99 stddev:94180.85 (367.6%) stddev-sans-first:94411.94 17:23:03 INFO - PROCESS | 1649 | Values: 366.2 390.8 390.9 399.5 410.5 17786.8 18851.3 21181.3 21531.1 21535.9 20214.4 20871.7 21086.4 21151.7 21242.4 5395.2 6002.2 6109.3 6148.2 6157.8 4236.1 4521.6 4530.0 4532.8 4544.6 4027.3 4318.9 4503.5 6867.7 6974.0 5459.3 5829.5 6597.6 6913.9 6941.0 5840.9 5863.9 5867.5 5872.2 5878.2 18084.1 19443.0 25596.6 25641.4 25804.0 1918.7 2173.8 2186.6 2260.4 2517.0 10009.9 10493.2 10768.9 11021.9 16405.9 1844.2 1893.9 1909.3 1942.8 1970.8 96346.0 96679.0 98422.0 108222.5 115348.4 7992.6 9173.8 9707.7 9974.3 10050.5 12294.6 12310.2 12377.1 12377.7 12401.8 7956.3 8143.4 8146.1 8207.1 8208.1 6945.6 8509.9 8521.2 8546.8 8631.7 3907.7 3930.2 3953.5 4007.8 4017.5 1619.5 1942.6 1951.7 1959.3 1995.9 808.3 917.7 946.7 960.8 996.3 17078.9 20866.5 21284.9 21379.9 21428.0 930.2 940.8 946.3 957.4 977.7 10872.7 11219.1 11241.9 11264.3 11269.7 533729.0 536660.0 575512.0 578297.0 587299.0 123903.1 130591.0 140315.0 142186.2 144010.0 1006.8 1013.2 1031.4 1074.1 1080.2 18877.9 19213.9 19286.5 19324.8 19356.7 53652.2 55490.0 57057.1 57095.7 57197.0 53538.0 69591.3 74931.9 79333.0 83308.0 7895.7 8212.3 8221.1 8338.0 8444.4 12174.3 13159.9 13320.5 13427.9 13576.7 11426.5 11446.8 11537.0 11540.8 11584.7 11295.9 11329.9 11403.3 11706.0 11722.1 11623.4 11624.5 11796.0 11976.3 12078.9 7029.3 7382.4 7411.6 7661.7 8550.8 10399.5 11244.4 11443.7 11469.3 11510.8 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5752.60 Median:5251.21 stddev:3197.88 (60.9%) stddev-sans-first:3190.04 17:23:03 INFO - PROCESS | 1649 | Values: 1388.8 1492.1 1574.9 1584.8 1893.7 7870.2 8561.4 8844.0 8851.8 8979.8 8161.8 8212.3 8566.6 8805.1 8817.5 2361.6 2387.8 2391.3 2395.3 2397.7 1954.0 2336.4 2374.1 2376.3 2382.4 2343.6 2362.3 2372.4 2374.9 2384.0 2315.8 2351.4 2356.8 2362.5 2372.0 2327.7 2328.0 2328.8 2330.3 2331.3 8649.5 8831.5 8844.6 8912.6 8917.6 5307.9 5454.3 5454.6 5456.3 5458.3 3498.3 3513.6 3538.8 3599.6 3606.0 1010.6 1027.8 1719.9 1778.8 1819.5 11637.9 11760.0 11765.9 11798.5 11806.8 4425.0 4509.5 4642.5 4643.2 4658.9 9022.6 9126.6 9145.0 9152.1 9175.5 5240.2 5248.8 5253.6 5254.3 5256.3 3953.9 4426.4 4820.6 4834.2 4922.5 3719.6 3742.3 3803.2 3841.4 3844.2 1073.4 1143.2 1157.2 1169.6 1178.5 790.8 840.4 923.8 950.8 971.5 6019.2 6033.6 6102.3 6194.9 6200.5 828.4 925.8 944.1 952.9 990.3 3262.3 3926.5 4113.7 4183.0 4195.1 10633.2 10857.6 10881.6 10975.5 11023.6 10107.0 10904.5 10907.2 10912.0 10921.7 7955.8 8149.1 8314.4 8316.5 8318.0 6050.2 6121.5 6289.3 6326.6 6351.9 9865.9 10015.3 10266.8 10277.8 10313.9 9377.4 9736.8 10146.4 10331.7 10460.9 7441.4 7679.2 7684.3 7685.3 7790.9 8495.5 8568.9 8588.7 8602.1 8608.1 7527.2 7567.3 7606.7 7638.0 7758.1 7493.9 7581.3 7600.5 7646.8 7718.7 6020.8 6945.9 7108.7 7111.3 7127.8 5094.7 5192.8 5318.3 5331.9 5448.2 5292.7 5433.6 5433.7 5518.3 5588.9 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4335.16 Median:4859.56 stddev:1310.59 (27.0%) stddev-sans-first:1277.81 17:23:03 INFO - PROCESS | 1649 | Values: 245.2 258.7 297.7 302.0 302.3 4792.4 5094.2 5199.3 5235.9 5236.6 4326.9 5016.1 5080.8 5139.5 5154.8 3378.5 3730.6 3754.2 3816.9 3930.5 3863.7 3947.2 3952.3 3965.2 3970.1 3891.4 3893.8 3894.6 3902.5 3907.4 3187.5 3714.0 3789.6 3813.6 3937.0 3441.7 3452.6 3456.3 3471.1 3487.1 5057.8 5084.6 5125.1 5125.6 5182.5 4344.2 4575.1 4580.9 4585.6 4599.3 4682.6 5454.8 5474.0 5484.2 5525.9 3191.7 3847.3 3879.4 3880.4 3883.8 5030.2 5335.0 5735.8 5747.2 5756.8 3531.2 3729.6 3766.6 3774.1 3789.1 3195.7 3801.3 3837.8 3851.8 3857.8 4103.7 4381.1 4412.5 4417.2 4456.9 5240.4 5377.5 5393.8 5485.7 5502.4 1278.3 1296.6 1302.2 1303.0 1303.7 692.7 712.9 800.4 810.8 811.6 4225.5 4727.7 4947.7 4948.2 5005.3 4652.8 5127.5 5277.7 5430.6 5472.5 4506.3 4683.7 5014.4 5041.3 5100.4 2775.7 2889.0 2900.9 2903.6 2912.7 4547.4 5622.8 5685.2 5731.8 5810.7 4710.6 4881.8 4970.4 5381.8 5607.6 5170.9 5358.9 5700.6 5735.5 5736.2 4538.8 5481.7 5485.4 5487.8 5505.3 4989.9 5078.7 5112.6 5428.8 5439.2 4689.8 4725.3 5636.6 5662.7 5686.5 3187.5 3823.0 3853.9 3867.0 3872.9 4554.9 4705.4 5242.8 5423.5 5434.5 4891.3 5323.5 5343.6 5348.5 5366.4 4233.9 4735.4 5253.1 5273.0 5309.5 4945.0 5145.8 5162.9 5162.9 5185.2 4420.3 4905.3 4936.3 5279.6 5293.5 4453.7 4650.3 4837.4 4951.1 5191.4 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24314.29 Median:3656.02 stddev:39134.27 (1070.4%) stddev-sans-first:39203.92 17:23:03 INFO - PROCESS | 1649 | Values: 708.5 714.4 750.6 775.9 813.9 15018.9 15609.5 15935.1 15939.4 15941.2 14462.5 14660.4 15423.7 15984.0 15997.0 835.8 850.9 860.0 861.6 862.8 847.9 849.2 850.0 850.5 852.1 893.0 893.4 893.5 893.7 894.8 891.2 892.5 893.0 894.6 895.4 657.6 658.4 658.5 658.5 663.2 13602.3 14482.6 15340.8 15350.4 15373.4 13146.3 13533.1 13974.2 14167.0 14569.9 11735.5 11837.0 11894.0 11928.6 12033.8 3914.4 3926.3 3945.3 4068.8 4075.5 106992.0 109691.0 110275.9 110358.0 110926.0 9549.2 11073.9 11081.3 11104.9 11447.8 22228.0 22401.3 22422.9 22427.0 22453.6 7588.7 7828.0 7884.1 8042.0 8235.2 4467.8 4537.0 4564.0 4580.4 4730.6 3536.5 3655.1 3657.0 3659.1 3920.5 713.9 715.6 720.5 729.6 729.9 522.7 560.4 632.3 677.0 678.0 7800.9 7979.8 8902.4 9055.8 9704.3 634.0 671.4 671.5 676.7 698.1 4501.5 4737.5 4763.6 4775.3 4861.9 101214.5 105362.0 150257.0 156911.2 161942.0 110048.4 128088.0 137463.3 138863.3 139141.0 119108.0 121717.0 121747.6 121750.4 125382.0 9017.4 9023.8 9024.9 9097.5 9528.3 63967.0 64772.0 64886.7 65301.3 65848.0 61104.0 61174.4 61847.7 89249.6 100232.0 11736.9 11792.3 11937.9 11946.2 12017.5 13170.5 13319.3 13383.6 13442.9 13468.5 12128.6 12350.2 12397.4 12854.0 12888.7 12257.4 12495.8 12551.8 12645.9 13058.9 11909.3 12282.4 12351.9 12499.9 12615.3 10556.6 10564.4 10585.2 10805.6 10889.1 9609.8 10533.7 10533.9 10538.7 10562.9 17:23:03 INFO - PROCESS | 1649 | -------- Summary: end -------- 17:23:03 INFO - PROCESS | 1649 | 17:23:03 INFO - TEST-INFO | 1649: exit 0 17:23:03 INFO - TEST-OK | dromaeo_css | took 1114132ms 17:23:03 INFO - TEST-START | kraken 17:23:03 INFO - operating with platform_type : mac_ 17:23:03 INFO - Initialising browser for kraken test... 17:23:03 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmp0dDBlT/profile http://localhost:49271/getInfo.html) 17:23:06 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 17:23:06 INFO - PROCESS | 1659 | colorDepth:24 17:23:06 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 17:23:06 INFO - PROCESS | 1659 | __metrics 17:23:06 INFO - PROCESS | 1659 | 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" 17:23:06 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:23:07 INFO - TEST-INFO | 1659: exit 0 17:23:07 INFO - Browser initialized. 17:23:07 INFO - Running cycle 1/1 for kraken test... 17:23:07 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmp0dDBlT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:23:18 INFO - PROCESS | 1660 | RSS: Main: 244645888 17:23:18 INFO - PROCESS | 1660 | 17:23:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 17:23:22 INFO - PROCESS | 1660 | RSS: Main: 294150144 17:23:22 INFO - PROCESS | 1660 | 17:23:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 17:23:25 INFO - PROCESS | 1660 | RSS: Main: 304140288 17:23:25 INFO - PROCESS | 1660 | 17:23:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 17:23:30 INFO - PROCESS | 1660 | RSS: Main: 390836224 17:23:30 INFO - PROCESS | 1660 | 17:23:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 17:23:32 INFO - PROCESS | 1660 | RSS: Main: 291135488 17:23:32 INFO - PROCESS | 1660 | 17:23:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:23:35 INFO - PROCESS | 1660 | RSS: Main: 295129088 17:23:35 INFO - PROCESS | 1660 | 17:23:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 17:23:38 INFO - PROCESS | 1660 | RSS: Main: 332910592 17:23:38 INFO - PROCESS | 1660 | 17:23:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 17:23:42 INFO - PROCESS | 1660 | RSS: Main: 334004224 17:23:42 INFO - PROCESS | 1660 | 17:23:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 17:23:45 INFO - PROCESS | 1660 | RSS: Main: 331456512 17:23:45 INFO - PROCESS | 1660 | 17:23:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:23:48 INFO - PROCESS | 1660 | RSS: Main: 311439360 17:23:48 INFO - PROCESS | 1660 | 17:23:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:23:50 INFO - PROCESS | 1660 | RSS: Main: 318722048 17:23:50 INFO - PROCESS | 1660 | 17:23:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:23:53 INFO - PROCESS | 1660 | RSS: Main: 315600896 17:23:53 INFO - PROCESS | 1660 | 17:23:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:23:56 INFO - PROCESS | 1660 | RSS: Main: 324509696 17:23:56 INFO - PROCESS | 1660 | 17:23:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:24:00 INFO - PROCESS | 1660 | RSS: Main: 327180288 17:24:00 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 17:24:02 INFO - PROCESS | 1660 | RSS: Main: 371154944 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | __start_tp_report 17:24:02 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 17:24:02 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 17:24:02 INFO - PROCESS | 1660 | |i|pagename|runs| 17:24:02 INFO - PROCESS | 1660 | |0;ai-astar;102;111;113;112;113;112;113;113;113;122 17:24:02 INFO - PROCESS | 1660 | |1;audio-beat-detection;144;106;108;153;109;107;128;105;140;107 17:24:02 INFO - PROCESS | 1660 | |2;audio-dft;183;182;184;180;183;184;182;179;177;180 17:24:02 INFO - PROCESS | 1660 | |3;audio-fft;79;73;58;106;58;58;60;62;85;61 17:24:02 INFO - PROCESS | 1660 | |4;audio-oscillator;81;87;92;76;100;100;123;68;68;130 17:24:02 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;86;84;87;84;86;83;87;84;86;84 17:24:02 INFO - PROCESS | 1660 | |6;imaging-darkroom;92;92;101;94;92;92;93;94;92;100 17:24:02 INFO - PROCESS | 1660 | |7;imaging-desaturate;86;86;83;84;85;85;100;140;134;85 17:24:02 INFO - PROCESS | 1660 | |8;json-parse-financial;56;55;54;55;55;54;55;52;54;54 17:24:02 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;65;70;83;77;71;70;71;70;71;71 17:24:02 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;83;86;84;81;81;79;82;84;83 17:24:02 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;137;138;137;134;130;136;138;119;233;153 17:24:02 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;166;167;164;169;165;165;169;165;166;164 17:24:02 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;68;65;69;65;89;67;66;66;67 17:24:02 INFO - PROCESS | 1660 | __end_tp_report 17:24:02 INFO - PROCESS | 1660 | __start_cc_report 17:24:02 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2106 17:24:02 INFO - PROCESS | 1660 | __end_cc_report 17:24:02 INFO - PROCESS | 1660 | __startTimestamp1467419042301__endTimestamp 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | ------- Summary: start ------- 17:24:02 INFO - PROCESS | 1660 | Number of tests: 14 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:4.77 (4.2%) stddev-sans-first:3.24 17:24:02 INFO - PROCESS | 1660 | Values: 102.0 111.0 113.0 112.0 113.0 112.0 113.0 113.0 113.0 122.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:118.50 stddev:18.70 (15.8%) stddev-sans-first:17.84 17:24:02 INFO - PROCESS | 1660 | Values: 144.0 106.0 108.0 153.0 109.0 107.0 128.0 105.0 140.0 107.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:181.40 Median:182.50 stddev:2.32 (1.3%) stddev-sans-first:2.39 17:24:02 INFO - PROCESS | 1660 | Values: 183.0 182.0 184.0 180.0 183.0 184.0 182.0 179.0 177.0 180.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:70.00 Median:61.50 stddev:15.94 (25.9%) stddev-sans-first:16.58 17:24:02 INFO - PROCESS | 1660 | Values: 79.0 73.0 58.0 106.0 58.0 58.0 60.0 62.0 85.0 61.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:72.00 stddev:21.30 (29.6%) stddev-sans-first:22.19 17:24:02 INFO - PROCESS | 1660 | Values: 81.0 87.0 92.0 76.0 100.0 100.0 123.0 68.0 68.0 130.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.45 (1.7%) stddev-sans-first:1.50 17:24:02 INFO - PROCESS | 1660 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 87.0 84.0 86.0 84.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:92.00 stddev:3.43 (3.7%) stddev-sans-first:3.54 17:24:02 INFO - PROCESS | 1660 | Values: 92.0 92.0 101.0 94.0 92.0 92.0 93.0 94.0 92.0 100.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:85.00 stddev:21.77 (25.6%) stddev-sans-first:22.74 17:24:02 INFO - PROCESS | 1660 | Values: 86.0 86.0 83.0 84.0 85.0 85.0 100.0 140.0 134.0 85.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:0.97 17:24:02 INFO - PROCESS | 1660 | Values: 56.0 55.0 54.0 55.0 55.0 54.0 55.0 52.0 54.0 54.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:71.00 stddev:4.84 (6.8%) stddev-sans-first:4.44 17:24:02 INFO - PROCESS | 1660 | Values: 65.0 70.0 83.0 77.0 71.0 70.0 71.0 70.0 71.0 71.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.50 stddev:2.23 (2.7%) stddev-sans-first:2.07 17:24:02 INFO - PROCESS | 1660 | Values: 86.0 83.0 86.0 84.0 81.0 81.0 79.0 82.0 84.0 83.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:137.50 stddev:31.86 (23.2%) stddev-sans-first:33.64 17:24:02 INFO - PROCESS | 1660 | Values: 137.0 138.0 137.0 134.0 130.0 136.0 138.0 119.0 233.0 153.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.00 stddev:1.83 (1.1%) stddev-sans-first:1.94 17:24:02 INFO - PROCESS | 1660 | Values: 166.0 167.0 164.0 169.0 165.0 165.0 169.0 165.0 166.0 164.0 17:24:02 INFO - PROCESS | 1660 | 17:24:02 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:67.00 stddev:7.17 (10.7%) stddev-sans-first:7.57 17:24:02 INFO - PROCESS | 1660 | Values: 67.0 68.0 65.0 69.0 65.0 89.0 67.0 66.0 66.0 67.0 17:24:02 INFO - PROCESS | 1660 | -------- Summary: end -------- 17:24:02 INFO - PROCESS | 1660 | 17:24:03 INFO - TEST-INFO | 1660: exit 0 17:24:03 INFO - TEST-OK | kraken | took 59210ms 17:24:03 INFO - SUITE-END | took 1173s 17:24:03 INFO - Completed test suite (00:19:33) 17:24:03 INFO - javascript benchmark 17:24:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2863.8854445822167, 3308.4375656138454, 3317.9668203317965, 3340.6492318306564, 3366.596008478983, 5551.139573366125, 5565.4434556544375, 5592.664440133584, 5616.691081990489, 5648.011597970349, 4350.695451318409, 5644.858878528039, 5690.914636280449, 5731.512821410185, 5742.511886489653, 5039.294498770175, 5058.342415485981, 5119.539241468267, 5129.871753206181, 5260.947390526084, 5171.301874246966, 5193.428722840485, 5204.193350030751, 5229.267902493654, 5243.816466423676, 5026.4219614744225, 5045.899082018339, 5068.214426763858, 5104.719240441774, 5128.53843154116, 5110.9744451277875, 5120.539151476385, 5129.461406552333, 5131.384233891919, 5144.202648589475, 4208.452901122834, 5087.262346959707, 5117.462666420047, 5129.461406552333, 5166.638335316529, 5499.917501237484, 5546.251256080458, 5573.526250268732, 5573.804916827891, 5618.943810561883, 6148.508119350441, 6160.507159427235, 6168.599041062316, 6180.505559555225, 6197.442230199259, 6209.96895015522, 6254.155688981975, 6263.279722831887, 6265.498760099181, 6275.497960163176, 5364.22218778275, 5595.0, 5739.913901291442, 5742.13867919807, 5777.74000169985, 6258.561900666909, 6265.4361107500545, 6266.122742816009, 6279.058141278845, 6290.654014029273, 5818.010938140526, 6081.326545338661, 6083.676678410109, 6085.0, 6123.387661233841, 6100.3899610039525, 6103.05402662588, 6138.447539721446, 6143.938560614337, 6144.893919094606, 6127.036821038878, 6168.568200226031, 6178.598391104564, 6180.969095154585, 6199.0, 6167.136600875881, 6175.042868355412, 6182.28903676074, 6194.287656919422, 6208.658523781235, 4765.8570242892765, 4778.832740854054, 4823.782929768169, 4868.805247790048, 4929.852104436873, 4310.849120280838, 4408.713433626804, 4417.536158703354, 4429.7785110744335, 4446.5331140230455, 5542.224088627595, 5616.66300022, 5618.578606604521, 5626.943730562723, 5701.287339082614, 6154.323024467305, 6161.137440758297, 6165.506759459233, 6167.907481387782, 6176.166217560572, 5553.583481238842, 5608.49523542875, 5633.295838020247, 5670.659760414388, 5684.914726279109, 5339.3058902342445, 5379.085555455582, 5397.8110766123, 5404.243405923174, 5406.567474602022, 6253.030780229072, 6265.091561723616, 6271.874562508816, 6280.371962803683, 6287.434130928147, 6196.907046394398, 6209.782657606962, 6231.376862313732, 6261.3112557617715, 6262.749490020348, 5637.9718101409235, 5838.095095260242, 6196.752129914753, 6243.531735119975, 6251.874962500634, 6146.938530614637, 6146.938530614637, 6151.477124444473, 6182.319944805978, 6196.504279657526, 6120.938790612036, 6225.875482490415, 6229.719662615284, 6230.968845155745, 6252.406021427957, 5222.511584198485, 5872.970635146797, 5956.987312156857, 5966.373530779206, 6022.126791615279, 5464.153056276284, 6018.458338749533, 6057.666828324484, 6066.059760737093, 6066.817995460143, 5727.942720572741, 5884.764609415746, 5893.02765043763, 5904.498117660047, 5923.4076592340425, 5971.313298970674, 6016.037434010537, 6051.364606716232, 6084.60450070744, 6091.0558863376245, 5868.647881127146, 5907.556933229939, 5913.645181289136, 5917.142014407801, 5922.674252916131], "value": 5612.719802246633, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.9454057323981, 535.5153586004666, 538.2894579155513, 559.0886854427285, 565.9473379514105, 40451.39322910159, 42045.369319460195, 42056.57943420569, 44304.556954430525, 44359.3346099808, 42853.0, 43232.56767432325, 43347.0, 43393.34909976345, 43410.0, 9579.425234485918, 9760.609575616967, 9765.974572669875, 9780.462074585896, 9781.657641982532, 9665.806683866318, 9777.511124443748, 9780.021997800199, 9782.315237933348, 9783.657571984982, 9433.962264150938, 9475.905240947572, 9491.620335186584, 9500.049995000514, 9533.90466095337, 9731.708048758548, 9786.021397860228, 9787.559559819825, 9801.215902727765, 9807.65673201438, 9655.131038206595, 9730.270229732798, 9732.367396119229, 9736.3671361362, 9742.805143897152, 41447.792761036, 41635.791821041006, 41642.58357416417, 41692.791536042125, 41693.583064169274, 3351.798892066484, 3419.350323438539, 3591.4612808078996, 3847.211321679049, 3997.14061476781, 31305.686943130506, 32247.0, 32557.02328930136, 32645.18387040319, 32734.6726532734, 3058.1284333964713, 3146.732527735145, 3174.206448387903, 3232.208109013307, 3249.366373557157, 451532.0, 452485.0, 452530.73734631116, 453951.73024134996, 454643.9999999967, 62255.0, 62503.0, 62534.68732656308, 62552.748945021754, 62851.685741571, 64378.35621643817, 64705.0, 64826.351736482975, 64907.0, 64910.67544662341, 16657.91671041637, 16885.40901068457, 16904.239309231114, 16905.83094169067, 16929.06890121055, 12879.0, 12930.482780688855, 12942.676433089247, 12956.416961236768, 12964.611061668165, 16323.755143672854, 16569.25438355277, 17690.734638980426, 17696.911515442596, 17704.822951770577, 6853.965730171317, 6869.622170780655, 6905.965470172617, 6995.615241161683, 7017.438604911595, 849.7975364858779, 891.4740303221001, 924.1682485762733, 938.8310104181177, 999.9450579638406, 26601.866990664923, 29758.25604359865, 30201.848990754905, 30803.0, 31454.37091258208, 1054.6097943760858, 1076.2197406880107, 1081.5727787523883, 1134.313740187175, 1140.7376303450253, 15881.0, 15976.360945562048, 16059.87580869328, 16458.588535286715, 16462.506124816275, 638658.8067059728, 903549.0, 921233.0, 923940.0, 926846.0, 60030.69984650049, 60041.0, 60715.39284607186, 60779.69610151921, 60818.391816081275, 1210.3040751567883, 1263.368315842079, 1296.805479178112, 1421.2396367943102, 1432.6919712261913, 30073.849630752582, 31317.217069573442, 32450.0, 32513.34973300568, 32610.836945815117, 56738.0, 57485.425145748006, 58091.70954145202, 58097.54756131131, 58190.0, 577139.0, 585732.0, 585834.0, 587133.0, 588061.0, 24039.0, 26621.7337826627, 26725.06462273811, 26966.0, 27175.0, 47291.527084730085, 47352.7632361836, 47794.52205477901, 47978.76010620064, 48123.75938120287, 39945.40081898832, 39974.20051598894, 40485.0, 41205.793971029954, 41240.76277711673, 43013.1397372057, 43044.78477607592, 43596.56403435925, 44896.32655510105, 46444.53555464537, 39690.0, 42291.0, 42898.92752681083, 42904.0, 44419.77790111029, 40478.0, 42771.0, 43022.35466468068, 43380.56619433891, 43399.34900976425, 32288.35423291568, 32617.673823261463, 32716.854910078033, 32770.50844237386, 33199.21818661036], "value": 20848.930160743104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.2290877702436, 390.78506821248317, 390.89836642472966, 399.53454225826914, 410.52174217037134, 17786.82213177871, 18851.340203092896, 21181.258655947044, 21531.13875444982, 21535.892320538394, 20214.393568192976, 20871.686924696143, 21086.367408977716, 21151.73089614624, 21242.36272911811, 5395.163749618796, 6002.1596976423225, 6109.297430795449, 6148.169997050408, 6157.7536898524, 4236.089240813225, 4521.638268938494, 4529.977350113245, 4532.818687252506, 4544.568266014723, 4027.2750904837117, 4318.870433886973, 4503.504614492404, 6867.656617169147, 6974.0, 5459.344878614592, 5829.533637309005, 6597.571157874723, 6913.89629155563, 6940.993555934387, 5840.941590584082, 5863.8534036649, 5867.530597552186, 5872.236609240771, 5878.206442130343, 18084.095795210185, 19443.0, 25596.616050759254, 25641.35896602581, 25804.0, 1918.7409699690502, 2173.7717539658265, 2186.5954798362195, 2260.4412864992864, 2517.0309430869247, 10009.89990100097, 10493.160547156209, 10768.946155269174, 11021.897810218994, 16405.91797041007, 1844.1516902224992, 1893.943181704551, 1909.3412772593433, 1942.8348590369835, 1970.753655793026, 96346.0, 96679.0, 98422.01577984272, 108222.45888770584, 115348.42325788402, 7992.560409177435, 9173.77065573366, 9707.70876873695, 9974.301798874008, 10050.497475126214, 12294.631161065183, 12310.199837010565, 12377.133600647869, 12377.690557735948, 12401.813972790414, 7956.283934445928, 8143.389245806588, 8146.103928567853, 8207.056188538276, 8208.138145494757, 6945.617991010543, 8509.914900851036, 8521.190486903733, 8546.786330341667, 8631.741047768577, 3907.7069219808627, 3930.155016671429, 3953.525576930729, 4007.8396864125693, 4017.4978127734034, 1619.5222409389023, 1942.5920556682972, 1951.707243913396, 1959.314240015983, 1995.8802471851736, 808.3492788305471, 917.6647977193168, 946.7301818981557, 960.8222478841436, 996.3175224970918, 17078.914605426893, 20866.47833804137, 21284.893575532333, 21379.931003450078, 21428.0, 930.2465003347311, 940.8165407745561, 946.2666433514082, 957.3872721458272, 977.7360112769512, 10872.67381978542, 11219.102471802236, 11241.943790280997, 11264.267822591506, 11269.66191014271, 533729.0, 536660.0, 575512.0, 578297.0, 587299.0, 123903.14145287828, 130591.0, 140315.0, 142186.1562768739, 144010.0, 1006.7885743993697, 1013.2147585621202, 1031.3554028732042, 1074.070928646715, 1080.2384319055047, 18877.90561047186, 19213.943233122314, 19286.517837054187, 19324.80675193286, 19356.70964935555, 53652.195217072556, 55490.0, 57057.14414283872, 57095.71452142713, 57197.0, 53538.0, 69591.30408695848, 74931.87874666946, 79333.0, 83308.0, 7895.6841726332605, 8212.3430125591, 8221.054578723284, 8337.999440066988, 8444.366672499464, 12174.330411827434, 13159.868401315864, 13320.467181312639, 13427.932860335635, 13576.728465430833, 11426.460752113646, 11446.828297575377, 11537.01935335506, 11540.769184616429, 11584.710382240175, 11295.887041129812, 11329.886701132884, 11403.258788178742, 11706.004989575982, 11722.120840936794, 11623.418829058683, 11624.5350185995, 11796.0, 11976.341301228167, 12078.939605301917, 7029.261927497743, 7382.4094072475145, 7411.62941852916, 7661.697511423183, 8550.78623034429, 10399.53202105892, 11244.437778111225, 11443.713907152387, 11469.31184128955, 11510.82733759011], "value": 9399.63520409975, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.8472268050507, 1492.0823693428545, 1574.9291281892313, 1584.7939767830187, 1893.6591413545557, 7870.173631768661, 8561.400701950866, 8843.999999999984, 8851.822963540733, 8979.775505612346, 8161.755147345589, 8212.260896519312, 8566.571671416435, 8805.119488051192, 8817.515036672981, 2361.633946738254, 2387.8209134314907, 2391.3065211088765, 2395.317334559651, 2397.6643269942224, 1953.9706904396446, 2336.427575244059, 2374.1453076892303, 2376.275236053003, 2382.4163080045414, 2343.60158773009, 2362.303120579433, 2372.4306166519996, 2374.893129809163, 2383.976160238393, 2315.814734821227, 2351.4239011442137, 2356.7996720278798, 2362.5038741864228, 2371.9525609487887, 2327.7323107842476, 2327.976720232793, 2328.7904088631935, 2330.2892617751577, 2331.3355693627404, 8649.481031138152, 8831.470111793313, 8844.55777211137, 8912.554372281425, 8917.643294268222, 5307.946920530784, 5454.290942177491, 5454.59090568209, 5456.263404440429, 5458.344998600193, 3498.265364273506, 3513.5608048993877, 3538.752287339887, 3599.6040435552068, 3606.04439823447, 1010.5907107621351, 1027.7790275090817, 1719.8538124259453, 1778.8310110539608, 1819.4905426480607, 11637.88362116385, 11760.000399966013, 11765.941170294094, 11798.528058877635, 11806.763864722743, 4425.032930247335, 4509.526499717548, 4642.53574642533, 4643.233866412072, 4658.883527911829, 9022.593983270768, 9126.634934602675, 9145.0, 9152.084791520927, 9175.541222938826, 5240.213967904845, 5248.790048398021, 5253.632245742761, 5254.343207099113, 5256.316678831728, 3953.9214200883093, 4426.424564806554, 4820.566149046533, 4834.178189707758, 4922.507749225048, 3719.6094410087094, 3742.3263812513464, 3803.23935212953, 3841.4045822896937, 3844.1927195289304, 1073.388168743824, 1143.1826244235413, 1157.242006485753, 1169.6374124021581, 1178.452019810792, 790.7864876483317, 840.3949156607233, 923.7690577355661, 950.8288508068475, 971.4710989828826, 6019.187409699722, 6033.577649564576, 6102.267727872683, 6194.876102478015, 6200.507993425986, 828.4325237212529, 925.8426966292135, 944.0795224656043, 952.8904176019846, 990.346371394876, 3262.331222099464, 3926.54844692864, 4113.670906327487, 4182.958170418317, 4195.056112374752, 10633.202509811796, 10857.565697372174, 10881.564737410416, 10975.50610222542, 11023.559057637605, 10107.0, 10904.454777261264, 10907.236493445384, 10912.017918387382, 10921.726956825984, 7955.801104972422, 8149.103598604267, 8314.41799074059, 8316.459430137022, 8317.958410208032, 6050.15297858309, 6121.47967422774, 6289.339619339903, 6326.563185826569, 6351.904721429275, 9865.901340986786, 10015.298929074896, 10266.845997309896, 10277.794444111225, 10313.9484302578, 9377.390469619453, 9736.75658108553, 10146.441945692763, 10331.741706457398, 10460.895391046297, 7441.367483763941, 7679.19368466303, 7684.269994350528, 7685.269899359552, 7790.8831367530665, 8495.490270583785, 8568.871466928125, 8588.742337729878, 8602.096779838026, 8608.139186081342, 7527.247275272429, 7567.31894129531, 7606.657700403606, 7638.007059082283, 7758.146603873683, 7493.925060749323, 7581.3176814087, 7600.50596711212, 7646.770596882103, 7718.652660630173, 6020.819375418744, 6945.895811562932, 7108.715651374094, 7111.288871112847, 7127.821804454723, 5094.745262736774, 5192.792288308577, 5318.255444237732, 5331.866703332293, 5448.155535891943, 5292.7353632319, 5433.592480563894, 5433.701146436984, 5518.310211223597, 5588.944110558842], "value": 4637.404098417381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [245.2018679199207, 258.72704296966714, 297.68891508373747, 301.96727192999936, 302.3212887068531, 4792.418501894375, 5094.18493041113, 5199.272101905732, 5235.86910327242, 5236.554892834104, 4326.8918277043085, 5016.097102521545, 5080.771365288561, 5139.511746384097, 5154.819581314655, 3378.459446488562, 3730.5523337199506, 3754.230382771539, 3816.9427458588143, 3930.547986981491, 3863.652271295583, 3947.1908258806875, 3952.288588054149, 3965.20695860829, 3970.1464185200175, 3891.435741817435, 3893.785841778701, 3894.571597124314, 3902.512185976753, 3907.4138879168067, 3187.490001599756, 3714.015785816747, 3789.6399842014966, 3813.599572044951, 3936.9803150984203, 3441.7074548663395, 3452.6374730653424, 3456.274182421669, 3471.1495683557437, 3487.1456493159085, 5057.848264552033, 5084.593232541425, 5125.104316061414, 5125.56484184429, 5182.507661772125, 4344.1963236800975, 4575.130725162208, 4580.908381832378, 4585.633149348044, 4599.264117741179, 4682.6253899687945, 5454.754536045887, 5474.0, 5484.203056441203, 5525.944740552583, 3191.664875188095, 3847.2882516734494, 3879.359905615598, 3880.417937309398, 3883.825227864753, 5030.195168772979, 5335.039692855285, 5735.77056917727, 5747.15515819672, 5756.7985120521, 3531.2407832316176, 3729.6083911189476, 3766.5856755756845, 3774.075091803874, 3789.0716774390453, 3195.6964088411564, 3801.258754542893, 3837.808109594509, 3851.8459261629782, 3857.7847977886877, 4103.712740108223, 4381.058072514425, 4412.492563355255, 4417.226985277628, 4456.866294011184, 5240.397354304304, 5377.489138531833, 5393.7572809223675, 5485.725713714299, 5502.394736578973, 1278.284160869916, 1296.623979046085, 1302.2121616422119, 1302.9609111726663, 1303.7066660001612, 692.7125243024088, 712.9251428600026, 800.3957012455675, 810.7527204202775, 811.6104269950339, 4225.549181474737, 4727.692699974491, 4947.678400903929, 4948.158813001798, 5005.324281221989, 4652.813887444464, 5127.462051184745, 5277.736113194402, 5430.619856609999, 5472.480114389126, 4506.2564676828615, 4683.704984557542, 5014.420457555858, 5041.269015992661, 5100.387953445536, 2775.653043369579, 2889.0, 2900.941981160365, 2903.637045369329, 2912.6796052434215, 4547.3633691282585, 5622.803201887996, 5685.167607533564, 5731.770729170787, 5810.738516766755, 4710.599599034052, 4881.755912204376, 4970.353853998957, 5381.7578208980685, 5607.635503692247, 5170.922436163385, 5358.91961620576, 5700.6009579329875, 5735.483806457355, 5736.225609542742, 4538.842595138219, 5481.671099734029, 5485.369182544058, 5487.835364939058, 5505.311836020497, 4989.850304490871, 5078.669886457368, 5112.565431938327, 5428.83713488596, 5439.184122381675, 4689.835855745033, 4725.314829349793, 5636.633618814764, 5662.66024038559, 5686.516646085129, 3187.5218717192606, 3823.044238940265, 3853.865114720971, 3867.0, 3872.9349428907276, 4554.9316760249285, 4705.364775755229, 5242.790288388422, 5423.511883930308, 5434.483724046209, 4891.315215869852, 5323.494268044529, 5343.599230057684, 5348.465153484621, 5366.382865970463, 4233.936490952699, 4735.431748190239, 5253.106971814725, 5272.996261420635, 5309.495597918191, 4945.010997800527, 5145.761593706749, 5162.8967420652125, 5162.9483705162465, 5185.248139019745, 4420.336949457607, 4905.258633185168, 4936.259561065869, 5279.604029697711, 5293.470652934676, 4453.686162581981, 4650.325702773147, 4837.37114175155, 4951.133551628523, 5191.377034755854], "value": 3906.7635341618047, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.5111273221478, 714.4320265389015, 750.5871770526211, 775.8913752074707, 813.8819871118684, 15018.924905375472, 15609.453669121584, 15935.123568203744, 15939.362425502966, 15941.20293985302, 14462.493812716557, 14660.41358345665, 15423.691526169498, 15984.040957542527, 15997.0, 835.795230168608, 850.8681154421043, 860.0195776814405, 861.5864385095158, 862.7584276402586, 847.8643417053273, 849.1847826086964, 850.0437008365589, 850.4897061762955, 852.1138016462871, 892.9864603674844, 893.3924931046886, 893.4996402014892, 893.7363477774072, 894.7941973346102, 891.1534042659499, 892.4868200784575, 892.995535022324, 894.6108442827358, 895.4403497813867, 657.621867426227, 658.427168363522, 658.5192809249227, 658.5456035335603, 663.1644128398204, 13602.31988400576, 14482.637934051627, 15340.846591534053, 15350.385984560604, 15373.385064597403, 13146.276954767483, 13533.120347177402, 13974.161550306913, 14167.033230695422, 14569.854301457062, 11735.471903764352, 11837.0, 11894.0, 11928.58249961256, 12033.759324813542, 3914.3541315683165, 3926.3325234709882, 3945.2701250268506, 4068.837246510136, 4075.4701888754566, 106992.0, 109691.0, 110275.89724102737, 110358.0, 110926.0, 9549.236061115094, 11073.944630276797, 11081.33511989267, 11104.944475277573, 11447.828282575769, 22228.0, 22401.327960161223, 22422.887885560467, 22427.0, 22453.550928981327, 7588.696452141963, 7828.0, 7884.132745398002, 8042.0, 8235.21765432283, 4467.767464387227, 4537.0, 4563.977180114078, 4580.358749774968, 4730.597899178609, 3536.504889315498, 3655.0862284428895, 3656.9634303657153, 3659.0852286928266, 3920.4707364506, 713.9036230108973, 715.588536591462, 720.4668545276458, 729.6023667101337, 729.923358047408, 522.7281813456981, 560.3723829311198, 632.2855173653742, 676.9932300677028, 677.9830254618073, 7800.92199078002, 7979.84040319202, 8902.376833621714, 9055.81888362229, 9704.320697551104, 633.996830015847, 671.4326394196896, 671.4896678524259, 676.7428377216725, 698.1482591238681, 4501.527339629357, 4737.45519265282, 4763.632568057211, 4775.307580400892, 4861.9027619447415, 101214.48178277332, 105362.0, 150257.0, 156911.21544392206, 161942.0, 110048.4497577507, 128088.0, 137463.3088227965, 138863.30568347094, 139141.0, 119108.0, 121717.0, 121747.56504869853, 121750.39124804319, 125382.0, 9017.384958362001, 9023.774405639911, 9024.864627030469, 9097.545122743968, 9528.333016688764, 63967.0, 64772.0, 64886.67556662187, 65301.34698652953, 65848.0, 61104.0, 61174.38825611687, 61847.690761545906, 89249.55375223083, 100232.0, 11736.88629239365, 11792.29246245228, 11937.880621194025, 11946.163768536118, 12017.4592143352, 13170.47318107265, 13319.33403329849, 13383.598492045254, 13442.865571344553, 13468.5286014989, 12128.636140915787, 12350.197237179555, 12397.44211510466, 12854.035947303802, 12888.742225155256, 12257.387130643254, 12495.812562811383, 12551.811722824348, 12645.87354126447, 13058.869411306145, 11909.285442873455, 12282.385880706108, 12351.876481235073, 12499.875001250235, 12615.30615816139, 10556.577736890438, 10564.366138031743, 10585.206109541661, 10805.567777288821, 10889.059953812584, 9609.807803844024, 10533.683989480327, 10533.9473302633, 10538.683839484827, 10562.87984575086], "value": 7734.712222694488, "unit": "score"}], "name": "dromaeo_css", "value": 7322.493209541279, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0, 113.0, 122.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 106.0, 108.0, 153.0, 109.0, 107.0, 128.0, 105.0, 140.0, 107.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 184.0, 180.0, 183.0, 184.0, 182.0, 179.0, 177.0, 180.0], "value": 181.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 106.0, 58.0, 58.0, 60.0, 62.0, 85.0, 61.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 87.0, 92.0, 76.0, 100.0, 100.0, 123.0, 68.0, 68.0, 130.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 87.0, 84.0, 86.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 101.0, 94.0, 92.0, 92.0, 93.0, 94.0, 92.0, 100.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 84.0, 85.0, 85.0, 100.0, 140.0, 134.0, 85.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 52.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 83.0, 77.0, 71.0, 70.0, 71.0, 70.0, 71.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 81.0, 81.0, 79.0, 82.0, 84.0, 83.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 137.0, 134.0, 130.0, 136.0, 138.0, 119.0, 233.0, 153.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 164.0, 169.0, 165.0, 165.0, 169.0, 165.0, 166.0, 164.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 69.0, 65.0, 89.0, 67.0, 66.0, 66.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1442.7, "alertThreshold": 2.0}]} 17:24:03 INFO - Return code: 0 17:24:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:24:03 INFO - # TBPL SUCCESS # 17:24:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:24:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:24:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:24:03 INFO - 2016-07-01 17:24:03.642 screenresolution[1664:10121] starting screenresolution argv=screenresolution get 17:24:03 INFO - 2016-07-01 17:24:03.645 screenresolution[1664:10121] Display 0: 1600x1200x32@60 17:24:03 INFO - 2016-07-01 17:24:03.651 screenresolution[1665:10124] starting screenresolution argv=screenresolution list 17:24:03 INFO - Available Modes on Display 0 17:24:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:24:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:24:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:24:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:24:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:24:03 INFO - Intel Iris: 17:24:03 INFO - Chipset Model: Intel Iris 17:24:03 INFO - Type: GPU 17:24:03 INFO - Bus: Built-In 17:24:03 INFO - VRAM (Dynamic, Max): 1536 MB 17:24:03 INFO - Vendor: Intel (0x8086) 17:24:03 INFO - Device ID: 0x0a2e 17:24:03 INFO - Revision ID: 0x0009 17:24:03 INFO - Displays: 17:24:03 INFO - Display: 17:24:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:24:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:24:03 INFO - Display Serial Number: 1600x1200 60 17:24:03 INFO - Main Display: Yes 17:24:03 INFO - Mirror: Off 17:24:03 INFO - Online: Yes 17:24:03 INFO - Rotation: Supported 17:24:03 INFO - Return code: 0 17:24:03 INFO - Running post-action listener: _resource_record_post_action 17:24:03 INFO - ##### 17:24:03 INFO - ##### Finished run-tests step (success) 17:24:03 INFO - ##### 17:24:03 INFO - Running post-run listener: _resource_record_post_run 17:24:03 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 36840960; Write bytes: 706304000; Read time: 236; Write time: 1939 17:24:03 INFO - TinderboxPrint: CPU usage
53.3% 17:24:03 INFO - TinderboxPrint: I/O read bytes / time
36,840,960 / 236 17:24:03 INFO - TinderboxPrint: I/O write bytes / time
706,304,000 / 1,939 17:24:03 INFO - TinderboxPrint: CPU idle
2,258.2 (46.7%) 17:24:03 INFO - TinderboxPrint: CPU system
959.7 (19.9%) 17:24:03 INFO - TinderboxPrint: CPU user
1,615.7 (33.4%) 17:24:03 INFO - TinderboxPrint: Swap in / out
443,777,024 / 0 17:24:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404650496; Write bytes: 399812608; Read time: 29733; Write time: 795 17:24:03 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 34436608; Write bytes: 294629376; Read time: 216; Write time: 1109 17:24:04 INFO - Running post-run listener: _upload_blobber_files 17:24:04 INFO - Blob upload gear active. 17:24:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:24:04 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 17:24:04 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'] 17:24:04 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 17:24:04 INFO - (blobuploader) - INFO - Open directory for files ... 17:24:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:24:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:24:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:05 INFO - (blobuploader) - INFO - Done attempting. 17:24:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:24:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:24:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:06 INFO - (blobuploader) - INFO - Done attempting. 17:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:24:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:08 INFO - (blobuploader) - INFO - Done attempting. 17:24:08 INFO - (blobuploader) - INFO - Iteration through files over. 17:24:08 INFO - Return code: 0 17:24:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:24:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:24:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/941ac06e9f0a54b1b27b7b63d1c94bd32c6bf6f08c8de8a88fa670456c542d9380cc1458a203629281ec736767265b94d642648237b6cd1603bc00a5fe601649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbed04f67f0bdaf0bd60520f0159ccae6e62a17c56e9121688ad30c37a401925ec46a604f6364e12199f5bf35eff31dd90261b97241436e300352834c6202cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f1c2d14809edf12c530fb63bd78e573558afb53a98732f24bc3e3016f05305909164e90b2d7402fee99a83cdd5f291118d0a107801bd1b9fac3571f38729c57"} 17:24:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:24:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:24:08 INFO - Contents: 17:24:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/941ac06e9f0a54b1b27b7b63d1c94bd32c6bf6f08c8de8a88fa670456c542d9380cc1458a203629281ec736767265b94d642648237b6cd1603bc00a5fe601649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbed04f67f0bdaf0bd60520f0159ccae6e62a17c56e9121688ad30c37a401925ec46a604f6364e12199f5bf35eff31dd90261b97241436e300352834c6202cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f1c2d14809edf12c530fb63bd78e573558afb53a98732f24bc3e3016f05305909164e90b2d7402fee99a83cdd5f291118d0a107801bd1b9fac3571f38729c57"} 17:24:08 INFO - Running post-run listener: copy_logs_to_upload_dir 17:24:08 INFO - Copying logs to upload dir... 17:24:08 INFO - mkdir: /builds/slave/test/build/upload/logs 17:24:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.100886 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-01 17:24:08.889277) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 17:24:08.895166) ========= 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.kjzys4fwXo/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.YNrVU6at9Y/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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/941ac06e9f0a54b1b27b7b63d1c94bd32c6bf6f08c8de8a88fa670456c542d9380cc1458a203629281ec736767265b94d642648237b6cd1603bc00a5fe601649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbed04f67f0bdaf0bd60520f0159ccae6e62a17c56e9121688ad30c37a401925ec46a604f6364e12199f5bf35eff31dd90261b97241436e300352834c6202cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f1c2d14809edf12c530fb63bd78e573558afb53a98732f24bc3e3016f05305909164e90b2d7402fee99a83cdd5f291118d0a107801bd1b9fac3571f38729c57"} build_url:https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010781 build_url: 'https://queue.taskcluster.net/v1/task/XxtCCM2wQMmwA9BoEGgecA/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/941ac06e9f0a54b1b27b7b63d1c94bd32c6bf6f08c8de8a88fa670456c542d9380cc1458a203629281ec736767265b94d642648237b6cd1603bc00a5fe601649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cdbed04f67f0bdaf0bd60520f0159ccae6e62a17c56e9121688ad30c37a401925ec46a604f6364e12199f5bf35eff31dd90261b97241436e300352834c6202cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f1c2d14809edf12c530fb63bd78e573558afb53a98732f24bc3e3016f05305909164e90b2d7402fee99a83cdd5f291118d0a107801bd1b9fac3571f38729c57"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 17:24:08.928151) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:24:08.928557) ========= 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.kjzys4fwXo/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.YNrVU6at9Y/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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.005060 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:24:08.978094) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 17:24:08.978545) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 17:24:08.978951) ========= ========= Total master_lag: 0.18 =========