builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0186 starttime: 1468407658.68 results: success (0) buildid: 20160713021310 builduid: 06c62b7d009046c796b54135e68167bc revision: bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.677659) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.678119) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.678394) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.703701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.704097) ========= 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.gdn5aVFsPh/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.n4rXjwFz05/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-13 04:00:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 33.1M=0s 2016-07-13 04:00:58 (33.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.150088 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.876940) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.877266) ========= 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.gdn5aVFsPh/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.n4rXjwFz05/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.027465 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 04:00:58.926001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 04:00:58.926362) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6 --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 bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gdn5aVFsPh/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.n4rXjwFz05/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-13 04:00:58,999 truncating revision to first 12 chars 2016-07-13 04:00:58,999 Setting DEBUG logging. 2016-07-13 04:00:58,999 attempt 1/10 2016-07-13 04:00:59,000 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bbbf2ec4d6a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 04:00:59,791 unpacking tar archive at: fx-team-bbbf2ec4d6a2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.064643 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 04:01:00.013482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 04:01:00.013805) ========= script_repo_revision: bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 04:01:00.014198) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 04:01:00.014478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 04:01:00.033784) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-13 04:01:00.034182) ========= /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.gdn5aVFsPh/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.n4rXjwFz05/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:00 INFO - MultiFileLogger online at 20160713 04:01:00 in /builds/slave/test 04:01:00 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 04:01:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:01:00 INFO - {'append_to_log': False, 04:01:00 INFO - 'base_work_dir': '/builds/slave/test', 04:01:00 INFO - 'blob_upload_branch': 'Fx-Team', 04:01:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:01:00 INFO - 'branch': 'Fx-Team', 04:01:00 INFO - 'buildbot_json_path': 'buildprops.json', 04:01:00 INFO - 'config_files': ('talos/mac_config.py',), 04:01:00 INFO - 'default_actions': ('clobber', 04:01:00 INFO - 'read-buildbot-config', 04:01:00 INFO - 'download-and-extract', 04:01:00 INFO - 'populate-webroot', 04:01:00 INFO - 'create-virtualenv', 04:01:00 INFO - 'install', 04:01:00 INFO - 'run-tests'), 04:01:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:01:00 INFO - 'download_minidump_stackwalk': True, 04:01:00 INFO - 'download_symbols': 'ondemand', 04:01:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:01:00 INFO - 'tooltool.py': '/tools/tooltool.py', 04:01:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:01:00 INFO - '/tools/misc-python/virtualenv.py')}, 04:01:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:01:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:01:00 INFO - 'installer_path': 'installer.exe', 04:01:00 INFO - 'log_level': 'info', 04:01:00 INFO - 'log_name': 'talos', 04:01:00 INFO - 'log_to_console': True, 04:01:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:01:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:01:00 INFO - 'opt_config_files': (), 04:01:00 INFO - 'pip_index': False, 04:01:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:01:00 INFO - 'cmd': ('bash', 04:01:00 INFO - '-c', 04:01:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:01:00 INFO - 'enabled': True, 04:01:00 INFO - 'halt_on_failure': False, 04:01:00 INFO - 'name': 'check_screen_resolution'},), 04:01:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:01:00 INFO - 'cmd': ('bash', 04:01:00 INFO - '-c', 04:01:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:01:00 INFO - 'enabled': True, 04:01:00 INFO - 'halt_on_failure': False, 04:01:00 INFO - 'name': 'check_screen_resolution'},), 04:01:00 INFO - 'run_cmd_checks_enabled': True, 04:01:00 INFO - 'sps_profile': False, 04:01:00 INFO - 'sps_profile_interval': 0, 04:01:00 INFO - 'suite': 'dromaeojs', 04:01:00 INFO - 'system_bits': '32', 04:01:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:01:00 INFO - 'title': 't-yosemite-r7-0186', 04:01:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:01:00 INFO - 'use_talos_json': True, 04:01:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:01:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:01:00 INFO - 'work_dir': 'build'} 04:01:00 INFO - ##### 04:01:00 INFO - ##### Running clobber step. 04:01:00 INFO - ##### 04:01:00 INFO - Running pre-action listener: _resource_record_pre_action 04:01:00 INFO - Running main action method: clobber 04:01:00 INFO - rmtree: /builds/slave/test/build 04:01:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:01:02 INFO - Running post-action listener: _resource_record_post_action 04:01:02 INFO - ##### 04:01:02 INFO - ##### Finished clobber step (success) 04:01:02 INFO - ##### 04:01:02 INFO - ##### 04:01:02 INFO - ##### Running read-buildbot-config step. 04:01:02 INFO - ##### 04:01:02 INFO - Running pre-action listener: _resource_record_pre_action 04:01:02 INFO - Running main action method: read_buildbot_config 04:01:02 INFO - Using buildbot properties: 04:01:02 INFO - { 04:01:02 INFO - "project": "", 04:01:02 INFO - "product": "firefox", 04:01:02 INFO - "script_repo_revision": "production", 04:01:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:01:02 INFO - "repository": "", 04:01:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:01:02 INFO - "buildid": "20160713021310", 04:01:02 INFO - "pgo_build": "False", 04:01:02 INFO - "basedir": "/builds/slave/test", 04:01:02 INFO - "buildnumber": 843, 04:01:02 INFO - "slavename": "t-yosemite-r7-0186", 04:01:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:01:02 INFO - "platform": "yosemite_r7", 04:01:02 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:01:02 INFO - "branch": "fx-team", 04:01:02 INFO - "repo_path": "integration/fx-team", 04:01:02 INFO - "revision": "bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6", 04:01:02 INFO - "stage_platform": "macosx64", 04:01:02 INFO - "builduid": "06c62b7d009046c796b54135e68167bc", 04:01:02 INFO - "slavebuilddir": "test" 04:01:02 INFO - } 04:01:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:01:02 INFO - Running post-action listener: _resource_record_post_action 04:01:02 INFO - ##### 04:01:02 INFO - ##### Finished read-buildbot-config step (success) 04:01:02 INFO - ##### 04:01:02 INFO - ##### 04:01:02 INFO - ##### Running download-and-extract step. 04:01:02 INFO - ##### 04:01:02 INFO - Running pre-action listener: _resource_record_pre_action 04:01:02 INFO - Running main action method: download_and_extract 04:01:02 INFO - mkdir: /builds/slave/test/build/tests 04:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:02 INFO - https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:01:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:01:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:01:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:03 INFO - Downloaded 1199 bytes. 04:01:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:01:03 INFO - Using the following test package requirements: 04:01:03 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:01:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:01:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:01:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'jsshell-mac.zip'], 04:01:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:01:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:01:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:01:03 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:01:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:01:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:01:03 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:01:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:01: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')]} 04:01:03 INFO - https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:01:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:01:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:01:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:05 INFO - Downloaded 18822804 bytes. 04:01: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'] 04:01: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 04:01:08 INFO - Return code: 0 04:01: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 04:01: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')]} 04:01:08 INFO - https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:01:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:01:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:01:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:11 INFO - Downloaded 18822804 bytes. 04:01:11 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'] 04:01:11 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 04:01:14 INFO - Return code: 0 04:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:14 INFO - https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:01:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:01:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:01:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:16 INFO - Downloaded 14403317 bytes. 04:01:16 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'] 04:01:16 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 04:01:16 INFO - Return code: 0 04:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:16 INFO - https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:01:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:01:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:01:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:01:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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 04:01:21 INFO - Downloaded 112757815 bytes. 04:01:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:01:21 INFO - mkdir: /builds/slave/test/properties 04:01:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:01:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:01:21 INFO - Contents: 04:01:21 INFO - build_url:https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:01:22 INFO - Running post-action listener: _resource_record_post_action 04:01:22 INFO - Running post-action listener: set_extra_try_arguments 04:01:22 INFO - ##### 04:01:22 INFO - ##### Finished download-and-extract step (success) 04:01:22 INFO - ##### 04:01:22 INFO - ##### 04:01:22 INFO - ##### Running populate-webroot step. 04:01:22 INFO - ##### 04:01:22 INFO - Running pre-action listener: _resource_record_pre_action 04:01:22 INFO - Running main action method: populate_webroot 04:01:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:01:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:01:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:01:22 INFO - '--tppagecycles', 04:01:22 INFO - '7'], 04:01:22 INFO - 'tests': ['tsvgm']}}, 04:01:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:01:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:01:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:01:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:01:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['damp', 'tps']}, 04:01:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'tests': ['damp', 'tps']}, 04:01:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['dromaeo_dom']}, 04:01:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:01:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['basic_compositor_video']}, 04:01:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:01:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['a11yr', 04:01:22 INFO - 'ts_paint', 04:01:22 INFO - 'tpaint', 04:01:22 INFO - 'sessionrestore', 04:01:22 INFO - 'sessionrestore_no_auto_restore', 04:01:22 INFO - 'tabpaint']}, 04:01:22 INFO - 'other-e10s': {'tests': ['a11yr', 04:01:22 INFO - 'ts_paint', 04:01:22 INFO - 'tpaint', 04:01:22 INFO - 'sessionrestore', 04:01:22 INFO - 'sessionrestore_no_auto_restore', 04:01:22 INFO - 'tabpaint']}, 04:01:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:01:22 INFO - 'ts_paint', 04:01:22 INFO - 'tpaint', 04:01:22 INFO - 'sessionrestore', 04:01:22 INFO - 'sessionrestore_no_auto_restore', 04:01:22 INFO - 'tabpaint']}, 04:01:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:01:22 INFO - 'ts_paint', 04:01:22 INFO - 'tpaint', 04:01:22 INFO - 'sessionrestore', 04:01:22 INFO - 'sessionrestore_no_auto_restore', 04:01:22 INFO - 'tabpaint']}, 04:01:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['a11yr', 04:01:22 INFO - 'ts_paint', 04:01:22 INFO - 'tpaint', 04:01:22 INFO - 'sessionrestore', 04:01:22 INFO - 'sessionrestore_no_auto_restore', 04:01:22 INFO - 'tabpaint']}, 04:01:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['a11yr', 04:01:22 INFO - 'ts_paint', 04:01:22 INFO - 'tpaint', 04:01:22 INFO - 'sessionrestore', 04:01:22 INFO - 'sessionrestore_no_auto_restore', 04:01:22 INFO - 'tabpaint']}, 04:01:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['tsvgx', 04:01:22 INFO - 'tsvgr_opacity', 04:01:22 INFO - 'tart', 04:01:22 INFO - 'tscrollx', 04:01:22 INFO - 'cart']}, 04:01:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:01:22 INFO - 'tsvgr_opacity', 04:01:22 INFO - 'tart', 04:01:22 INFO - 'tscrollx', 04:01:22 INFO - 'cart']}, 04:01:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'talos_options': ['--disable-e10s'], 04:01:22 INFO - 'tests': ['tp5o']}, 04:01:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'tests': ['tp5o']}, 04:01:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'talos_options': ['--disable-e10s', 04:01:22 INFO - '--xperf_path', 04:01:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:22 INFO - 'tests': ['tp5n']}, 04:01:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:22 INFO - 'talos_options': ['--xperf_path', 04:01:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:22 INFO - 'tests': ['tp5n']}}, 04:01:22 INFO - 'talos.zip': {'path': '', 04:01:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:01:22 INFO - Running post-action listener: _resource_record_post_action 04:01:22 INFO - ##### 04:01:22 INFO - ##### Finished populate-webroot step (success) 04:01:22 INFO - ##### 04:01:22 INFO - ##### 04:01:22 INFO - ##### Running create-virtualenv step. 04:01:22 INFO - ##### 04:01:22 INFO - Running pre-action listener: _resource_record_pre_action 04:01:22 INFO - Running main action method: create_virtualenv 04:01:22 INFO - Creating virtualenv /builds/slave/test/build/venv 04:01:22 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 04:01:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:01:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:01:22 INFO - Using real prefix '/tools/python27' 04:01:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:01:23 INFO - Installing distribute.............................................................................................................................................................................................done. 04:01:26 INFO - Installing pip.................done. 04:01:26 INFO - Return code: 0 04:01:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:26 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:26 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 04:01:26 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 04:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:26 INFO - 'HOME': '/Users/cltbld', 04:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:26 INFO - 'LOGNAME': 'cltbld', 04:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:26 INFO - 'MOZ_NO_REMOTE': '1', 04:01:26 INFO - 'NO_EM_RESTART': '1', 04:01:26 INFO - 'PAGER': '/bin/cat', 04:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:26 INFO - 'PWD': '/builds/slave/test', 04:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:26 INFO - 'SHELL': '/bin/bash', 04:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:26 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:26 INFO - 'USER': 'cltbld', 04:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:26 INFO - 'XPC_FLAGS': '0x0', 04:01:26 INFO - 'XPC_SERVICE_NAME': '0', 04:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:26 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 04:01:26 INFO - Cleaning up... 04:01:26 INFO - Return code: 0 04:01:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:26 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:26 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 04:01:26 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 04:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:26 INFO - 'HOME': '/Users/cltbld', 04:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:26 INFO - 'LOGNAME': 'cltbld', 04:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:26 INFO - 'MOZ_NO_REMOTE': '1', 04:01:26 INFO - 'NO_EM_RESTART': '1', 04:01:26 INFO - 'PAGER': '/bin/cat', 04:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:26 INFO - 'PWD': '/builds/slave/test', 04:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:26 INFO - 'SHELL': '/bin/bash', 04:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:26 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:26 INFO - 'USER': 'cltbld', 04:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:26 INFO - 'XPC_FLAGS': '0x0', 04:01:26 INFO - 'XPC_SERVICE_NAME': '0', 04:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:26 INFO - Downloading/unpacking psutil>=3.1.1 04:01:26 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 04:01:26 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 04:01:26 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 04:01:26 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 04:01:26 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 04:01:26 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 04:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:01:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:29 INFO - Installing collected packages: psutil 04:01:29 INFO - Running setup.py install for psutil 04:01:29 INFO - building 'psutil._psutil_osx' extension 04:01:29 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 04:01:30 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 04:01:30 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 04:01:30 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 04:01:30 INFO - building 'psutil._psutil_posix' extension 04:01:30 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 04:01:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:01:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:01:30 INFO - ^ 04:01:30 INFO - 1 warning generated. 04:01:30 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 04:01:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:30 INFO - Successfully installed psutil 04:01:30 INFO - Cleaning up... 04:01:30 INFO - Return code: 0 04:01:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:30 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:30 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 04:01:30 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 04:01:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:30 INFO - 'HOME': '/Users/cltbld', 04:01:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:30 INFO - 'LOGNAME': 'cltbld', 04:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:30 INFO - 'MOZ_NO_REMOTE': '1', 04:01:30 INFO - 'NO_EM_RESTART': '1', 04:01:30 INFO - 'PAGER': '/bin/cat', 04:01:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:30 INFO - 'PWD': '/builds/slave/test', 04:01:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:30 INFO - 'SHELL': '/bin/bash', 04:01:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:30 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:30 INFO - 'USER': 'cltbld', 04:01:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:30 INFO - 'XPC_FLAGS': '0x0', 04:01:30 INFO - 'XPC_SERVICE_NAME': '0', 04:01:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:01:31 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 04:01:31 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 04:01:31 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 04:01:31 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 04:01:31 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 04:01:31 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 04:01:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:01:33 INFO - Installing collected packages: mozsystemmonitor 04:01:33 INFO - Running setup.py install for mozsystemmonitor 04:01:33 INFO - Successfully installed mozsystemmonitor 04:01:33 INFO - Cleaning up... 04:01:33 INFO - Return code: 0 04:01:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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')]} 04:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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', '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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:01: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 jsonschema==2.5.1 04:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:33 INFO - 'HOME': '/Users/cltbld', 04:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:33 INFO - 'LOGNAME': 'cltbld', 04:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:33 INFO - 'MOZ_NO_REMOTE': '1', 04:01:33 INFO - 'NO_EM_RESTART': '1', 04:01:33 INFO - 'PAGER': '/bin/cat', 04:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:33 INFO - 'PWD': '/builds/slave/test', 04:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:33 INFO - 'SHELL': '/bin/bash', 04:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:33 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:33 INFO - 'USER': 'cltbld', 04:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:33 INFO - 'XPC_FLAGS': '0x0', 04:01:33 INFO - 'XPC_SERVICE_NAME': '0', 04:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:34 INFO - Downloading/unpacking jsonschema==2.5.1 04:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:01:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01:36 INFO - Downloading functools32-3.2.3-2.tar.gz 04:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:01:37 INFO - warning: no files found matching '*.txt' 04:01:37 INFO - no previously-included directories found matching 'build' 04:01:37 INFO - no previously-included directories found matching 'dist' 04:01:37 INFO - no previously-included directories found matching '.git*' 04:01:37 INFO - Installing collected packages: jsonschema, functools32 04:01:37 INFO - Running setup.py install for functools32 04:01:37 INFO - warning: no files found matching '*.txt' 04:01:37 INFO - no previously-included directories found matching 'build' 04:01:37 INFO - no previously-included directories found matching 'dist' 04:01:37 INFO - no previously-included directories found matching '.git*' 04:01:37 INFO - Successfully installed jsonschema functools32 04:01:37 INFO - Cleaning up... 04:01:37 INFO - Return code: 0 04:01:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:01:37 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')]} 04:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:37 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')]} 04:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:37 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:37 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 04:01:37 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 04:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:37 INFO - 'HOME': '/Users/cltbld', 04:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:37 INFO - 'LOGNAME': 'cltbld', 04:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:37 INFO - 'MOZ_NO_REMOTE': '1', 04:01:37 INFO - 'NO_EM_RESTART': '1', 04:01:37 INFO - 'PAGER': '/bin/cat', 04:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:37 INFO - 'PWD': '/builds/slave/test', 04:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:37 INFO - 'SHELL': '/bin/bash', 04:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:37 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:37 INFO - 'USER': 'cltbld', 04:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:37 INFO - 'XPC_FLAGS': '0x0', 04:01:37 INFO - 'XPC_SERVICE_NAME': '0', 04:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:37 INFO - Downloading/unpacking blobuploader==1.2.4 04:01:37 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 04:01:37 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 04:01:37 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 04:01:37 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 04:01:37 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 04:01:37 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 04:01:40 INFO - Downloading blobuploader-1.2.4.tar.gz 04:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:01:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:01:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01:41 INFO - Downloading docopt-0.6.1.tar.gz 04:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:01:41 INFO - Installing collected packages: blobuploader, requests, docopt 04:01:41 INFO - Running setup.py install for blobuploader 04:01:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:01:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:01:41 INFO - Running setup.py install for requests 04:01:41 INFO - Running setup.py install for docopt 04:01:42 INFO - Successfully installed blobuploader requests docopt 04:01:42 INFO - Cleaning up... 04:01:42 INFO - Return code: 0 04:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:42 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:42 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 04:01:42 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 04:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:42 INFO - 'HOME': '/Users/cltbld', 04:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:42 INFO - 'LOGNAME': 'cltbld', 04:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:42 INFO - 'MOZ_NO_REMOTE': '1', 04:01:42 INFO - 'NO_EM_RESTART': '1', 04:01:42 INFO - 'PAGER': '/bin/cat', 04:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:42 INFO - 'PWD': '/builds/slave/test', 04:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:42 INFO - 'SHELL': '/bin/bash', 04:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:42 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:42 INFO - 'USER': 'cltbld', 04:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:42 INFO - 'XPC_FLAGS': '0x0', 04:01:42 INFO - 'XPC_SERVICE_NAME': '0', 04:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-fmnbn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-3ECxHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-G8c3Yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-S6sunO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-0y_oM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-n4eG03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ITvrIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-1Kdcbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-YKh6qR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-GqyVaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:43 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-oSrIdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:44 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-x4UZqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:44 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-aLC6_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:44 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-YOIpQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:44 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-RvVZSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:44 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-bfCDam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:44 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-7MpZkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:01:44 INFO - Running setup.py install for manifestparser 04:01:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:01:44 INFO - Running setup.py install for mozcrash 04:01:44 INFO - Running setup.py install for mozdebug 04:01:45 INFO - Running setup.py install for mozdevice 04:01:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Running setup.py install for mozfile 04:01:45 INFO - Running setup.py install for mozhttpd 04:01:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Running setup.py install for mozinfo 04:01:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Running setup.py install for mozInstall 04:01:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:01:45 INFO - Running setup.py install for mozleak 04:01:45 INFO - Running setup.py install for mozlog 04:01:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:01:46 INFO - Running setup.py install for moznetwork 04:01:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:01:46 INFO - Running setup.py install for mozprocess 04:01:46 INFO - Running setup.py install for mozprofile 04:01:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:01:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:01:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:01:46 INFO - Running setup.py install for mozrunner 04:01:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:01:46 INFO - Running setup.py install for mozscreenshot 04:01:46 INFO - Running setup.py install for moztest 04:01:47 INFO - Running setup.py install for mozversion 04:01:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:01:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:01:47 INFO - Cleaning up... 04:01:47 INFO - Return code: 0 04:01:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:47 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:47 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 04:01:47 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 04:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:47 INFO - 'HOME': '/Users/cltbld', 04:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:47 INFO - 'LOGNAME': 'cltbld', 04:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:47 INFO - 'MOZ_NO_REMOTE': '1', 04:01:47 INFO - 'NO_EM_RESTART': '1', 04:01:47 INFO - 'PAGER': '/bin/cat', 04:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:47 INFO - 'PWD': '/builds/slave/test', 04:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:47 INFO - 'SHELL': '/bin/bash', 04:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:47 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:47 INFO - 'USER': 'cltbld', 04:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:47 INFO - 'XPC_FLAGS': '0x0', 04:01:47 INFO - 'XPC_SERVICE_NAME': '0', 04:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-4Mq5bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:47 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)) 04:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-sdlDlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:47 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)) 04:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ASu6ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:47 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)) 04:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-BfT_Uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:47 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)) 04:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-U24ivn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-b92dWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-npT0E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-4tny9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-TgTGCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-1FL_Ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-gG31A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-L9mBcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-brG4Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:48 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)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-QtZ2yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:48 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-pUGEi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:49 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)) 04:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:49 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-_cZpAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:49 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)) 04:01:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:49 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-px6U_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:49 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)) 04:01:49 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)) 04:01:49 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)) 04:01:49 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)) 04:01:49 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)) 04:01:49 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)) 04:01:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:51 INFO - Downloading blessings-1.6.tar.gz 04:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:01:51 INFO - Installing collected packages: blessings 04:01:51 INFO - Running setup.py install for blessings 04:01:52 INFO - Successfully installed blessings 04:01:52 INFO - Cleaning up... 04:01:52 INFO - Return code: 0 04:01:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:01:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:52 INFO - Reading from file tmpfile_stdout 04:01:52 INFO - Current package versions: 04:01:52 INFO - blessings == 1.6 04:01:52 INFO - blobuploader == 1.2.4 04:01:52 INFO - docopt == 0.6.1 04:01:52 INFO - functools32 == 3.2.3-2 04:01:52 INFO - jsonschema == 2.5.1 04:01:52 INFO - manifestparser == 1.1 04:01:52 INFO - mozInstall == 1.12 04:01:52 INFO - mozcrash == 0.17 04:01:52 INFO - mozdebug == 0.1 04:01:52 INFO - mozdevice == 0.48 04:01:52 INFO - mozfile == 1.2 04:01:52 INFO - mozhttpd == 0.7 04:01:52 INFO - mozinfo == 0.9 04:01:52 INFO - mozleak == 0.1 04:01:52 INFO - mozlog == 3.2 04:01:52 INFO - moznetwork == 0.27 04:01:52 INFO - mozprocess == 0.23 04:01:52 INFO - mozprofile == 0.28 04:01:52 INFO - mozrunner == 6.12 04:01:52 INFO - mozscreenshot == 0.1 04:01:52 INFO - mozsystemmonitor == 0.3 04:01:52 INFO - moztest == 0.7 04:01:52 INFO - mozversion == 1.4 04:01:52 INFO - psutil == 3.1.1 04:01:52 INFO - requests == 1.2.3 04:01:52 INFO - wsgiref == 0.1.2 04:01:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:52 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')]} 04:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 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')]} 04:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:52 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 04:01:52 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 04:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:52 INFO - 'HOME': '/Users/cltbld', 04:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:52 INFO - 'LOGNAME': 'cltbld', 04:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:52 INFO - 'MOZ_NO_REMOTE': '1', 04:01:52 INFO - 'NO_EM_RESTART': '1', 04:01:52 INFO - 'PAGER': '/bin/cat', 04:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:52 INFO - 'PWD': '/builds/slave/test', 04:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:52 INFO - 'SHELL': '/bin/bash', 04:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:52 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:52 INFO - 'USER': 'cltbld', 04:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:52 INFO - 'XPC_FLAGS': '0x0', 04:01:52 INFO - 'XPC_SERVICE_NAME': '0', 04:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 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)) 04:01:52 INFO - Cleaning up... 04:01:52 INFO - Return code: 0 04:01:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:01:52 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')]} 04:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 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')]} 04:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 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 0x10868ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108721618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b62d8fbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, '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.gdn5aVFsPh/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.n4rXjwFz05/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 04:01:52 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 04:01:52 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 04:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:52 INFO - 'HOME': '/Users/cltbld', 04:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:52 INFO - 'LOGNAME': 'cltbld', 04:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:52 INFO - 'MOZ_NO_REMOTE': '1', 04:01:52 INFO - 'NO_EM_RESTART': '1', 04:01:52 INFO - 'PAGER': '/bin/cat', 04:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:52 INFO - 'PWD': '/builds/slave/test', 04:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:52 INFO - 'SHELL': '/bin/bash', 04:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:01:52 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:52 INFO - 'USER': 'cltbld', 04:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:52 INFO - 'XPC_FLAGS': '0x0', 04:01:52 INFO - 'XPC_SERVICE_NAME': '0', 04:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:01:53 INFO - Cleaning up... 04:01:53 INFO - Return code: 0 04:01:53 INFO - Running post-action listener: _resource_record_post_action 04:01:53 INFO - Running post-action listener: _start_resource_monitoring 04:01:53 INFO - Starting resource monitoring. 04:01:53 INFO - ##### 04:01:53 INFO - ##### Finished create-virtualenv step (success) 04:01:53 INFO - ##### 04:01:53 INFO - ##### 04:01:53 INFO - ##### Running install step. 04:01:53 INFO - ##### 04:01:53 INFO - Running pre-action listener: _resource_record_pre_action 04:01:53 INFO - Running main action method: install 04:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:53 INFO - Reading from file tmpfile_stdout 04:01:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:01:53 INFO - Reading from file tmpfile_stdout 04:01:53 INFO - Output received: 04:01:53 INFO - Usage: mozinstall [options] installer 04:01:53 INFO - Options: 04:01:53 INFO - -h, --help show this help message and exit 04:01:53 INFO - -d DEST, --destination=DEST 04:01:53 INFO - Directory to install application into. [default: 04:01:53 INFO - "/builds/slave/test"] 04:01:53 INFO - --app=APP Application being installed. [default: firefox] 04:01:53 INFO - mkdir: /builds/slave/test/build/application 04:01:53 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'] 04:01:53 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 04:02:27 INFO - Reading from file tmpfile_stdout 04:02:27 INFO - Output received: 04:02:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:02:27 INFO - Running post-action listener: _resource_record_post_action 04:02:27 INFO - ##### 04:02:27 INFO - ##### Finished install step (success) 04:02:27 INFO - ##### 04:02:27 INFO - ##### 04:02:27 INFO - ##### Running run-tests step. 04:02:27 INFO - ##### 04:02:27 INFO - Running pre-action listener: _resource_record_pre_action 04:02:27 INFO - Running main action method: run_tests 04:02:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:02:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:02:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:02:27 INFO - 2016-07-13 04:02:27.866 screenresolution[1640:7710] starting screenresolution argv=screenresolution get 04:02:27 INFO - 2016-07-13 04:02:27.868 screenresolution[1640:7710] Display 0: 1600x1200x32@60 04:02:27 INFO - 2016-07-13 04:02:27.874 screenresolution[1641:7712] starting screenresolution argv=screenresolution list 04:02:27 INFO - Available Modes on Display 0 04:02:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:02:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:02:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:02:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:02:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:02:28 INFO - Intel Iris: 04:02:28 INFO - Chipset Model: Intel Iris 04:02:28 INFO - Type: GPU 04:02:28 INFO - Bus: Built-In 04:02:28 INFO - VRAM (Dynamic, Max): 1536 MB 04:02:28 INFO - Vendor: Intel (0x8086) 04:02:28 INFO - Device ID: 0x0a2e 04:02:28 INFO - Revision ID: 0x0009 04:02:28 INFO - Displays: 04:02:28 INFO - Display: 04:02:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:02:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:02:28 INFO - Display Serial Number: 1600x1200 60 04:02:28 INFO - Main Display: Yes 04:02:28 INFO - Mirror: Off 04:02:28 INFO - Online: Yes 04:02:28 INFO - Rotation: Supported 04:02:28 INFO - Return code: 0 04:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:02:28 INFO - Python 2.7.3 04:02:28 INFO - Return code: 0 04:02:28 INFO - grabbing minidump binary from tooltool 04:02: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')]} 04:02:28 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 0x1085f0d30>, '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 0x108355db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1085e8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:02:28 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 04:02:28 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 04:02:28 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 04:02:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:02:28 INFO - Return code: 0 04:02:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:02:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:02:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:02:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:02:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:02:28 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/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 04:02:28 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/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0186 --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 04:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gdn5aVFsPh/Render', 04:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:28 INFO - 'HOME': '/Users/cltbld', 04:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:28 INFO - 'LOGNAME': 'cltbld', 04:02:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:02:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:28 INFO - 'MOZ_NO_REMOTE': '1', 04:02:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:02:28 INFO - 'NO_EM_RESTART': '1', 04:02:28 INFO - 'PAGER': '/bin/cat', 04:02:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:28 INFO - 'PWD': '/builds/slave/test', 04:02:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:28 INFO - 'SHELL': '/bin/bash', 04:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n4rXjwFz05/Listeners', 04:02:28 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 04:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:28 INFO - 'USER': 'cltbld', 04:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:28 INFO - 'XPC_FLAGS': '0x0', 04:02:28 INFO - 'XPC_SERVICE_NAME': '0', 04:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:28 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/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 04:02:28 INFO - mozversion application_buildid: 20160713021310 04:02:28 INFO - mozversion application_changeset: bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6 04:02:28 INFO - mozversion application_display_name: Nightly 04:02:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:02:28 INFO - mozversion application_name: Firefox 04:02:28 INFO - mozversion application_remotingname: firefox 04:02:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:02:28 INFO - mozversion application_vendor: Mozilla 04:02:28 INFO - mozversion application_version: 50.0a1 04:02:28 INFO - mozversion platform_buildid: 20160713021310 04:02:28 INFO - mozversion platform_changeset: bbbf2ec4d6a268d2e1154643dddc2ecc986c39a6 04:02:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:02:28 INFO - mozversion platform_version: 50.0a1 04:02:28 INFO - using testdate: 1468407748 04:02:28 INFO - actual date: 1468407748 04:02:28 INFO - starting webserver on 'localhost:49266' 04:02:28 INFO - SUITE-START | Running 2 tests 04:02:28 INFO - TEST-START | dromaeo_css 04:02:28 INFO - operating with platform_type : mac_ 04:02:28 INFO - Initialising browser for dromaeo_css test... 04:02:28 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmp9IqlYw/profile http://localhost:49266/getInfo.html) 04:02:31 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 04:02:31 INFO - PROCESS | 1651 | colorDepth:24 04:02:31 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 04:02:31 INFO - PROCESS | 1651 | __metrics 04:02:31 INFO - PROCESS | 1651 | 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" 04:02:31 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:02:31 INFO - TEST-INFO | 1651: exit 0 04:02:31 INFO - Browser initialized. 04:02:31 INFO - Running cycle 1/1 for dromaeo_css test... 04:02:31 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmp9IqlYw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:43 INFO - PROCESS | 1653 | RSS: Main: 243396608 04:02:43 INFO - PROCESS | 1653 | 04:03:34 INFO - PROCESS | 1653 | 1468407814772 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] 04:05:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 04:05:48 INFO - PROCESS | 1653 | RSS: Main: 366374912 04:05:48 INFO - PROCESS | 1653 | 04:08:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 04:08:52 INFO - PROCESS | 1653 | RSS: Main: 383188992 04:08:52 INFO - PROCESS | 1653 | 04:11:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 04:11:54 INFO - PROCESS | 1653 | RSS: Main: 372883456 04:11:54 INFO - PROCESS | 1653 | 04:14:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 04:14:57 INFO - PROCESS | 1653 | RSS: Main: 371830784 04:14:57 INFO - PROCESS | 1653 | 04:18:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 04:18:01 INFO - PROCESS | 1653 | RSS: Main: 338989056 04:18:01 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 04:21:04 INFO - PROCESS | 1653 | RSS: Main: 412008448 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | __start_tp_report 04:21:04 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 04:21:04 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 04:21:04 INFO - PROCESS | 1653 | |i|pagename|runs| 04:21:04 INFO - PROCESS | 1653 | |0;dojo.html;3347.832608369581;3355.412802759518;3363.9908027591723;3390.6100798408193;3418.8632454701815;5545.889082218353;5570.415106413824;5618.690971996539;5651.604387692858;5652.604317697763;5627.324721033471;5633.746481408347;5741;5743.167240750089;5764.942350576493;5037.471065538102;5069.138246498086;5106.69359838409;5113.616478764089;5224.660397074197;5181.6113791465605;5209.166533354664;5225.660332078422;5241.318628578299;5271.86820329491;4093.8771836844944;5041;5087.847364579068;5088.440271570124;5123.718195499246;5084.9745751271375;5085.847424577269;5089.720065396402;5099.898002039939;5142.4600416956055;5094.414142373637;5130.384353877558;5147.613928955306;5165.380154381461;5213.843584692465;5529.253550770634;5593.636413633101;5662.49037586615;5662.546996240291;5743.655380677173;6130.574441619199;6151.938480615181;6163.106349579286;6179.351168127327;6184.257889053298;6163.352847950945;6220.160278362454;6225.782097626607;6245.87508249837;6260.40526150015;5576.219329293902;5630.859228519238;5658.773649054074;5712.314522257356;5763.279590051244;5395.865103372447;6209.534284928648;6334.493240540838;6346.714397852108;6367.586106903036;6096.085587161872;6097.354222971313;6107.389261073946;6109.114178444101;6123.96938015316;6009.487088680874;6039.305479869871;6057.770322400836;6108.706347747029;6235.719392627343;6034.125051867454;6080.391960803972;6109.541784366191;6124.969375153096;6130.2030736004035;6118.663473508911;6136.7852125175405;6139.201903752573;6156.476699480505;6167.722452489649;4812.5668689818085;4830.830920917751;4847.224444088929;4847.418309802846;4853.6602437829015;4384.05742765307;4454.7549884756645;4474.6420286377015;4478.104379124123;4479.820807167677;5680.204771332017;5694.088945768657;5694.629849059798;5697.487226149667;5697.743601537941;6121.544430002081;6139.201903752483;6152.692365381802;6167.383261673796;6195.535334849904;5715.028445164332;5726.885462290814;5731.598788084794;5747.626404283708;5761.222234998221;5273.1035723927025;5384.273123128406;5390.00284947286;5397.4062853086125;5399.4600539945695;6242.781502647385;6251.249850018027;6307.369263073656;6324.399182077604;6326.905096423466;6141.631502109888;6245.750169993331;6250.3749625037135;6260.311365749855;6279.120923070683;6251.78118765841;6322.241331040304;6337.493000559852;6350.110984462087;6365.013422919455;5968;5992.161097446274;6067.969660151671;6081;6092.329843717276;6203.503719702503;6209.627422354673;6221.688915554295;6244.344343843831;6252.531060170414;5165.870853228699;5693.117566777156;5845.386234445322;5949.732262048305;6011.72947217385;5811.331696854747;5859.56053296013;5952.702364881825;5984.611000284967;6008.369121242206;5775.104858746901;5784.884302313846;5834.649921004753;5862.208601838868;5888.499477544457;6002.3097343804275;6033;6033.276006879202;6035;6053.061775424816;5689.601727879009;5736.913946290725;5757.971210143923;5833.212516310414;5846.181534585076 04:21:04 INFO - PROCESS | 1653 | |1;ext.html;465.250945976977;476.325998711823;534.0680512505677;534.4228233507812;542.9972850135749;29860.850695746518;30118.849405752968;42019.57980420195;42570.93572660685;42641.360379594335;43937.78031109848;44166.77916610413;44295.335569966395;44392.55607443924;44411.777941110246;9684.854727179098;9870.259730520203;9870.654527091554;9877.456739879304;9880.505974701273;8685.95657021714;8885.733427997171;11741.354225517593;11901.10741694372;11928.105392095584;11810.350430726307;11984.101192410604;11993.640190794247;12016.879831201708;12032.278063316186;8756.737297881074;11917.404129793518;12000.279983201035;12009.099317551132;12025.639230823046;11638;12035.277883327028;12042.397880106048;12051.819222711667;12089.21420107699;39862;40089.39865901985;40121;40177.196456071004;40226.59773402287;3506.982465087658;3559.270349578356;3601.099725068733;3674.7427680062406;3855.4409610606303;27331.180064598095;30693.77224911001;31163;31174.532382014288;31932.521012184836;3248.96751032489;3337.9666203337897;3357.7817441866446;3398.7111095556907;3418.7265018798435;435303;437139;437960;438270;438415;57465;57675.711621441624;57754.42245577575;57768.8446231073;57898.131528027116;61629.691851540454;61796.38203617996;61846;61857.072143917874;61980.69009654923;16419.261133249034;16657.167141643113;16657.91723537966;16666.250018749186;16674.66650667004;11933.105017123751;12153.756924861453;12160.209586377034;12419.31693756834;13003.934980325226;15589.142597157039;15646.765298520531;16135.757963630555;16262.918685406497;16289;6694.364035416627;6885.277045910208;7046;7074.71701131949;7144.642767861586;812.2892469089546;844.7634662294568;906.9410488318238;961.927855410847;1080.2006515178869;29745.553816692765;29942;30183.09450716482;30687.693123068482;30963.535546966814;1069.850220969065;1088.9455527223606;1097.204526718123;1101.2126329674322;1135.6990397544657;15526.767098493532;15610.531684049496;15657.669098126558;15678.66731327827;16049.759253611206;194401;194612;195117;195407;195643;60154.398456015755;60231.69884150551;60235.397646023855;60312.69843650753;60365.396346036854;1300.5773123734823;1345.132389608697;1419.1839692176834;1445.5880074178808;1473.4032716749734;29883;30111.548326775555;31459.528107077953;32212.35575288528;32237.51643725393;49868.750656246484;49974.750126249135;50458;50586.988260235325;50764.23853642272;474458;475502.62248688535;475610;476262;477459;34441.966740997814;35595.64404355923;35752.82123589365;39329;40481.39277910892;40551.1889762209;42188.78905605452;48741.75629121832;49356.2596561059;49588.50411495983;38463.615363846;39442.802785987034;39858.82541142622;39996.60003399929;40325.39511907382;39600;39642.80178599089;40186.598134018284;40570.59429405668;40575.797121015385;38884.61115388923;38977.80511097427;39189.60810391974;39191;39350;33648.663513364554;33865;34712.65287347094;35553.644463555036;35583.288334233686;24781.876090620153;25472.108476203244;25946.740532595188;26169.398253379164;26480.43910778681 04:21:04 INFO - PROCESS | 1653 | |2;jquery.html;359.1739000299311;381.39358420112023;390.6581740976645;392.7918203352221;412.3340804600569;15437;15700.921495392522;20657.896710516463;21035.263765768203;21054.684179737298;20573.794262057374;21113.894430527827;21121.57756844862;21163.259285924996;21419.678704819442;5489.39616642169;6002.87994240116;6133.233345831771;6169.629822210659;6183.9381606183815;6042.456178943894;6086.330503644595;6157.661328626924;6160;6160.1683772690785;5809.912851307234;5985.042393217086;5985.5810093293485;6013.548983826208;6050.455459008688;5748.2527271454865;5935.347111817697;6020.759169633209;6023.69881505923;6028.397160283958;5182.792688292464;5215.113430716788;5215.8174463893965;5225.059489291926;5226.189940559219;26374.47251054968;26382.73617263822;26382.73617263822;26655.866720666465;26713.33216669599;2338.298510446859;2418.286605451396;2577.80666450017;2863.840144741383;2962.888916656254;15866.52400427989;15915;15975.84024159755;16152.676946461122;16157.757633635505;1861.4973957031525;1878.633666435032;1894.2517705506248;1927.0461121744686;1979.604079184169;109738;111275.44362278217;111846;112035;112293;8601.956990215009;9649.324547281623;10083.647072352433;10190.388576685422;10219.335743176818;12393.318367489876;12427.875721242852;12511.12422130442;12535.811962820566;12546;8062.919370806334;8261.917380826235;8267.173282671805;8308.252257296872;8323.334133269445;7207.639618019078;8358.582070896553;8674.91325086741;8678.349123815738;8800.383973121949;3913.1391093959282;3921.92156156881;3954.5056867891512;3967.3255546557157;3997.1605962747567;1664.3509031477734;1804.5398423402212;1809.2762894841906;1924.5765931495048;1995.122146255643;708;824.4805772363372;890.9821803564022;906.2297047509696;1003.1322905686528;21035.36893893155;21380.358589242347;21903.795291259135;21931.342059738232;22037.449063773212;869.6173683579204;878.8462019146624;958.2046901072093;963.6145541783343;1006.6124542051363;11071.72320691989;11565.710857228636;11568.710782230512;11580.76838463226;11619.9542041215;584325;605968;613257;621689;622314;144681;151096;152701;154048.22975885274;155937;982.6511588386163;1021.5249908792447;1038.1590911361973;1045.6706137566641;1046.1892033673964;19817.900910495355;20141.194352225746;20195.19219231273;20211.696824547937;20255.59488810186;60263;60276.49308767316;60554.697226513585;60605.39394605997;60747;83038.75441868255;84016.57991710247;84487;85576;88211;5513.972430137824;7021.789346319619;7622.16156222804;7684.538927664358;7712.9614351927885;12379.62861114168;13360.665983350495;13651.726965460437;13684.247366394944;13836.930815345859;11320.6037788677;11365.261258018203;11577.768444631229;11705.941470292593;11838.408079595814;11394.829077563678;11435.885641143483;11599;11768.529258829893;11896.762064758483;9880.35777674449;11127;11220.66338009861;11855.110866685205;12065.215760975509;7575.621218939141;7610.124835643972;7663;7674.15584285717;7791.92208077912;9924.965382096509;11767.52929882795;11909.940450297692;11992.160548761503;11995.940020300191 04:21:04 INFO - PROCESS | 1653 | |3;mootools.html;1333.8266025416694;1408.2043645340382;1691.2727527163322;1913.502489352768;1939.1177014458424;8401.873971890418;8653.870191947126;8826.514541700204;8827.691030813923;8840.867386989185;7788.844223115534;8007.839843203133;8436.409451338393;8602.311815054796;8670.176333248332;2324.3491822289743;2350.6239001759636;2350.882455877208;2351.2946116165167;2359.0681680736097;2327.8254130940163;2339.9532009359805;2363.149266264152;2369.7037870266217;2372.264597974625;1837.8070302618166;2085.2910010596393;2278.9658155127686;2345.765423457649;2348.3542025942834;2323.512062466884;2329.126577533425;2350.894209760565;2353.6940197774265;2354.6703461515403;2313.548857972696;2335.9065637374483;2343.484433424644;2347.5891718949115;2349.2717257650183;8766.474011559327;8854.557272136368;8855.33584981129;8855.822883542292;8872.645094196288;5182.378114626231;5330.307060082165;5332.68003919766;5333.413324534298;5333.959877823826;3547.308274886398;3552.1652411683235;3604.7116230701745;3635.3456377851976;3643.6538528839983;938.5776400619749;1075.7687097274124;1079.228351728518;1091.110744743032;1091.6288461922984;11575.826362604568;11586.246893951866;11589;11597.536098556047;11625.302481851117;4446.199684056836;4510.706804057726;4684.367610372624;4717.150912835723;4723.291506274018;8358.20597043292;9810.31327807061;9821.705348839547;9840.852387214198;9852.655157069612;5286.471352864683;5287.20691896211;5290.894182116336;5291.232771248147;5292.576593872558;4837.153498137812;4845.563899249085;4854.417469903634;4865.95134048662;4874.2444921037295;3882.2429626222615;3882.417637354363;3934.881953541398;3966.960330396659;3972.364421692573;1151.9020883225019;1195.9820602690966;1228.975420491585;1242.4719494214924;1253.354522420954;759.2824780582297;896.7937374403793;928.4058202750246;940.3229674634252;951.9619215231452;5809.593328466966;5820.970895145582;5903.822885313448;5956.493698035629;6063.363346848518;891.505214605887;949.8100379924043;952.7713348796239;972.4116909269932;984.5372674842812;3385.542951701538;3907;3957.821898014596;4009.7594144351433;4037.818298176589;9248.907510924939;9984.400935943866;10619.522121504397;10732.570697172026;10779.245452818228;10071.34536255141;10505.474726263656;10649.467526623637;10666;10673.35959842412;7962.442629015914;8191.0580283267;8193.262606365337;8199.262066414052;8207.507549547046;5916.467517923407;5986.2517185351835;6007.669578173242;6086.939130608815;6262.561620686508;9485.193758530602;9923.305368624127;9935.602575897172;9935.850962235716;9948.552315145691;9940.950295248478;10086.79826403453;10108.797824043331;10113.443760593238;10118.392896426238;7414.406847452299;7420.666070026754;7571.697132114874;7584.962075189589;7614.733484328022;8117.431779775595;8284.751457456287;8288.792780180544;8289.378296627656;8289.4197406181;7044.577325360495;7308.707651693872;7359.8160045999275;7420.740274090597;7550.565392575393;7291.708331666674;7297.270272972661;7370.77887663371;7429.368503677032;7430.4798664093;6639.3692599203;6777.728890844311;6989.33601307875;7004.949257611399;7076.646167691491;4269.42362781033;4812.181929071996;4889.022195560973;4927.211646136601;5045;4951.851444456672;5002.899942001212;5018;5072.746362681925;5099.3370861787735 04:21:04 INFO - PROCESS | 1653 | |4;prototype.html;240.43977532349624;303.411381919077;308.7051865468477;309.26857980875235;315.42277631933564;4473.150101480721;5277.287566178565;5357.8212793185485;5391.676499410029;5400.459954004597;4422.867313980578;4755.358026666399;5287.127623942045;5332.493413125747;5358.5169639734095;3452.033430639419;3854;3863.74885632433;4017.955331613772;4047.51429828421;3973.960260397388;3980.5621381647998;4008.5390180129366;4052.1895620875944;4065.9186816263805;4012.2978478766245;4021.457103291062;4022.798860057;4034.374671925856;4054.2296963576828;3071.616047994001;3478.3651683708617;3782.3191825471404;3894.55212650546;4034.9193016139807;2860.842653654069;3468.6704763047724;3509.3332266869215;3511.315293517765;3523.3834079036064;5163.7676304566385;5167.689938603658;5182.067227898977;5191.299174611455;5217.739113044333;3950.980245098785;4224.746515209066;4531;4620.036484494077;4623.86128416148;5411.594130440233;5442.857000029999;5454.427285135083;5485.6982865942355;5487.780488780444;3749.493818334517;3758.6241375862196;3773.9811300943593;3800.277947190025;3801.3157631626295;4843.813265749878;4852.369192005052;5162.535371816554;5376.892462150774;5753.395893431213;3796.8291426885858;3796.962030379716;3807.1053302473897;3828.674562662149;3870.825812838429;3496.4056110461283;3507.438809790421;3907.648311651908;3918.6104020023045;3920.6471417571984;3795.7153213509096;4285.357196420562;4534.319852022157;4564.269716845289;4579.816807327737;5327.413984461706;5543.7782488700805;5588.21764952907;5614.382417934024;5639.2518319320325;1066.9626563070256;1123.853898993142;1172.6833754886134;1308.9869101309055;1330.5210124355224;697.0306494334172;751.8571770908299;785.3206975965862;819.2053707903324;822.6421506644629;4085.4484644572603;4959.603231741452;5112.233165025202;5201.7919283229;5262.368515778131;4712.882177945578;5321.920171197435;5635.7601327707835;5669;5748.281464816898;4680.976595117071;4853.587445067209;4859.684120532154;5223;5242.9213561796605;2951.704829517031;2955.2611847038243;2955.27595739041;2977.464056469856;2980.210244285266;5316.893662126813;5396.78412863481;5819.73811178498;5907.021147915497;5922.170896074553;4924.3352147460355;5441.863953401117;5739.236433760053;5779.826605201851;5939.346671866091;5970.432808883149;5999.70001499932;6025.849353766191;6026.668533230714;6038.969805150946;5103.387593488805;5504.779808807602;5801;5822.592418530662;5848.853778655568;5580.441955804387;5597.885554117639;5612.158176273429;5666.603337766482;5738.626989245686;5483.150111732688;5620.960122377373;5830.53355731549;5924.970375148269;5975.462208401264;3599.892003239907;3797.2025874566652;3829.4830197922925;3832.6550610445192;3837.4052021936645;4812.422509298906;4963.245398803699;5621.634593751393;5672.376038635829;5750;4858.92711609319;5373.543248823894;5455.618106732491;5536.806211782729;5616.354119276336;4448.910017045844;5229.241759944863;5497.587680923867;5510.972445137749;5599.80400685974;4734.928976065431;5400.432954539717;5443.972780136074;5447.1829225616475;5457.290552228185;4619.907601847877;4843.958548912001;4914.65597408178;5396;5578.525825304732;4328.545502722169;4470.932936005897;5428.891422171613;5452.745868450199;5458.781648734006 04:21:04 INFO - PROCESS | 1653 | |5;yui.html;706.9823254418638;715.609992554058;717.6232477949076;730.200430528571;764.2357642357645;15375.231238438075;16133.757993630075;16163.35346586138;16190.352385904564;16191.595210119767;14780.187089710062;14917.92541037296;14946;15888.76166857498;16506;896.2158111652304;922.9676961306355;923.8891333040044;926.6339795780663;927.8840144981878;894.6197865906963;907.7458311672742;908.609298001859;910.2035718746097;910.9726708198732;962.7593101724569;965.2037069417723;965.2856885904415;965.4786415335745;966.4249771386014;959.2757468111566;959.9856002159974;960.8510680844445;961.8268711631904;962.6101428921298;715.6529083394549;716.3660160757714;716.5127713155052;720.0711082703307;720.8306048078697;15793.763093553605;15802.209889505479;15822.920885395615;15866.206689665585;15868.682626347409;12568.245905245714;14095;14214.218217998006;14327.212003339811;14332.785008224884;11844.815518448173;11918.880811191864;11952.701182470508;12135.696607584881;12475.750484990249;3802.372608519591;3821.617838216184;3855.9614403855885;3930.371140617516;3934.075492259317;111258.44370778094;117455.82544174534;118578;118658.62682746383;119213.21180182304;10828.296160749684;10934.890651093387;11054.889451105546;11130.49912753928;11482.966533011902;22014.559708805733;22504.549909002057;22517.099316027503;22528.549429011655;22578.0968761251;7987.960060199662;8148.796280093045;8156.877646835303;8168.305694015957;8225.917740822635;4527.139843429738;4554.157480866051;4584.679072464962;4758.714477131383;4798.23228283473;3504.7196224301993;3648.744587878823;3649.6897763690386;3651.561812582507;3652.4886515887797;679.7688785812848;687.7627218609572;699.2203692882501;701.3722718167314;710.1868360727042;539.4632340820908;596.7702434562725;668.6088638146739;680.4714624176116;709.6487238816716;8867.68963086289;9124.543772811332;9276.768080798034;9317.953410232905;9431.434113953048;533.8692020455013;603.4900509069829;617.6843323720447;622.6886556721639;680.9216940051858;4130.648894843912;4555.385023021916;4559.566841150086;4575.862724118282;4575.908481830411;81515.18484815194;88256;127102.72897271095;132289.33855330662;135186;110837;114249;116217;116680;117540;98722;100775.99999999853;101250;101869;105622.47188764007;8823.161799629024;8858.512781797062;8908.55457227149;8934.106589341274;8989.41568798026;56575.86848263094;57508.71245643745;57671.42328576828;58079;58898;69233.61532769418;83343;84114.57942710248;85313;87154.12845871461;12017.579384721494;12038;12097.69755756078;12119.855997772243;12547.184433011826;12447.253164810141;12644.494220231089;12653.936730316289;12806.67983300425;13124.081314307907;12011.399430028638;12028.27830330183;12088.581674341298;12117.394130293625;12323.075769317158;11919.82120268214;12126.33305168189;12281.508739650313;12357.32034738062;12463.813042804546;11853.407329633656;11998;12019.579314724293;12210.755784884075;12400.81398779037;10035.69892903214;10239.590416383262;10273.280870339004;10502.737431564272;10566.788664226826;9488.905110948803;9773.364731292444;10313.381197128196;10335.896641033494;10349.84475232857 04:21:04 INFO - PROCESS | 1653 | __end_tp_report 04:21:04 INFO - PROCESS | 1653 | __start_cc_report 04:21:04 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1301 04:21:04 INFO - PROCESS | 1653 | __end_cc_report 04:21:04 INFO - PROCESS | 1653 | __startTimestamp1468408864769__endTimestamp 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | ------- Summary: start ------- 04:21:04 INFO - PROCESS | 1653 | Number of tests: 6 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5668.85 Median:5784.88 stddev:636.37 (11.0%) stddev-sans-first:611.72 04:21:04 INFO - PROCESS | 1653 | Values: 3347.8 3355.4 3364.0 3390.6 3418.9 5545.9 5570.4 5618.7 5651.6 5652.6 5627.3 5633.7 5741.0 5743.2 5764.9 5037.5 5069.1 5106.7 5113.6 5224.7 5181.6 5209.2 5225.7 5241.3 5271.9 4093.9 5041.0 5087.8 5088.4 5123.7 5085.0 5085.8 5089.7 5099.9 5142.5 5094.4 5130.4 5147.6 5165.4 5213.8 5529.3 5593.6 5662.5 5662.5 5743.7 6130.6 6151.9 6163.1 6179.4 6184.3 6163.4 6220.2 6225.8 6245.9 6260.4 5576.2 5630.9 5658.8 5712.3 5763.3 5395.9 6209.5 6334.5 6346.7 6367.6 6096.1 6097.4 6107.4 6109.1 6124.0 6009.5 6039.3 6057.8 6108.7 6235.7 6034.1 6080.4 6109.5 6125.0 6130.2 6118.7 6136.8 6139.2 6156.5 6167.7 4812.6 4830.8 4847.2 4847.4 4853.7 4384.1 4454.8 4474.6 4478.1 4479.8 5680.2 5694.1 5694.6 5697.5 5697.7 6121.5 6139.2 6152.7 6167.4 6195.5 5715.0 5726.9 5731.6 5747.6 5761.2 5273.1 5384.3 5390.0 5397.4 5399.5 6242.8 6251.2 6307.4 6324.4 6326.9 6141.6 6245.8 6250.4 6260.3 6279.1 6251.8 6322.2 6337.5 6350.1 6365.0 5968.0 5992.2 6068.0 6081.0 6092.3 6203.5 6209.6 6221.7 6244.3 6252.5 5165.9 5693.1 5845.4 5949.7 6011.7 5811.3 5859.6 5952.7 5984.6 6008.4 5775.1 5784.9 5834.6 5862.2 5888.5 6002.3 6033.0 6033.3 6035.0 6053.1 5689.6 5736.9 5758.0 5833.2 5846.2 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:54136.97 Median:3637.92 stddev:103457.96 (2843.9%) stddev-sans-first:103669.71 04:21:04 INFO - PROCESS | 1653 | Values: 465.3 476.3 534.1 534.4 543.0 29860.9 30118.8 42019.6 42570.9 42641.4 43937.8 44166.8 44295.3 44392.6 44411.8 9684.9 9870.3 9870.7 9877.5 9880.5 8686.0 8885.7 11741.4 11901.1 11928.1 11810.4 11984.1 11993.6 12016.9 12032.3 8756.7 11917.4 12000.3 12009.1 12025.6 11638.0 12035.3 12042.4 12051.8 12089.2 39862.0 40089.4 40121.0 40177.2 40226.6 3507.0 3559.3 3601.1 3674.7 3855.4 27331.2 30693.8 31163.0 31174.5 31932.5 3249.0 3338.0 3357.8 3398.7 3418.7 435303.0 437139.0 437960.0 438270.0 438415.0 57465.0 57675.7 57754.4 57768.8 57898.1 61629.7 61796.4 61846.0 61857.1 61980.7 16419.3 16657.2 16657.9 16666.3 16674.7 11933.1 12153.8 12160.2 12419.3 13003.9 15589.1 15646.8 16135.8 16262.9 16289.0 6694.4 6885.3 7046.0 7074.7 7144.6 812.3 844.8 906.9 961.9 1080.2 29745.6 29942.0 30183.1 30687.7 30963.5 1069.9 1088.9 1097.2 1101.2 1135.7 15526.8 15610.5 15657.7 15678.7 16049.8 194401.0 194612.0 195117.0 195407.0 195643.0 60154.4 60231.7 60235.4 60312.7 60365.4 1300.6 1345.1 1419.2 1445.6 1473.4 29883.0 30111.5 31459.5 32212.4 32237.5 49868.8 49974.8 50458.0 50587.0 50764.2 474458.0 475502.6 475610.0 476262.0 477459.0 34442.0 35595.6 35752.8 39329.0 40481.4 40551.2 42188.8 48741.8 49356.3 49588.5 38463.6 39442.8 39858.8 39996.6 40325.4 39600.0 39642.8 40186.6 40570.6 40575.8 38884.6 38977.8 39189.6 39191.0 39350.0 33648.7 33865.0 34712.7 35553.6 35583.3 24781.9 25472.1 25946.7 26169.4 26480.4 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:36375.92 Median:26378.60 stddev:102290.19 (387.8%) stddev-sans-first:102541.40 04:21:04 INFO - PROCESS | 1653 | Values: 359.2 381.4 390.7 392.8 412.3 15437.0 15700.9 20657.9 21035.3 21054.7 20573.8 21113.9 21121.6 21163.3 21419.7 5489.4 6002.9 6133.2 6169.6 6183.9 6042.5 6086.3 6157.7 6160.0 6160.2 5809.9 5985.0 5985.6 6013.5 6050.5 5748.3 5935.3 6020.8 6023.7 6028.4 5182.8 5215.1 5215.8 5225.1 5226.2 26374.5 26382.7 26382.7 26655.9 26713.3 2338.3 2418.3 2577.8 2863.8 2962.9 15866.5 15915.0 15975.8 16152.7 16157.8 1861.5 1878.6 1894.3 1927.0 1979.6 109738.0 111275.4 111846.0 112035.0 112293.0 8602.0 9649.3 10083.6 10190.4 10219.3 12393.3 12427.9 12511.1 12535.8 12546.0 8062.9 8261.9 8267.2 8308.3 8323.3 7207.6 8358.6 8674.9 8678.3 8800.4 3913.1 3921.9 3954.5 3967.3 3997.2 1664.4 1804.5 1809.3 1924.6 1995.1 708.0 824.5 891.0 906.2 1003.1 21035.4 21380.4 21903.8 21931.3 22037.4 869.6 878.8 958.2 963.6 1006.6 11071.7 11565.7 11568.7 11580.8 11620.0 584325.0 605968.0 613257.0 621689.0 622314.0 144681.0 151096.0 152701.0 154048.2 155937.0 982.7 1021.5 1038.2 1045.7 1046.2 19817.9 20141.2 20195.2 20211.7 20255.6 60263.0 60276.5 60554.7 60605.4 60747.0 83038.8 84016.6 84487.0 85576.0 88211.0 5514.0 7021.8 7622.2 7684.5 7713.0 12379.6 13360.7 13651.7 13684.2 13836.9 11320.6 11365.3 11577.8 11705.9 11838.4 11394.8 11435.9 11599.0 11768.5 11896.8 9880.4 11127.0 11220.7 11855.1 12065.2 7575.6 7610.1 7663.0 7674.2 7791.9 9925.0 11767.5 11909.9 11992.2 11995.9 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5660.44 Median:5140.86 stddev:3150.02 (61.3%) stddev-sans-first:3142.07 04:21:04 INFO - PROCESS | 1653 | Values: 1333.8 1408.2 1691.3 1913.5 1939.1 8401.9 8653.9 8826.5 8827.7 8840.9 7788.8 8007.8 8436.4 8602.3 8670.2 2324.3 2350.6 2350.9 2351.3 2359.1 2327.8 2340.0 2363.1 2369.7 2372.3 1837.8 2085.3 2279.0 2345.8 2348.4 2323.5 2329.1 2350.9 2353.7 2354.7 2313.5 2335.9 2343.5 2347.6 2349.3 8766.5 8854.6 8855.3 8855.8 8872.6 5182.4 5330.3 5332.7 5333.4 5334.0 3547.3 3552.2 3604.7 3635.3 3643.7 938.6 1075.8 1079.2 1091.1 1091.6 11575.8 11586.2 11589.0 11597.5 11625.3 4446.2 4510.7 4684.4 4717.2 4723.3 8358.2 9810.3 9821.7 9840.9 9852.7 5286.5 5287.2 5290.9 5291.2 5292.6 4837.2 4845.6 4854.4 4866.0 4874.2 3882.2 3882.4 3934.9 3967.0 3972.4 1151.9 1196.0 1229.0 1242.5 1253.4 759.3 896.8 928.4 940.3 952.0 5809.6 5821.0 5903.8 5956.5 6063.4 891.5 949.8 952.8 972.4 984.5 3385.5 3907.0 3957.8 4009.8 4037.8 9248.9 9984.4 10619.5 10732.6 10779.2 10071.3 10505.5 10649.5 10666.0 10673.4 7962.4 8191.1 8193.3 8199.3 8207.5 5916.5 5986.3 6007.7 6086.9 6262.6 9485.2 9923.3 9935.6 9935.9 9948.6 9941.0 10086.8 10108.8 10113.4 10118.4 7414.4 7420.7 7571.7 7585.0 7614.7 8117.4 8284.8 8288.8 8289.4 8289.4 7044.6 7308.7 7359.8 7420.7 7550.6 7291.7 7297.3 7370.8 7429.4 7430.5 6639.4 6777.7 6989.3 7004.9 7076.6 4269.4 4812.2 4889.0 4927.2 5045.0 4951.9 5002.9 5018.0 5072.7 5099.3 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4460.74 Median:4961.42 stddev:1391.76 (28.1%) stddev-sans-first:1359.14 04:21:04 INFO - PROCESS | 1653 | Values: 240.4 303.4 308.7 309.3 315.4 4473.2 5277.3 5357.8 5391.7 5400.5 4422.9 4755.4 5287.1 5332.5 5358.5 3452.0 3854.0 3863.7 4018.0 4047.5 3974.0 3980.6 4008.5 4052.2 4065.9 4012.3 4021.5 4022.8 4034.4 4054.2 3071.6 3478.4 3782.3 3894.6 4034.9 2860.8 3468.7 3509.3 3511.3 3523.4 5163.8 5167.7 5182.1 5191.3 5217.7 3951.0 4224.7 4531.0 4620.0 4623.9 5411.6 5442.9 5454.4 5485.7 5487.8 3749.5 3758.6 3774.0 3800.3 3801.3 4843.8 4852.4 5162.5 5376.9 5753.4 3796.8 3797.0 3807.1 3828.7 3870.8 3496.4 3507.4 3907.6 3918.6 3920.6 3795.7 4285.4 4534.3 4564.3 4579.8 5327.4 5543.8 5588.2 5614.4 5639.3 1067.0 1123.9 1172.7 1309.0 1330.5 697.0 751.9 785.3 819.2 822.6 4085.4 4959.6 5112.2 5201.8 5262.4 4712.9 5321.9 5635.8 5669.0 5748.3 4681.0 4853.6 4859.7 5223.0 5242.9 2951.7 2955.3 2955.3 2977.5 2980.2 5316.9 5396.8 5819.7 5907.0 5922.2 4924.3 5441.9 5739.2 5779.8 5939.3 5970.4 5999.7 6025.8 6026.7 6039.0 5103.4 5504.8 5801.0 5822.6 5848.9 5580.4 5597.9 5612.2 5666.6 5738.6 5483.2 5621.0 5830.5 5925.0 5975.5 3599.9 3797.2 3829.5 3832.7 3837.4 4812.4 4963.2 5621.6 5672.4 5750.0 4858.9 5373.5 5455.6 5536.8 5616.4 4448.9 5229.2 5497.6 5511.0 5599.8 4734.9 5400.4 5444.0 5447.2 5457.3 4619.9 4844.0 4914.7 5396.0 5578.5 4328.5 4470.9 5428.9 5452.7 5458.8 04:21:04 INFO - PROCESS | 1653 | 04:21:04 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:22907.99 Median:3812.00 stddev:35346.86 (927.3%) stddev-sans-first:35406.71 04:21:04 INFO - PROCESS | 1653 | Values: 707.0 715.6 717.6 730.2 764.2 15375.2 16133.8 16163.4 16190.4 16191.6 14780.2 14917.9 14946.0 15888.8 16506.0 896.2 923.0 923.9 926.6 927.9 894.6 907.7 908.6 910.2 911.0 962.8 965.2 965.3 965.5 966.4 959.3 960.0 960.9 961.8 962.6 715.7 716.4 716.5 720.1 720.8 15793.8 15802.2 15822.9 15866.2 15868.7 12568.2 14095.0 14214.2 14327.2 14332.8 11844.8 11918.9 11952.7 12135.7 12475.8 3802.4 3821.6 3856.0 3930.4 3934.1 111258.4 117455.8 118578.0 118658.6 119213.2 10828.3 10934.9 11054.9 11130.5 11483.0 22014.6 22504.5 22517.1 22528.5 22578.1 7988.0 8148.8 8156.9 8168.3 8225.9 4527.1 4554.2 4584.7 4758.7 4798.2 3504.7 3648.7 3649.7 3651.6 3652.5 679.8 687.8 699.2 701.4 710.2 539.5 596.8 668.6 680.5 709.6 8867.7 9124.5 9276.8 9318.0 9431.4 533.9 603.5 617.7 622.7 680.9 4130.6 4555.4 4559.6 4575.9 4575.9 81515.2 88256.0 127102.7 132289.3 135186.0 110837.0 114249.0 116217.0 116680.0 117540.0 98722.0 100776.0 101250.0 101869.0 105622.5 8823.2 8858.5 8908.6 8934.1 8989.4 56575.9 57508.7 57671.4 58079.0 58898.0 69233.6 83343.0 84114.6 85313.0 87154.1 12017.6 12038.0 12097.7 12119.9 12547.2 12447.3 12644.5 12653.9 12806.7 13124.1 12011.4 12028.3 12088.6 12117.4 12323.1 11919.8 12126.3 12281.5 12357.3 12463.8 11853.4 11998.0 12019.6 12210.8 12400.8 10035.7 10239.6 10273.3 10502.7 10566.8 9488.9 9773.4 10313.4 10335.9 10349.8 04:21:04 INFO - PROCESS | 1653 | -------- Summary: end -------- 04:21:04 INFO - PROCESS | 1653 | 04:21:05 INFO - TEST-INFO | 1653: exit 0 04:21:05 INFO - TEST-OK | dromaeo_css | took 1116931ms 04:21:05 INFO - TEST-START | kraken 04:21:05 INFO - operating with platform_type : mac_ 04:21:05 INFO - Initialising browser for kraken test... 04:21:05 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmp0ieWKQ/profile http://localhost:49266/getInfo.html) 04:21:07 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:21:07 INFO - PROCESS | 1660 | colorDepth:24 04:21:07 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:21:07 INFO - PROCESS | 1660 | __metrics 04:21:07 INFO - PROCESS | 1660 | 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" 04:21:07 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:21:08 INFO - TEST-INFO | 1660: exit 0 04:21:08 INFO - Browser initialized. 04:21:08 INFO - Running cycle 1/1 for kraken test... 04:21:08 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmp0ieWKQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:21:20 INFO - PROCESS | 1661 | RSS: Main: 243646464 04:21:20 INFO - PROCESS | 1661 | 04:21:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 04:21:24 INFO - PROCESS | 1661 | RSS: Main: 294391808 04:21:24 INFO - PROCESS | 1661 | 04:21:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 04:21:27 INFO - PROCESS | 1661 | RSS: Main: 304349184 04:21:27 INFO - PROCESS | 1661 | 04:21:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 04:21:31 INFO - PROCESS | 1661 | RSS: Main: 387248128 04:21:31 INFO - PROCESS | 1661 | 04:21:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 04:21:34 INFO - PROCESS | 1661 | RSS: Main: 289218560 04:21:34 INFO - PROCESS | 1661 | 04:21:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:21:36 INFO - PROCESS | 1661 | RSS: Main: 293232640 04:21:36 INFO - PROCESS | 1661 | 04:21:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 04:21:40 INFO - PROCESS | 1661 | RSS: Main: 329142272 04:21:40 INFO - PROCESS | 1661 | 04:21:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 04:21:43 INFO - PROCESS | 1661 | RSS: Main: 329150464 04:21:43 INFO - PROCESS | 1661 | 04:21:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 04:21:47 INFO - PROCESS | 1661 | RSS: Main: 331145216 04:21:47 INFO - PROCESS | 1661 | 04:21:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:21:49 INFO - PROCESS | 1661 | RSS: Main: 311713792 04:21:49 INFO - PROCESS | 1661 | 04:21:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:21:52 INFO - PROCESS | 1661 | RSS: Main: 318148608 04:21:52 INFO - PROCESS | 1661 | 04:21:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:21:54 INFO - PROCESS | 1661 | RSS: Main: 318218240 04:21:54 INFO - PROCESS | 1661 | 04:21:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:21:58 INFO - PROCESS | 1661 | RSS: Main: 325066752 04:21:58 INFO - PROCESS | 1661 | 04:22:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:22:01 INFO - PROCESS | 1661 | RSS: Main: 326512640 04:22:01 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 04:22:03 INFO - PROCESS | 1661 | RSS: Main: 370454528 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | __start_tp_report 04:22:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 04:22:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 04:22:03 INFO - PROCESS | 1661 | |i|pagename|runs| 04:22:03 INFO - PROCESS | 1661 | |0;ai-astar;101;112;112;114;113;112;114;114;112;111 04:22:03 INFO - PROCESS | 1661 | |1;audio-beat-detection;143;107;105;156;128;117;128;104;140;104 04:22:03 INFO - PROCESS | 1661 | |2;audio-dft;182;184;183;185;182;183;181;182;182;182 04:22:03 INFO - PROCESS | 1661 | |3;audio-fft;76;74;57;108;56;58;63;66;83;57 04:22:03 INFO - PROCESS | 1661 | |4;audio-oscillator;82;89;93;95;101;98;97;72;68;102 04:22:03 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;88;86;90;87;89;94;134;87;89;85 04:22:03 INFO - PROCESS | 1661 | |6;imaging-darkroom;95;94;94;95;95;95;95;95;95;94 04:22:03 INFO - PROCESS | 1661 | |7;imaging-desaturate;86;85;87;86;85;86;105;132;142;87 04:22:03 INFO - PROCESS | 1661 | |8;json-parse-financial;54;55;54;55;55;55;54;57;55;56 04:22:03 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;66;67;74;78;71;70;72;70;69;71 04:22:03 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;84;81;84;84;85;80;83;82;87;75 04:22:03 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;140;135;138;134;147;155;149;135;180;135 04:22:03 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;162;161;170;220;199;163;160;161;162;163 04:22:03 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;65;66;67;65;66;66;66;65;65;65 04:22:03 INFO - PROCESS | 1661 | __end_tp_report 04:22:03 INFO - PROCESS | 1661 | __start_cc_report 04:22:03 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2076 04:22:03 INFO - PROCESS | 1661 | __end_cc_report 04:22:03 INFO - PROCESS | 1661 | __startTimestamp1468408923888__endTimestamp 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | ------- Summary: start ------- 04:22:03 INFO - PROCESS | 1661 | Number of tests: 14 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.50 stddev:3.84 (3.4%) stddev-sans-first:1.12 04:22:03 INFO - PROCESS | 1661 | Values: 101.0 112.0 112.0 114.0 113.0 112.0 114.0 114.0 112.0 111.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:128.00 stddev:18.75 (14.7%) stddev-sans-first:18.47 04:22:03 INFO - PROCESS | 1661 | Values: 143.0 107.0 105.0 156.0 128.0 117.0 128.0 104.0 140.0 104.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:182.60 Median:182.50 stddev:1.17 (0.6%) stddev-sans-first:1.22 04:22:03 INFO - PROCESS | 1661 | Values: 182.0 184.0 183.0 185.0 182.0 183.0 181.0 182.0 182.0 182.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:69.80 Median:64.50 stddev:16.36 (25.4%) stddev-sans-first:17.19 04:22:03 INFO - PROCESS | 1661 | Values: 76.0 74.0 57.0 108.0 56.0 58.0 63.0 66.0 83.0 57.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:91.00 stddev:11.94 (13.1%) stddev-sans-first:12.34 04:22:03 INFO - PROCESS | 1661 | Values: 82.0 89.0 93.0 95.0 101.0 98.0 97.0 72.0 68.0 102.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.50 stddev:14.65 (16.6%) stddev-sans-first:15.44 04:22:03 INFO - PROCESS | 1661 | Values: 88.0 86.0 90.0 87.0 89.0 94.0 134.0 87.0 89.0 85.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:22:03 INFO - PROCESS | 1661 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:86.00 stddev:21.48 (25.0%) stddev-sans-first:22.33 04:22:03 INFO - PROCESS | 1661 | Values: 86.0 85.0 87.0 86.0 85.0 86.0 105.0 132.0 142.0 87.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.94 (1.7%) stddev-sans-first:0.93 04:22:03 INFO - PROCESS | 1661 | Values: 54.0 55.0 54.0 55.0 55.0 55.0 54.0 57.0 55.0 56.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.43 (4.8%) stddev-sans-first:3.16 04:22:03 INFO - PROCESS | 1661 | Values: 66.0 67.0 74.0 78.0 71.0 70.0 72.0 70.0 69.0 71.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:84.00 stddev:3.31 (3.9%) stddev-sans-first:3.46 04:22:03 INFO - PROCESS | 1661 | Values: 84.0 81.0 84.0 84.0 85.0 80.0 83.0 82.0 87.0 75.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:143.50 stddev:14.30 (10.0%) stddev-sans-first:15.06 04:22:03 INFO - PROCESS | 1661 | Values: 140.0 135.0 138.0 134.0 147.0 155.0 149.0 135.0 180.0 135.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:163.00 stddev:20.51 (12.6%) stddev-sans-first:21.42 04:22:03 INFO - PROCESS | 1661 | Values: 162.0 161.0 170.0 220.0 199.0 163.0 160.0 161.0 162.0 163.0 04:22:03 INFO - PROCESS | 1661 | 04:22:03 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 04:22:03 INFO - PROCESS | 1661 | Values: 65.0 66.0 67.0 65.0 66.0 66.0 66.0 65.0 65.0 65.0 04:22:03 INFO - PROCESS | 1661 | -------- Summary: end -------- 04:22:03 INFO - PROCESS | 1661 | 04:22:04 INFO - TEST-INFO | 1661: exit 0 04:22:04 INFO - TEST-OK | kraken | took 59310ms 04:22:04 INFO - SUITE-END | took 1176s 04:22:05 INFO - Completed test suite (00:19:36) 04:22:05 INFO - javascript benchmark 04:22:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.832608369581, 3355.412802759518, 3363.9908027591723, 3390.6100798408193, 3418.8632454701815, 5545.889082218353, 5570.415106413824, 5618.690971996539, 5651.604387692858, 5652.604317697763, 5627.324721033471, 5633.746481408347, 5741.0, 5743.167240750089, 5764.942350576493, 5037.471065538102, 5069.138246498086, 5106.69359838409, 5113.616478764089, 5224.660397074197, 5181.6113791465605, 5209.166533354664, 5225.660332078422, 5241.318628578299, 5271.86820329491, 4093.8771836844944, 5041.0, 5087.847364579068, 5088.440271570124, 5123.718195499246, 5084.9745751271375, 5085.847424577269, 5089.720065396402, 5099.898002039939, 5142.4600416956055, 5094.414142373637, 5130.384353877558, 5147.613928955306, 5165.380154381461, 5213.843584692465, 5529.253550770634, 5593.636413633101, 5662.49037586615, 5662.546996240291, 5743.655380677173, 6130.574441619199, 6151.938480615181, 6163.106349579286, 6179.351168127327, 6184.257889053298, 6163.352847950945, 6220.160278362454, 6225.782097626607, 6245.87508249837, 6260.40526150015, 5576.219329293902, 5630.859228519238, 5658.773649054074, 5712.314522257356, 5763.279590051244, 5395.865103372447, 6209.534284928648, 6334.493240540838, 6346.714397852108, 6367.586106903036, 6096.085587161872, 6097.354222971313, 6107.389261073946, 6109.114178444101, 6123.96938015316, 6009.487088680874, 6039.305479869871, 6057.770322400836, 6108.706347747029, 6235.719392627343, 6034.125051867454, 6080.391960803972, 6109.541784366191, 6124.969375153096, 6130.2030736004035, 6118.663473508911, 6136.7852125175405, 6139.201903752573, 6156.476699480505, 6167.722452489649, 4812.5668689818085, 4830.830920917751, 4847.224444088929, 4847.418309802846, 4853.6602437829015, 4384.05742765307, 4454.7549884756645, 4474.6420286377015, 4478.104379124123, 4479.820807167677, 5680.204771332017, 5694.088945768657, 5694.629849059798, 5697.487226149667, 5697.743601537941, 6121.544430002081, 6139.201903752483, 6152.692365381802, 6167.383261673796, 6195.535334849904, 5715.028445164332, 5726.885462290814, 5731.598788084794, 5747.626404283708, 5761.222234998221, 5273.1035723927025, 5384.273123128406, 5390.00284947286, 5397.4062853086125, 5399.4600539945695, 6242.781502647385, 6251.249850018027, 6307.369263073656, 6324.399182077604, 6326.905096423466, 6141.631502109888, 6245.750169993331, 6250.3749625037135, 6260.311365749855, 6279.120923070683, 6251.78118765841, 6322.241331040304, 6337.493000559852, 6350.110984462087, 6365.013422919455, 5968.0, 5992.161097446274, 6067.969660151671, 6081.0, 6092.329843717276, 6203.503719702503, 6209.627422354673, 6221.688915554295, 6244.344343843831, 6252.531060170414, 5165.870853228699, 5693.117566777156, 5845.386234445322, 5949.732262048305, 6011.72947217385, 5811.331696854747, 5859.56053296013, 5952.702364881825, 5984.611000284967, 6008.369121242206, 5775.104858746901, 5784.884302313846, 5834.649921004753, 5862.208601838868, 5888.499477544457, 6002.3097343804275, 6033.0, 6033.276006879202, 6035.0, 6053.061775424816, 5689.601727879009, 5736.913946290725, 5757.971210143923, 5833.212516310414, 5846.181534585076], "value": 5628.470916445855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.250945976977, 476.325998711823, 534.0680512505677, 534.4228233507812, 542.9972850135749, 29860.850695746518, 30118.849405752968, 42019.57980420195, 42570.93572660685, 42641.360379594335, 43937.78031109848, 44166.77916610413, 44295.335569966395, 44392.55607443924, 44411.777941110246, 9684.854727179098, 9870.259730520203, 9870.654527091554, 9877.456739879304, 9880.505974701273, 8685.95657021714, 8885.733427997171, 11741.354225517593, 11901.10741694372, 11928.105392095584, 11810.350430726307, 11984.101192410604, 11993.640190794247, 12016.879831201708, 12032.278063316186, 8756.737297881074, 11917.404129793518, 12000.279983201035, 12009.099317551132, 12025.639230823046, 11638.0, 12035.277883327028, 12042.397880106048, 12051.819222711667, 12089.21420107699, 39862.0, 40089.39865901985, 40121.0, 40177.196456071004, 40226.59773402287, 3506.982465087658, 3559.270349578356, 3601.099725068733, 3674.7427680062406, 3855.4409610606303, 27331.180064598095, 30693.77224911001, 31163.0, 31174.532382014288, 31932.521012184836, 3248.96751032489, 3337.9666203337897, 3357.7817441866446, 3398.7111095556907, 3418.7265018798435, 435303.0, 437139.0, 437960.0, 438270.0, 438415.0, 57465.0, 57675.711621441624, 57754.42245577575, 57768.8446231073, 57898.131528027116, 61629.691851540454, 61796.38203617996, 61846.0, 61857.072143917874, 61980.69009654923, 16419.261133249034, 16657.167141643113, 16657.91723537966, 16666.250018749186, 16674.66650667004, 11933.105017123751, 12153.756924861453, 12160.209586377034, 12419.31693756834, 13003.934980325226, 15589.142597157039, 15646.765298520531, 16135.757963630555, 16262.918685406497, 16289.0, 6694.364035416627, 6885.277045910208, 7046.0, 7074.71701131949, 7144.642767861586, 812.2892469089546, 844.7634662294568, 906.9410488318238, 961.927855410847, 1080.2006515178869, 29745.553816692765, 29942.0, 30183.09450716482, 30687.693123068482, 30963.535546966814, 1069.850220969065, 1088.9455527223606, 1097.204526718123, 1101.2126329674322, 1135.6990397544657, 15526.767098493532, 15610.531684049496, 15657.669098126558, 15678.66731327827, 16049.759253611206, 194401.0, 194612.0, 195117.0, 195407.0, 195643.0, 60154.398456015755, 60231.69884150551, 60235.397646023855, 60312.69843650753, 60365.396346036854, 1300.5773123734823, 1345.132389608697, 1419.1839692176834, 1445.5880074178808, 1473.4032716749734, 29883.0, 30111.548326775555, 31459.528107077953, 32212.35575288528, 32237.51643725393, 49868.750656246484, 49974.750126249135, 50458.0, 50586.988260235325, 50764.23853642272, 474458.0, 475502.62248688535, 475610.0, 476262.0, 477459.0, 34441.966740997814, 35595.64404355923, 35752.82123589365, 39329.0, 40481.39277910892, 40551.1889762209, 42188.78905605452, 48741.75629121832, 49356.2596561059, 49588.50411495983, 38463.615363846, 39442.802785987034, 39858.82541142622, 39996.60003399929, 40325.39511907382, 39600.0, 39642.80178599089, 40186.598134018284, 40570.59429405668, 40575.797121015385, 38884.61115388923, 38977.80511097427, 39189.60810391974, 39191.0, 39350.0, 33648.663513364554, 33865.0, 34712.65287347094, 35553.644463555036, 35583.288334233686, 24781.876090620153, 25472.108476203244, 25946.740532595188, 26169.398253379164, 26480.43910778681], "value": 19741.308386336485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.1739000299311, 381.39358420112023, 390.6581740976645, 392.7918203352221, 412.3340804600569, 15437.0, 15700.921495392522, 20657.896710516463, 21035.263765768203, 21054.684179737298, 20573.794262057374, 21113.894430527827, 21121.57756844862, 21163.259285924996, 21419.678704819442, 5489.39616642169, 6002.87994240116, 6133.233345831771, 6169.629822210659, 6183.9381606183815, 6042.456178943894, 6086.330503644595, 6157.661328626924, 6160.0, 6160.1683772690785, 5809.912851307234, 5985.042393217086, 5985.5810093293485, 6013.548983826208, 6050.455459008688, 5748.2527271454865, 5935.347111817697, 6020.759169633209, 6023.69881505923, 6028.397160283958, 5182.792688292464, 5215.113430716788, 5215.8174463893965, 5225.059489291926, 5226.189940559219, 26374.47251054968, 26382.73617263822, 26382.73617263822, 26655.866720666465, 26713.33216669599, 2338.298510446859, 2418.286605451396, 2577.80666450017, 2863.840144741383, 2962.888916656254, 15866.52400427989, 15915.0, 15975.84024159755, 16152.676946461122, 16157.757633635505, 1861.4973957031525, 1878.633666435032, 1894.2517705506248, 1927.0461121744686, 1979.604079184169, 109738.0, 111275.44362278217, 111846.0, 112035.0, 112293.0, 8601.956990215009, 9649.324547281623, 10083.647072352433, 10190.388576685422, 10219.335743176818, 12393.318367489876, 12427.875721242852, 12511.12422130442, 12535.811962820566, 12546.0, 8062.919370806334, 8261.917380826235, 8267.173282671805, 8308.252257296872, 8323.334133269445, 7207.639618019078, 8358.582070896553, 8674.91325086741, 8678.349123815738, 8800.383973121949, 3913.1391093959282, 3921.92156156881, 3954.5056867891512, 3967.3255546557157, 3997.1605962747567, 1664.3509031477734, 1804.5398423402212, 1809.2762894841906, 1924.5765931495048, 1995.122146255643, 708.0, 824.4805772363372, 890.9821803564022, 906.2297047509696, 1003.1322905686528, 21035.36893893155, 21380.358589242347, 21903.795291259135, 21931.342059738232, 22037.449063773212, 869.6173683579204, 878.8462019146624, 958.2046901072093, 963.6145541783343, 1006.6124542051363, 11071.72320691989, 11565.710857228636, 11568.710782230512, 11580.76838463226, 11619.9542041215, 584325.0, 605968.0, 613257.0, 621689.0, 622314.0, 144681.0, 151096.0, 152701.0, 154048.22975885274, 155937.0, 982.6511588386163, 1021.5249908792447, 1038.1590911361973, 1045.6706137566641, 1046.1892033673964, 19817.900910495355, 20141.194352225746, 20195.19219231273, 20211.696824547937, 20255.59488810186, 60263.0, 60276.49308767316, 60554.697226513585, 60605.39394605997, 60747.0, 83038.75441868255, 84016.57991710247, 84487.0, 85576.0, 88211.0, 5513.972430137824, 7021.789346319619, 7622.16156222804, 7684.538927664358, 7712.9614351927885, 12379.62861114168, 13360.665983350495, 13651.726965460437, 13684.247366394944, 13836.930815345859, 11320.6037788677, 11365.261258018203, 11577.768444631229, 11705.941470292593, 11838.408079595814, 11394.829077563678, 11435.885641143483, 11599.0, 11768.529258829893, 11896.762064758483, 9880.35777674449, 11127.0, 11220.66338009861, 11855.110866685205, 12065.215760975509, 7575.621218939141, 7610.124835643972, 7663.0, 7674.15584285717, 7791.92208077912, 9924.965382096509, 11767.52929882795, 11909.940450297692, 11992.160548761503, 11995.940020300191], "value": 9735.081838247981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.8266025416694, 1408.2043645340382, 1691.2727527163322, 1913.502489352768, 1939.1177014458424, 8401.873971890418, 8653.870191947126, 8826.514541700204, 8827.691030813923, 8840.867386989185, 7788.844223115534, 8007.839843203133, 8436.409451338393, 8602.311815054796, 8670.176333248332, 2324.3491822289743, 2350.6239001759636, 2350.882455877208, 2351.2946116165167, 2359.0681680736097, 2327.8254130940163, 2339.9532009359805, 2363.149266264152, 2369.7037870266217, 2372.264597974625, 1837.8070302618166, 2085.2910010596393, 2278.9658155127686, 2345.765423457649, 2348.3542025942834, 2323.512062466884, 2329.126577533425, 2350.894209760565, 2353.6940197774265, 2354.6703461515403, 2313.548857972696, 2335.9065637374483, 2343.484433424644, 2347.5891718949115, 2349.2717257650183, 8766.474011559327, 8854.557272136368, 8855.33584981129, 8855.822883542292, 8872.645094196288, 5182.378114626231, 5330.307060082165, 5332.68003919766, 5333.413324534298, 5333.959877823826, 3547.308274886398, 3552.1652411683235, 3604.7116230701745, 3635.3456377851976, 3643.6538528839983, 938.5776400619749, 1075.7687097274124, 1079.228351728518, 1091.110744743032, 1091.6288461922984, 11575.826362604568, 11586.246893951866, 11589.0, 11597.536098556047, 11625.302481851117, 4446.199684056836, 4510.706804057726, 4684.367610372624, 4717.150912835723, 4723.291506274018, 8358.20597043292, 9810.31327807061, 9821.705348839547, 9840.852387214198, 9852.655157069612, 5286.471352864683, 5287.20691896211, 5290.894182116336, 5291.232771248147, 5292.576593872558, 4837.153498137812, 4845.563899249085, 4854.417469903634, 4865.95134048662, 4874.2444921037295, 3882.2429626222615, 3882.417637354363, 3934.881953541398, 3966.960330396659, 3972.364421692573, 1151.9020883225019, 1195.9820602690966, 1228.975420491585, 1242.4719494214924, 1253.354522420954, 759.2824780582297, 896.7937374403793, 928.4058202750246, 940.3229674634252, 951.9619215231452, 5809.593328466966, 5820.970895145582, 5903.822885313448, 5956.493698035629, 6063.363346848518, 891.505214605887, 949.8100379924043, 952.7713348796239, 972.4116909269932, 984.5372674842812, 3385.542951701538, 3907.0, 3957.821898014596, 4009.7594144351433, 4037.818298176589, 9248.907510924939, 9984.400935943866, 10619.522121504397, 10732.570697172026, 10779.245452818228, 10071.34536255141, 10505.474726263656, 10649.467526623637, 10666.0, 10673.35959842412, 7962.442629015914, 8191.0580283267, 8193.262606365337, 8199.262066414052, 8207.507549547046, 5916.467517923407, 5986.2517185351835, 6007.669578173242, 6086.939130608815, 6262.561620686508, 9485.193758530602, 9923.305368624127, 9935.602575897172, 9935.850962235716, 9948.552315145691, 9940.950295248478, 10086.79826403453, 10108.797824043331, 10113.443760593238, 10118.392896426238, 7414.406847452299, 7420.666070026754, 7571.697132114874, 7584.962075189589, 7614.733484328022, 8117.431779775595, 8284.751457456287, 8288.792780180544, 8289.378296627656, 8289.4197406181, 7044.577325360495, 7308.707651693872, 7359.8160045999275, 7420.740274090597, 7550.565392575393, 7291.708331666674, 7297.270272972661, 7370.77887663371, 7429.368503677032, 7430.4798664093, 6639.3692599203, 6777.728890844311, 6989.33601307875, 7004.949257611399, 7076.646167691491, 4269.42362781033, 4812.181929071996, 4889.022195560973, 4927.211646136601, 5045.0, 4951.851444456672, 5002.899942001212, 5018.0, 5072.746362681925, 5099.3370861787735], "value": 4554.067812975833, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [240.43977532349624, 303.411381919077, 308.7051865468477, 309.26857980875235, 315.42277631933564, 4473.150101480721, 5277.287566178565, 5357.8212793185485, 5391.676499410029, 5400.459954004597, 4422.867313980578, 4755.358026666399, 5287.127623942045, 5332.493413125747, 5358.5169639734095, 3452.033430639419, 3854.0, 3863.74885632433, 4017.955331613772, 4047.51429828421, 3973.960260397388, 3980.5621381647998, 4008.5390180129366, 4052.1895620875944, 4065.9186816263805, 4012.2978478766245, 4021.457103291062, 4022.798860057, 4034.374671925856, 4054.2296963576828, 3071.616047994001, 3478.3651683708617, 3782.3191825471404, 3894.55212650546, 4034.9193016139807, 2860.842653654069, 3468.6704763047724, 3509.3332266869215, 3511.315293517765, 3523.3834079036064, 5163.7676304566385, 5167.689938603658, 5182.067227898977, 5191.299174611455, 5217.739113044333, 3950.980245098785, 4224.746515209066, 4531.0, 4620.036484494077, 4623.86128416148, 5411.594130440233, 5442.857000029999, 5454.427285135083, 5485.6982865942355, 5487.780488780444, 3749.493818334517, 3758.6241375862196, 3773.9811300943593, 3800.277947190025, 3801.3157631626295, 4843.813265749878, 4852.369192005052, 5162.535371816554, 5376.892462150774, 5753.395893431213, 3796.8291426885858, 3796.962030379716, 3807.1053302473897, 3828.674562662149, 3870.825812838429, 3496.4056110461283, 3507.438809790421, 3907.648311651908, 3918.6104020023045, 3920.6471417571984, 3795.7153213509096, 4285.357196420562, 4534.319852022157, 4564.269716845289, 4579.816807327737, 5327.413984461706, 5543.7782488700805, 5588.21764952907, 5614.382417934024, 5639.2518319320325, 1066.9626563070256, 1123.853898993142, 1172.6833754886134, 1308.9869101309055, 1330.5210124355224, 697.0306494334172, 751.8571770908299, 785.3206975965862, 819.2053707903324, 822.6421506644629, 4085.4484644572603, 4959.603231741452, 5112.233165025202, 5201.7919283229, 5262.368515778131, 4712.882177945578, 5321.920171197435, 5635.7601327707835, 5669.0, 5748.281464816898, 4680.976595117071, 4853.587445067209, 4859.684120532154, 5223.0, 5242.9213561796605, 2951.704829517031, 2955.2611847038243, 2955.27595739041, 2977.464056469856, 2980.210244285266, 5316.893662126813, 5396.78412863481, 5819.73811178498, 5907.021147915497, 5922.170896074553, 4924.3352147460355, 5441.863953401117, 5739.236433760053, 5779.826605201851, 5939.346671866091, 5970.432808883149, 5999.70001499932, 6025.849353766191, 6026.668533230714, 6038.969805150946, 5103.387593488805, 5504.779808807602, 5801.0, 5822.592418530662, 5848.853778655568, 5580.441955804387, 5597.885554117639, 5612.158176273429, 5666.603337766482, 5738.626989245686, 5483.150111732688, 5620.960122377373, 5830.53355731549, 5924.970375148269, 5975.462208401264, 3599.892003239907, 3797.2025874566652, 3829.4830197922925, 3832.6550610445192, 3837.4052021936645, 4812.422509298906, 4963.245398803699, 5621.634593751393, 5672.376038635829, 5750.0, 4858.92711609319, 5373.543248823894, 5455.618106732491, 5536.806211782729, 5616.354119276336, 4448.910017045844, 5229.241759944863, 5497.587680923867, 5510.972445137749, 5599.80400685974, 4734.928976065431, 5400.432954539717, 5443.972780136074, 5447.1829225616475, 5457.290552228185, 4619.907601847877, 4843.958548912001, 4914.65597408178, 5396.0, 5578.525825304732, 4328.545502722169, 4470.932936005897, 5428.891422171613, 5452.745868450199, 5458.781648734006], "value": 4003.9998710890463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.9823254418638, 715.609992554058, 717.6232477949076, 730.200430528571, 764.2357642357645, 15375.231238438075, 16133.757993630075, 16163.35346586138, 16190.352385904564, 16191.595210119767, 14780.187089710062, 14917.92541037296, 14946.0, 15888.76166857498, 16506.0, 896.2158111652304, 922.9676961306355, 923.8891333040044, 926.6339795780663, 927.8840144981878, 894.6197865906963, 907.7458311672742, 908.609298001859, 910.2035718746097, 910.9726708198732, 962.7593101724569, 965.2037069417723, 965.2856885904415, 965.4786415335745, 966.4249771386014, 959.2757468111566, 959.9856002159974, 960.8510680844445, 961.8268711631904, 962.6101428921298, 715.6529083394549, 716.3660160757714, 716.5127713155052, 720.0711082703307, 720.8306048078697, 15793.763093553605, 15802.209889505479, 15822.920885395615, 15866.206689665585, 15868.682626347409, 12568.245905245714, 14095.0, 14214.218217998006, 14327.212003339811, 14332.785008224884, 11844.815518448173, 11918.880811191864, 11952.701182470508, 12135.696607584881, 12475.750484990249, 3802.372608519591, 3821.617838216184, 3855.9614403855885, 3930.371140617516, 3934.075492259317, 111258.44370778094, 117455.82544174534, 118578.0, 118658.62682746383, 119213.21180182304, 10828.296160749684, 10934.890651093387, 11054.889451105546, 11130.49912753928, 11482.966533011902, 22014.559708805733, 22504.549909002057, 22517.099316027503, 22528.549429011655, 22578.0968761251, 7987.960060199662, 8148.796280093045, 8156.877646835303, 8168.305694015957, 8225.917740822635, 4527.139843429738, 4554.157480866051, 4584.679072464962, 4758.714477131383, 4798.23228283473, 3504.7196224301993, 3648.744587878823, 3649.6897763690386, 3651.561812582507, 3652.4886515887797, 679.7688785812848, 687.7627218609572, 699.2203692882501, 701.3722718167314, 710.1868360727042, 539.4632340820908, 596.7702434562725, 668.6088638146739, 680.4714624176116, 709.6487238816716, 8867.68963086289, 9124.543772811332, 9276.768080798034, 9317.953410232905, 9431.434113953048, 533.8692020455013, 603.4900509069829, 617.6843323720447, 622.6886556721639, 680.9216940051858, 4130.648894843912, 4555.385023021916, 4559.566841150086, 4575.862724118282, 4575.908481830411, 81515.18484815194, 88256.0, 127102.72897271095, 132289.33855330662, 135186.0, 110837.0, 114249.0, 116217.0, 116680.0, 117540.0, 98722.0, 100775.99999999853, 101250.0, 101869.0, 105622.47188764007, 8823.161799629024, 8858.512781797062, 8908.55457227149, 8934.106589341274, 8989.41568798026, 56575.86848263094, 57508.71245643745, 57671.42328576828, 58079.0, 58898.0, 69233.61532769418, 83343.0, 84114.57942710248, 85313.0, 87154.12845871461, 12017.579384721494, 12038.0, 12097.69755756078, 12119.855997772243, 12547.184433011826, 12447.253164810141, 12644.494220231089, 12653.936730316289, 12806.67983300425, 13124.081314307907, 12011.399430028638, 12028.27830330183, 12088.581674341298, 12117.394130293625, 12323.075769317158, 11919.82120268214, 12126.33305168189, 12281.508739650313, 12357.32034738062, 12463.813042804546, 11853.407329633656, 11998.0, 12019.579314724293, 12210.755784884075, 12400.81398779037, 10035.69892903214, 10239.590416383262, 10273.280870339004, 10502.737431564272, 10566.788664226826, 9488.905110948803, 9773.364731292444, 10313.381197128196, 10335.896641033494, 10349.84475232857], "value": 7677.517901396185, "unit": "score"}], "name": "dromaeo_css", "value": 7300.925768400594, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 114.0, 113.0, 112.0, 114.0, 114.0, 112.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 105.0, 156.0, 128.0, 117.0, 128.0, 104.0, 140.0, 104.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 185.0, 182.0, 183.0, 181.0, 182.0, 182.0, 182.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 57.0, 108.0, 56.0, 58.0, 63.0, 66.0, 83.0, 57.0], "value": 69.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 93.0, 95.0, 101.0, 98.0, 97.0, 72.0, 68.0, 102.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 87.0, 89.0, 94.0, 134.0, 87.0, 89.0, 85.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 86.0, 85.0, 86.0, 105.0, 132.0, 142.0, 87.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 54.0, 57.0, 55.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 74.0, 78.0, 71.0, 70.0, 72.0, 70.0, 69.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 84.0, 84.0, 85.0, 80.0, 83.0, 82.0, 87.0, 75.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 138.0, 134.0, 147.0, 155.0, 149.0, 135.0, 180.0, 135.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 170.0, 220.0, 199.0, 163.0, 160.0, 161.0, 162.0, 163.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1453.2999999999997, "alertThreshold": 2.0}]} 04:22:05 INFO - Return code: 0 04:22:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:22:05 INFO - # TBPL SUCCESS # 04:22:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:22:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:22:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:22:05 INFO - 2016-07-13 04:22:05.223 screenresolution[1665:9878] starting screenresolution argv=screenresolution get 04:22:05 INFO - 2016-07-13 04:22:05.225 screenresolution[1665:9878] Display 0: 1600x1200x32@60 04:22:05 INFO - 2016-07-13 04:22:05.231 screenresolution[1666:9881] starting screenresolution argv=screenresolution list 04:22:05 INFO - Available Modes on Display 0 04:22:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:22:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:22:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:22:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:22:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:22:05 INFO - Intel Iris: 04:22:05 INFO - Chipset Model: Intel Iris 04:22:05 INFO - Type: GPU 04:22:05 INFO - Bus: Built-In 04:22:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:22:05 INFO - Vendor: Intel (0x8086) 04:22:05 INFO - Device ID: 0x0a2e 04:22:05 INFO - Revision ID: 0x0009 04:22:05 INFO - Displays: 04:22:05 INFO - Display: 04:22:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:22:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:22:05 INFO - Display Serial Number: 1600x1200 60 04:22:05 INFO - Main Display: Yes 04:22:05 INFO - Mirror: Off 04:22:05 INFO - Online: Yes 04:22:05 INFO - Rotation: Supported 04:22:05 INFO - Return code: 0 04:22:05 INFO - Running post-action listener: _resource_record_post_action 04:22:05 INFO - ##### 04:22:05 INFO - ##### Finished run-tests step (success) 04:22:05 INFO - ##### 04:22:05 INFO - Running post-run listener: _resource_record_post_run 04:22:05 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37332480; Write bytes: 701348864; Read time: 387; Write time: 1998 04:22:05 INFO - TinderboxPrint: CPU usage
53.5% 04:22:05 INFO - TinderboxPrint: I/O read bytes / time
37,332,480 / 387 04:22:05 INFO - TinderboxPrint: I/O write bytes / time
701,348,864 / 1,998 04:22:05 INFO - TinderboxPrint: CPU idle
2,255.5 (46.5%) 04:22:05 INFO - TinderboxPrint: CPU system
967.1 (20.0%) 04:22:05 INFO - TinderboxPrint: CPU user
1,622.9 (33.5%) 04:22:05 INFO - TinderboxPrint: Swap in / out
444,448,768 / 0 04:22:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397580800; Write bytes: 386922496; Read time: 29321; Write time: 788 04:22:05 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31153664; Write bytes: 291361792; Read time: 303; Write time: 1162 04:22:05 INFO - Running post-run listener: _upload_blobber_files 04:22:05 INFO - Blob upload gear active. 04:22:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:22:05 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 04:22:05 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'] 04:22:05 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 04:22:06 INFO - (blobuploader) - INFO - Open directory for files ... 04:22:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:22:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:07 INFO - (blobuploader) - INFO - Done attempting. 04:22:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:22:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:07 INFO - (blobuploader) - INFO - Done attempting. 04:22:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:22:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:10 INFO - (blobuploader) - INFO - Done attempting. 04:22:10 INFO - (blobuploader) - INFO - Iteration through files over. 04:22:10 INFO - Return code: 0 04:22:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:22:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:22:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f3769cc8bca973dc2ea70538274c378d912035bbe8fa3342b0a339b3418fb9298337fd86e4329a0e82f492be84123f04906ed19a9f11ce27a0d6ffa5f58e096", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8aae15d146c80e0648ecf4c4b5da7d1f6d7874aba2d1795c539bf14159eb895f26838419f7086ef6d27ab0647ac5d211eee711b44f781c3823193f7363ead5e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/34a098935853a7c6e30005c12ccb15e0b8f589d4dc0f769a4f9bcd0963adff7ee3882020361ca858b01660d24db6ebf7b9052d8a0c5b421c0374bb172fe1c26a"} 04:22:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:22:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:22:10 INFO - Contents: 04:22:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f3769cc8bca973dc2ea70538274c378d912035bbe8fa3342b0a339b3418fb9298337fd86e4329a0e82f492be84123f04906ed19a9f11ce27a0d6ffa5f58e096", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8aae15d146c80e0648ecf4c4b5da7d1f6d7874aba2d1795c539bf14159eb895f26838419f7086ef6d27ab0647ac5d211eee711b44f781c3823193f7363ead5e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/34a098935853a7c6e30005c12ccb15e0b8f589d4dc0f769a4f9bcd0963adff7ee3882020361ca858b01660d24db6ebf7b9052d8a0c5b421c0374bb172fe1c26a"} 04:22:10 INFO - Running post-run listener: copy_logs_to_upload_dir 04:22:10 INFO - Copying logs to upload dir... 04:22:10 INFO - mkdir: /builds/slave/test/build/upload/logs 04:22:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.254002 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-13 04:22:10.341171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 04:22:10.348456) ========= 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.gdn5aVFsPh/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.n4rXjwFz05/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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/8f3769cc8bca973dc2ea70538274c378d912035bbe8fa3342b0a339b3418fb9298337fd86e4329a0e82f492be84123f04906ed19a9f11ce27a0d6ffa5f58e096", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8aae15d146c80e0648ecf4c4b5da7d1f6d7874aba2d1795c539bf14159eb895f26838419f7086ef6d27ab0647ac5d211eee711b44f781c3823193f7363ead5e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/34a098935853a7c6e30005c12ccb15e0b8f589d4dc0f769a4f9bcd0963adff7ee3882020361ca858b01660d24db6ebf7b9052d8a0c5b421c0374bb172fe1c26a"} build_url:https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011007 build_url: 'https://queue.taskcluster.net/v1/task/dOB6gCSxSfuqRh1sLWVOdg/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/8f3769cc8bca973dc2ea70538274c378d912035bbe8fa3342b0a339b3418fb9298337fd86e4329a0e82f492be84123f04906ed19a9f11ce27a0d6ffa5f58e096", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8aae15d146c80e0648ecf4c4b5da7d1f6d7874aba2d1795c539bf14159eb895f26838419f7086ef6d27ab0647ac5d211eee711b44f781c3823193f7363ead5e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/34a098935853a7c6e30005c12ccb15e0b8f589d4dc0f769a4f9bcd0963adff7ee3882020361ca858b01660d24db6ebf7b9052d8a0c5b421c0374bb172fe1c26a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 04:22:10.383408) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 04:22:10.383758) ========= 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.gdn5aVFsPh/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.n4rXjwFz05/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.005356 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 04:22:10.431973) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 04:22:10.435384) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 04:22:10.435737) ========= ========= Total master_lag: 0.19 =========