builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0242 starttime: 1472817577.07 results: success (0) buildid: 20160902025442 builduid: 59fbbd0d60234bf8962ae65e124f44ab revision: 884f60cd86d31b32ec4883246ace726bc93e445b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.069858) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.070310) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.070597) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.095748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.096059) ========= 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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-02 04:59:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-09-02 04:59:37 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132962 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.251912) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.252243) ========= 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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.026053 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:37.300763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 04:59:37.301123) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 884f60cd86d31b32ec4883246ace726bc93e445b --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 884f60cd86d31b32ec4883246ace726bc93e445b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pIhkcGEwQP/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.NOxkqcQdMg/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-02 04:59:37,384 truncating revision to first 12 chars 2016-09-02 04:59:37,385 Setting DEBUG logging. 2016-09-02 04:59:37,385 attempt 1/10 2016-09-02 04:59:37,385 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/884f60cd86d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 04:59:38,786 unpacking tar archive at: fx-team-884f60cd86d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.663929 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 04:59:38.986733) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:38.987068) ========= script_repo_revision: 884f60cd86d31b32ec4883246ace726bc93e445b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:38.987486) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:38.987753) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 04:59:39.006360) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-02 04:59:39.006701) ========= /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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:59:39 INFO - MultiFileLogger online at 20160902 04:59:39 in /builds/slave/test 04:59:39 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:59:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:39 INFO - {'append_to_log': False, 04:59:39 INFO - 'base_work_dir': '/builds/slave/test', 04:59:39 INFO - 'blob_upload_branch': 'Fx-Team', 04:59:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:39 INFO - 'branch': 'Fx-Team', 04:59:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:39 INFO - 'config_files': ('talos/mac_config.py',), 04:59:39 INFO - 'default_actions': ('clobber', 04:59:39 INFO - 'read-buildbot-config', 04:59:39 INFO - 'download-and-extract', 04:59:39 INFO - 'populate-webroot', 04:59:39 INFO - 'create-virtualenv', 04:59:39 INFO - 'install', 04:59:39 INFO - 'run-tests'), 04:59:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:39 INFO - 'download_minidump_stackwalk': True, 04:59:39 INFO - 'download_symbols': 'ondemand', 04:59:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:39 INFO - 'installer_path': 'installer.exe', 04:59:39 INFO - 'log_level': 'info', 04:59:39 INFO - 'log_name': 'talos', 04:59:39 INFO - 'log_to_console': True, 04:59:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:39 INFO - 'opt_config_files': (), 04:59:39 INFO - 'pip_index': False, 04:59:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:39 INFO - 'cmd': ('bash', 04:59:39 INFO - '-c', 04:59:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:39 INFO - 'enabled': True, 04:59:39 INFO - 'halt_on_failure': False, 04:59:39 INFO - 'name': 'check_screen_resolution'},), 04:59:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:39 INFO - 'cmd': ('bash', 04:59:39 INFO - '-c', 04:59:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:39 INFO - 'enabled': True, 04:59:39 INFO - 'halt_on_failure': False, 04:59:39 INFO - 'name': 'check_screen_resolution'},), 04:59:39 INFO - 'run_cmd_checks_enabled': True, 04:59:39 INFO - 'sps_profile': False, 04:59:39 INFO - 'sps_profile_interval': 0, 04:59:39 INFO - 'suite': 'dromaeojs', 04:59:39 INFO - 'system_bits': '32', 04:59:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:39 INFO - 'title': 't-yosemite-r7-0242', 04:59:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:39 INFO - 'use_talos_json': True, 04:59:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:39 INFO - 'work_dir': 'build'} 04:59:39 INFO - [mozharness: 2016-09-02 11:59:39.204587Z] Running clobber step. 04:59:39 INFO - Running pre-action listener: _resource_record_pre_action 04:59:39 INFO - Running main action method: clobber 04:59:39 INFO - rmtree: /builds/slave/test/build 04:59:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:41 INFO - Running post-action listener: _resource_record_post_action 04:59:41 INFO - [mozharness: 2016-09-02 11:59:41.354948Z] Finished clobber step (success) 04:59:41 INFO - [mozharness: 2016-09-02 11:59:41.355185Z] Running read-buildbot-config step. 04:59:41 INFO - Running pre-action listener: _resource_record_pre_action 04:59:41 INFO - Running main action method: read_buildbot_config 04:59:41 INFO - Using buildbot properties: 04:59:41 INFO - { 04:59:41 INFO - "project": "", 04:59:41 INFO - "product": "firefox", 04:59:41 INFO - "script_repo_revision": "production", 04:59:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:59:41 INFO - "repository": "", 04:59:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:59:41 INFO - "buildid": "20160902025442", 04:59:41 INFO - "pgo_build": "False", 04:59:41 INFO - "basedir": "/builds/slave/test", 04:59:41 INFO - "buildnumber": 139, 04:59:41 INFO - "slavename": "t-yosemite-r7-0242", 04:59:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:59:41 INFO - "platform": "yosemite_r7", 04:59:41 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:59:41 INFO - "branch": "fx-team", 04:59:41 INFO - "repo_path": "integration/fx-team", 04:59:41 INFO - "revision": "884f60cd86d31b32ec4883246ace726bc93e445b", 04:59:41 INFO - "stage_platform": "macosx64", 04:59:41 INFO - "builduid": "59fbbd0d60234bf8962ae65e124f44ab", 04:59:41 INFO - "slavebuilddir": "test" 04:59:41 INFO - } 04:59:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:59:41 INFO - Running post-action listener: _resource_record_post_action 04:59:41 INFO - [mozharness: 2016-09-02 11:59:41.359055Z] Finished read-buildbot-config step (success) 04:59:41 INFO - [mozharness: 2016-09-02 11:59:41.359199Z] Running download-and-extract step. 04:59:41 INFO - Running pre-action listener: _resource_record_pre_action 04:59:41 INFO - Running main action method: download_and_extract 04:59:41 INFO - mkdir: /builds/slave/test/build/tests 04:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:41 INFO - trying https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:59:41 INFO - Downloading https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 04:59:42 INFO - Downloaded 1199 bytes. 04:59:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:59:42 INFO - Using the following test package requirements: 04:59:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:59:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:59:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'jsshell-mac.zip'], 04:59:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:59:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:59:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:59:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:59:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:59:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:42 INFO - trying https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:59:42 INFO - Downloading https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:59:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:43 INFO - Downloaded 16399273 bytes. 04:59:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:59:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:50 INFO - trying https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:59:50 INFO - Downloading https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:59:51 INFO - Downloaded 16399273 bytes. 04:59:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:57 INFO - trying https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:59:57 INFO - Downloading https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:59:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:59:58 INFO - Downloaded 14413998 bytes. 04:59:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 04:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:59 INFO - trying https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:59:59 INFO - Downloading https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:00:01 INFO - Downloaded 111588370 bytes. 05:00:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:00:01 INFO - mkdir: /builds/slave/test/properties 05:00:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:00:01 INFO - Writing to file /builds/slave/test/properties/build_url 05:00:01 INFO - Contents: 05:00:01 INFO - build_url:https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:00:02 INFO - Running post-action listener: _resource_record_post_action 05:00:02 INFO - Running post-action listener: set_extra_try_arguments 05:00:02 INFO - [mozharness: 2016-09-02 12:00:02.649388Z] Finished download-and-extract step (success) 05:00:02 INFO - [mozharness: 2016-09-02 12:00:02.649527Z] Running populate-webroot step. 05:00:02 INFO - Running pre-action listener: _resource_record_pre_action 05:00:02 INFO - Running main action method: populate_webroot 05:00:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:00:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:00:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:00:02 INFO - '--tppagecycles', 05:00:02 INFO - '7'], 05:00:02 INFO - 'tests': ['tsvgm']}}, 05:00:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:00:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:00:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:00:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:00:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['damp', 'tps']}, 05:00:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'tests': ['damp', 'tps']}, 05:00:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['dromaeo_dom']}, 05:00:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:00:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['basic_compositor_video']}, 05:00:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:00:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['a11yr', 05:00:02 INFO - 'ts_paint', 05:00:02 INFO - 'tpaint', 05:00:02 INFO - 'sessionrestore', 05:00:02 INFO - 'sessionrestore_no_auto_restore', 05:00:02 INFO - 'tabpaint']}, 05:00:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:00:02 INFO - 'ts_paint', 05:00:02 INFO - 'tpaint', 05:00:02 INFO - 'sessionrestore', 05:00:02 INFO - 'sessionrestore_no_auto_restore', 05:00:02 INFO - 'tabpaint']}, 05:00:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:00:02 INFO - 'ts_paint', 05:00:02 INFO - 'tpaint', 05:00:02 INFO - 'sessionrestore', 05:00:02 INFO - 'sessionrestore_no_auto_restore', 05:00:02 INFO - 'tabpaint']}, 05:00:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:00:02 INFO - 'ts_paint', 05:00:02 INFO - 'tpaint', 05:00:02 INFO - 'sessionrestore', 05:00:02 INFO - 'sessionrestore_no_auto_restore', 05:00:02 INFO - 'tabpaint']}, 05:00:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['a11yr', 05:00:02 INFO - 'ts_paint', 05:00:02 INFO - 'tpaint', 05:00:02 INFO - 'sessionrestore', 05:00:02 INFO - 'sessionrestore_no_auto_restore', 05:00:02 INFO - 'tabpaint']}, 05:00:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['a11yr', 05:00:02 INFO - 'ts_paint', 05:00:02 INFO - 'tpaint', 05:00:02 INFO - 'sessionrestore', 05:00:02 INFO - 'sessionrestore_no_auto_restore', 05:00:02 INFO - 'tabpaint']}, 05:00:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['tsvgx', 05:00:02 INFO - 'tsvgr_opacity', 05:00:02 INFO - 'tart', 05:00:02 INFO - 'tscrollx', 05:00:02 INFO - 'cart']}, 05:00:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:00:02 INFO - 'tsvgr_opacity', 05:00:02 INFO - 'tart', 05:00:02 INFO - 'tscrollx', 05:00:02 INFO - 'cart']}, 05:00:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'talos_options': ['--disable-e10s'], 05:00:02 INFO - 'tests': ['tp5o']}, 05:00:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'tests': ['tp5o']}, 05:00:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'talos_options': ['--disable-e10s', 05:00:02 INFO - '--xperf_path', 05:00:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:02 INFO - 'tests': ['tp5n']}, 05:00:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:02 INFO - 'talos_options': ['--xperf_path', 05:00:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:02 INFO - 'tests': ['tp5n']}}, 05:00:02 INFO - 'talos.zip': {'path': '', 05:00:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:00:02 INFO - Running post-action listener: _resource_record_post_action 05:00:02 INFO - [mozharness: 2016-09-02 12:00:02.668298Z] Finished populate-webroot step (success) 05:00:02 INFO - [mozharness: 2016-09-02 12:00:02.668434Z] Running create-virtualenv step. 05:00:02 INFO - Running pre-action listener: _resource_record_pre_action 05:00:02 INFO - Running main action method: create_virtualenv 05:00:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:00:02 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 05:00:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:00:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:00:02 INFO - Using real prefix '/tools/python27' 05:00:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:00:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:06 INFO - Installing pip.................done. 05:00:06 INFO - Return code: 0 05:00:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:06 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:06 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 05:00:06 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 05:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:06 INFO - 'HOME': '/Users/cltbld', 05:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:06 INFO - 'LOGNAME': 'cltbld', 05:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:06 INFO - 'MOZ_NO_REMOTE': '1', 05:00:06 INFO - 'NO_EM_RESTART': '1', 05:00:06 INFO - 'PAGER': '/bin/cat', 05:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:06 INFO - 'PWD': '/builds/slave/test', 05:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:06 INFO - 'SHELL': '/bin/bash', 05:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:06 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:06 INFO - 'USER': 'cltbld', 05:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:06 INFO - 'XPC_FLAGS': '0x0', 05:00:06 INFO - 'XPC_SERVICE_NAME': '0', 05:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:06 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 05:00:06 INFO - Cleaning up... 05:00:06 INFO - Return code: 0 05:00:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:06 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:06 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 05:00:06 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 05:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:06 INFO - 'HOME': '/Users/cltbld', 05:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:06 INFO - 'LOGNAME': 'cltbld', 05:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:06 INFO - 'MOZ_NO_REMOTE': '1', 05:00:06 INFO - 'NO_EM_RESTART': '1', 05:00:06 INFO - 'PAGER': '/bin/cat', 05:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:06 INFO - 'PWD': '/builds/slave/test', 05:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:06 INFO - 'SHELL': '/bin/bash', 05:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:06 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:06 INFO - 'USER': 'cltbld', 05:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:06 INFO - 'XPC_FLAGS': '0x0', 05:00:06 INFO - 'XPC_SERVICE_NAME': '0', 05:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:06 INFO - Downloading/unpacking psutil>=3.1.1 05:00:06 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 05:00:06 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 05:00:06 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 05:00:06 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 05:00:06 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 05:00:06 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 05:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:10 INFO - Installing collected packages: psutil 05:00:10 INFO - Running setup.py install for psutil 05:00:10 INFO - building 'psutil._psutil_osx' extension 05:00:10 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 05:00:10 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 05:00:10 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 05:00:11 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 05:00:11 INFO - building 'psutil._psutil_posix' extension 05:00:11 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 05:00:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:00:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:00:11 INFO - ^ 05:00:11 INFO - 1 warning generated. 05:00:11 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 05:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:11 INFO - Successfully installed psutil 05:00:11 INFO - Cleaning up... 05:00:11 INFO - Return code: 0 05:00:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:11 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:11 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 05:00:11 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 05:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:11 INFO - 'HOME': '/Users/cltbld', 05:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:11 INFO - 'LOGNAME': 'cltbld', 05:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:11 INFO - 'MOZ_NO_REMOTE': '1', 05:00:11 INFO - 'NO_EM_RESTART': '1', 05:00:11 INFO - 'PAGER': '/bin/cat', 05:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:11 INFO - 'PWD': '/builds/slave/test', 05:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:11 INFO - 'SHELL': '/bin/bash', 05:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:11 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:11 INFO - 'USER': 'cltbld', 05:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:11 INFO - 'XPC_FLAGS': '0x0', 05:00:11 INFO - 'XPC_SERVICE_NAME': '0', 05:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:00:11 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 05:00:11 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 05:00:11 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 05:00:11 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 05:00:11 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 05:00:11 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 05:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:00:14 INFO - Installing collected packages: mozsystemmonitor 05:00:14 INFO - Running setup.py install for mozsystemmonitor 05:00:14 INFO - Successfully installed mozsystemmonitor 05:00:14 INFO - Cleaning up... 05:00:14 INFO - Return code: 0 05:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:14 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:14 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 05:00:14 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 05:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:14 INFO - 'HOME': '/Users/cltbld', 05:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:14 INFO - 'LOGNAME': 'cltbld', 05:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:14 INFO - 'MOZ_NO_REMOTE': '1', 05:00:14 INFO - 'NO_EM_RESTART': '1', 05:00:14 INFO - 'PAGER': '/bin/cat', 05:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:14 INFO - 'PWD': '/builds/slave/test', 05:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:14 INFO - 'SHELL': '/bin/bash', 05:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:14 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:14 INFO - 'USER': 'cltbld', 05:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:14 INFO - 'XPC_FLAGS': '0x0', 05:00:14 INFO - 'XPC_SERVICE_NAME': '0', 05:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:14 INFO - Downloading/unpacking jsonschema==2.5.1 05:00:14 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 05:00:14 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 05:00:14 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 05:00:14 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 05:00:14 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 05:00:14 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 05:00:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:00:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:00:17 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 05:00:17 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 05:00:17 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 05:00:17 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 05:00:17 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 05:00:17 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 05:00:17 INFO - Downloading functools32-3.2.3-2.tar.gz 05:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:00:17 INFO - warning: no files found matching '*.txt' 05:00:17 INFO - no previously-included directories found matching 'build' 05:00:17 INFO - no previously-included directories found matching 'dist' 05:00:17 INFO - no previously-included directories found matching '.git*' 05:00:17 INFO - Installing collected packages: jsonschema, functools32 05:00:17 INFO - Running setup.py install for functools32 05:00:17 INFO - warning: no files found matching '*.txt' 05:00:17 INFO - no previously-included directories found matching 'build' 05:00:17 INFO - no previously-included directories found matching 'dist' 05:00:17 INFO - no previously-included directories found matching '.git*' 05:00:17 INFO - Successfully installed jsonschema functools32 05:00:17 INFO - Cleaning up... 05:00:17 INFO - Return code: 0 05:00:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:17 INFO - 'HOME': '/Users/cltbld', 05:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:17 INFO - 'LOGNAME': 'cltbld', 05:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:17 INFO - 'MOZ_NO_REMOTE': '1', 05:00:17 INFO - 'NO_EM_RESTART': '1', 05:00:17 INFO - 'PAGER': '/bin/cat', 05:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:17 INFO - 'PWD': '/builds/slave/test', 05:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:17 INFO - 'SHELL': '/bin/bash', 05:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:17 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:17 INFO - 'USER': 'cltbld', 05:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:17 INFO - 'XPC_FLAGS': '0x0', 05:00:17 INFO - 'XPC_SERVICE_NAME': '0', 05:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:00:18 INFO - Cleaning up... 05:00:18 INFO - Return code: 0 05:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:18 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:18 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 05:00:18 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 05:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:18 INFO - 'HOME': '/Users/cltbld', 05:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:18 INFO - 'LOGNAME': 'cltbld', 05:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:18 INFO - 'MOZ_NO_REMOTE': '1', 05:00:18 INFO - 'NO_EM_RESTART': '1', 05:00:18 INFO - 'PAGER': '/bin/cat', 05:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:18 INFO - 'PWD': '/builds/slave/test', 05:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:18 INFO - 'SHELL': '/bin/bash', 05:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:18 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:18 INFO - 'USER': 'cltbld', 05:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:18 INFO - 'XPC_FLAGS': '0x0', 05:00:18 INFO - 'XPC_SERVICE_NAME': '0', 05:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:00:18 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 05:00:18 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 05:00:18 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 05:00:18 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 05:00:18 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 05:00:18 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 05:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00:20 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 05:00:20 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 05:00:20 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 05:00:20 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 05:00:20 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 05:00:20 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 05:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00:21 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 05:00:21 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 05:00:21 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 05:00:21 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 05:00:21 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 05:00:21 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 05:00:22 INFO - Downloading docopt-0.6.1.tar.gz 05:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:22 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:22 INFO - Running setup.py install for blobuploader 05:00:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:22 INFO - Running setup.py install for requests 05:00:22 INFO - Running setup.py install for docopt 05:00:23 INFO - Successfully installed blobuploader requests docopt 05:00:23 INFO - Cleaning up... 05:00:23 INFO - Return code: 0 05:00:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:23 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:23 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 05:00:23 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 05:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:23 INFO - 'HOME': '/Users/cltbld', 05:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:23 INFO - 'LOGNAME': 'cltbld', 05:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:23 INFO - 'MOZ_NO_REMOTE': '1', 05:00:23 INFO - 'NO_EM_RESTART': '1', 05:00:23 INFO - 'PAGER': '/bin/cat', 05:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:23 INFO - 'PWD': '/builds/slave/test', 05:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:23 INFO - 'SHELL': '/bin/bash', 05:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:23 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:23 INFO - 'USER': 'cltbld', 05:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:23 INFO - 'XPC_FLAGS': '0x0', 05:00:23 INFO - 'XPC_SERVICE_NAME': '0', 05:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:23 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Ts0Fsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:23 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-ymEaGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:23 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-TwKW7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:23 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-UDx_d6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:23 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-GZNyIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:23 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-dNP1mO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-H__j12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-iqTZsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Mg8wAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-zFQ_E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-MefQt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-0ygV57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-sLWQs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-XPbn0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:24 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-LIxZ_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:25 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-tYXYA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:25 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-X8hwkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:25 INFO - Running setup.py install for manifestparser 05:00:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for mozcrash 05:00:25 INFO - Running setup.py install for mozdebug 05:00:25 INFO - Running setup.py install for mozdevice 05:00:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for mozfile 05:00:25 INFO - Running setup.py install for mozhttpd 05:00:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for mozinfo 05:00:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for mozInstall 05:00:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for mozleak 05:00:26 INFO - Running setup.py install for mozlog 05:00:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for moznetwork 05:00:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for mozprocess 05:00:27 INFO - Running setup.py install for mozprofile 05:00:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:27 INFO - Running setup.py install for mozrunner 05:00:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:27 INFO - Running setup.py install for mozscreenshot 05:00:27 INFO - Running setup.py install for moztest 05:00:27 INFO - Running setup.py install for mozversion 05:00:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:27 INFO - Cleaning up... 05:00:27 INFO - Return code: 0 05:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:27 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:27 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 05:00:27 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 05:00:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:27 INFO - 'HOME': '/Users/cltbld', 05:00:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:27 INFO - 'LOGNAME': 'cltbld', 05:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:27 INFO - 'MOZ_NO_REMOTE': '1', 05:00:27 INFO - 'NO_EM_RESTART': '1', 05:00:27 INFO - 'PAGER': '/bin/cat', 05:00:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:27 INFO - 'PWD': '/builds/slave/test', 05:00:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:27 INFO - 'SHELL': '/bin/bash', 05:00:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:27 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:27 INFO - 'USER': 'cltbld', 05:00:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:27 INFO - 'XPC_FLAGS': '0x0', 05:00:27 INFO - 'XPC_SERVICE_NAME': '0', 05:00:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-1guJSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-1VGqC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-csuz2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-mDlon1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-PEiDr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-GQeUA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-whLPIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-b4Ouyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:28 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-NKQ0sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-UMgH9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-0BGKgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-3B_K3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-SpqcgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-CGyUW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-qPzylb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Zjq6EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:29 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)) 05:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:29 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Lvhm3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:29 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)) 05:00:29 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)) 05:00:29 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)) 05:00:29 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)) 05:00:29 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)) 05:00:29 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)) 05:00:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:32 INFO - Downloading blessings-1.6.tar.gz 05:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:32 INFO - Installing collected packages: blessings 05:00:32 INFO - Running setup.py install for blessings 05:00:32 INFO - Successfully installed blessings 05:00:32 INFO - Cleaning up... 05:00:33 INFO - Return code: 0 05:00:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:33 INFO - Reading from file tmpfile_stdout 05:00:33 INFO - Current package versions: 05:00:33 INFO - blessings == 1.6 05:00:33 INFO - blobuploader == 1.2.4 05:00:33 INFO - docopt == 0.6.1 05:00:33 INFO - functools32 == 3.2.3-2 05:00:33 INFO - jsonschema == 2.5.1 05:00:33 INFO - manifestparser == 1.1 05:00:33 INFO - mozInstall == 1.12 05:00:33 INFO - mozcrash == 0.17 05:00:33 INFO - mozdebug == 0.1 05:00:33 INFO - mozdevice == 0.48 05:00:33 INFO - mozfile == 1.2 05:00:33 INFO - mozhttpd == 0.7 05:00:33 INFO - mozinfo == 0.9 05:00:33 INFO - mozleak == 0.1 05:00:33 INFO - mozlog == 3.3 05:00:33 INFO - moznetwork == 0.27 05:00:33 INFO - mozprocess == 0.23 05:00:33 INFO - mozprofile == 0.28 05:00:33 INFO - mozrunner == 6.12 05:00:33 INFO - mozscreenshot == 0.1 05:00:33 INFO - mozsystemmonitor == 0.3 05:00:33 INFO - moztest == 0.7 05:00:33 INFO - mozversion == 1.4 05:00:33 INFO - psutil == 3.1.1 05:00:33 INFO - requests == 1.2.3 05:00:33 INFO - wsgiref == 0.1.2 05:00:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:33 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 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00:33 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 05:00:33 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 05:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:33 INFO - 'HOME': '/Users/cltbld', 05:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:33 INFO - 'LOGNAME': 'cltbld', 05:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:33 INFO - 'MOZ_NO_REMOTE': '1', 05:00:33 INFO - 'NO_EM_RESTART': '1', 05:00:33 INFO - 'PAGER': '/bin/cat', 05:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:33 INFO - 'PWD': '/builds/slave/test', 05:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:33 INFO - 'SHELL': '/bin/bash', 05:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:33 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:33 INFO - 'USER': 'cltbld', 05:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:33 INFO - 'XPC_FLAGS': '0x0', 05:00:33 INFO - 'XPC_SERVICE_NAME': '0', 05:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 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)) 05:00:33 INFO - Cleaning up... 05:00:33 INFO - Return code: 0 05:00:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1087f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108886618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fff00d9f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, '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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 05:00: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'] in /builds/slave/test/build 05:00: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 05:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:33 INFO - 'HOME': '/Users/cltbld', 05:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:33 INFO - 'LOGNAME': 'cltbld', 05:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:33 INFO - 'MOZ_NO_REMOTE': '1', 05:00:33 INFO - 'NO_EM_RESTART': '1', 05:00:33 INFO - 'PAGER': '/bin/cat', 05:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:33 INFO - 'PWD': '/builds/slave/test', 05:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:33 INFO - 'SHELL': '/bin/bash', 05:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:00:33 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:33 INFO - 'USER': 'cltbld', 05:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:33 INFO - 'XPC_FLAGS': '0x0', 05:00:33 INFO - 'XPC_SERVICE_NAME': '0', 05:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:00:34 INFO - Cleaning up... 05:00:34 INFO - Return code: 0 05:00:34 INFO - Running post-action listener: _resource_record_post_action 05:00:34 INFO - Running post-action listener: _start_resource_monitoring 05:00:34 INFO - Starting resource monitoring. 05:00:34 INFO - [mozharness: 2016-09-02 12:00:34.230216Z] Finished create-virtualenv step (success) 05:00:34 INFO - [mozharness: 2016-09-02 12:00:34.231123Z] Running install step. 05:00:34 INFO - Running pre-action listener: _resource_record_pre_action 05:00:34 INFO - Running main action method: install 05:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:34 INFO - Reading from file tmpfile_stdout 05:00:34 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:34 INFO - Reading from file tmpfile_stdout 05:00:34 INFO - Output received: 05:00:34 INFO - Usage: mozinstall [options] installer 05:00:34 INFO - Options: 05:00:34 INFO - -h, --help show this help message and exit 05:00:34 INFO - -d DEST, --destination=DEST 05:00:34 INFO - Directory to install application into. [default: 05:00:34 INFO - "/builds/slave/test"] 05:00:34 INFO - --app=APP Application being installed. [default: firefox] 05:00:34 INFO - mkdir: /builds/slave/test/build/application 05:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:01:08 INFO - Reading from file tmpfile_stdout 05:01:08 INFO - Output received: 05:01:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:01:08 INFO - Running post-action listener: _resource_record_post_action 05:01:08 INFO - [mozharness: 2016-09-02 12:01:08.683806Z] Finished install step (success) 05:01:08 INFO - [mozharness: 2016-09-02 12:01:08.683954Z] Running run-tests step. 05:01:08 INFO - Running pre-action listener: _resource_record_pre_action 05:01:08 INFO - Running main action method: run_tests 05:01:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:08 INFO - 2016-09-02 05:01:08.698 screenresolution[1641:7496] starting screenresolution argv=screenresolution get 05:01:08 INFO - 2016-09-02 05:01:08.701 screenresolution[1641:7496] Display 0: 1600x1200x32@60 05:01:08 INFO - 2016-09-02 05:01:08.707 screenresolution[1642:7498] starting screenresolution argv=screenresolution list 05:01:08 INFO - Available Modes on Display 0 05:01:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:08 INFO - Intel Iris: 05:01:08 INFO - Chipset Model: Intel Iris 05:01:08 INFO - Type: GPU 05:01:08 INFO - Bus: Built-In 05:01:08 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:08 INFO - Vendor: Intel (0x8086) 05:01:08 INFO - Device ID: 0x0a2e 05:01:08 INFO - Revision ID: 0x0009 05:01:08 INFO - Displays: 05:01:08 INFO - Display: 05:01:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:08 INFO - Display Serial Number: 1600x1200 60 05:01:08 INFO - Main Display: Yes 05:01:08 INFO - Mirror: Off 05:01:08 INFO - Online: Yes 05:01:08 INFO - Rotation: Supported 05:01:08 INFO - Return code: 0 05:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:01:08 INFO - Python 2.7.3 05:01:08 INFO - Return code: 0 05:01:08 INFO - grabbing minidump binary from tooltool 05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:08 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 0x108755d30>, '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 0x1084badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10874db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:01:08 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 05:01:08 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 05:01:08 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 05:01:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:01:09 INFO - Return code: 0 05:01:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:01:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:01:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:01:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:01:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:01:09 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/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0242', '--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 05:01:09 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/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0242 --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 05:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pIhkcGEwQP/Render', 05:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:09 INFO - 'HOME': '/Users/cltbld', 05:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:09 INFO - 'LOGNAME': 'cltbld', 05:01:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:01:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:09 INFO - 'MOZ_NO_REMOTE': '1', 05:01:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:01:09 INFO - 'NO_EM_RESTART': '1', 05:01:09 INFO - 'PAGER': '/bin/cat', 05:01:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:09 INFO - 'PWD': '/builds/slave/test', 05:01:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:09 INFO - 'SHELL': '/bin/bash', 05:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NOxkqcQdMg/Listeners', 05:01:09 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 05:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:09 INFO - 'USER': 'cltbld', 05:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:09 INFO - 'XPC_FLAGS': '0x0', 05:01:09 INFO - 'XPC_SERVICE_NAME': '0', 05:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:09 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/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0242', '--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 05:01:09 INFO - mozversion application_buildid: 20160902025442 05:01:09 INFO - mozversion application_changeset: 884f60cd86d31b32ec4883246ace726bc93e445b 05:01:09 INFO - mozversion application_display_name: Nightly 05:01:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:01:09 INFO - mozversion application_name: Firefox 05:01:09 INFO - mozversion application_remotingname: firefox 05:01:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:01:09 INFO - mozversion application_vendor: Mozilla 05:01:09 INFO - mozversion application_version: 51.0a1 05:01:09 INFO - mozversion platform_buildid: 20160902025442 05:01:09 INFO - mozversion platform_changeset: 884f60cd86d31b32ec4883246ace726bc93e445b 05:01:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:01:09 INFO - mozversion platform_version: 51.0a1 05:01:09 INFO - using testdate: 1472817669 05:01:09 INFO - actual date: 1472817669 05:01:09 INFO - starting webserver on 'localhost:49266' 05:01:09 INFO - SUITE-START | Running 2 tests 05:01:09 INFO - TEST-START | dromaeo_css 05:01:09 INFO - operating with platform_type : mac_ 05:01:09 INFO - Initialising browser for dromaeo_css test... 05:01:09 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmphdcCpp/profile http://localhost:49266/getInfo.html) 05:01:11 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:01:11 INFO - PROCESS | 1652 | colorDepth:24 05:01:11 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:01:11 INFO - PROCESS | 1652 | __metrics 05:01:11 INFO - PROCESS | 1652 | 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" 05:01:11 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:01:12 INFO - TEST-INFO | 1652: exit 0 05:01:12 INFO - Browser initialized. 05:01:12 INFO - Running cycle 1/1 for dromaeo_css test... 05:01:12 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmphdcCpp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:24 INFO - PROCESS | 1654 | RSS: Main: 234143744 05:01:24 INFO - PROCESS | 1654 | 05:02:15 INFO - PROCESS | 1654 | 1472817735897 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] 05:04:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:04:29 INFO - PROCESS | 1654 | RSS: Main: 401973248 05:04:29 INFO - PROCESS | 1654 | 05:07:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:07:33 INFO - PROCESS | 1654 | RSS: Main: 372150272 05:07:33 INFO - PROCESS | 1654 | 05:10:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:10:36 INFO - PROCESS | 1654 | RSS: Main: 333766656 05:10:36 INFO - PROCESS | 1654 | 05:13:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:13:39 INFO - PROCESS | 1654 | RSS: Main: 345284608 05:13:39 INFO - PROCESS | 1654 | 05:16:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:16:43 INFO - PROCESS | 1654 | RSS: Main: 330956800 05:16:43 INFO - PROCESS | 1654 | 05:19:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:19:47 INFO - PROCESS | 1654 | RSS: Main: 540340224 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | __start_tp_report 05:19:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:19:47 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:19:47 INFO - PROCESS | 1654 | |i|pagename|runs| 05:19:47 INFO - PROCESS | 1654 | |0;dojo.html;3114.330418959923;3322.833858307084;3345.933081338373;3375.9831200844023;3420.828958552072;4723.85828425147;5581.49766521013;5661.179129026289;5735.741891614877;5768.913466298004;4714.9764251178785;4850.781714822832;5566.832995010146;5763.423657634235;5810.506106980901;5144.588432925357;5148.3564554430695;5243.921341179866;5262.368515778112;5393.056215162351;5312.840614781543;5342.866428339284;5366.087765079927;5367.007103685811;5367.114426119686;5174.948250517522;5208.609354298443;5244.5279924806955;5289.709066001349;5299.231611416323;5214.843554693365;5216.947830521684;5353.973230133864;5369.785208591652;5378.327709036371;5228.79084836606;5240.109181439165;5249.212618107276;5271.499207575274;5298.735063246822;5643.633163844337;5791.015527360359;5791.913121303184;5805.5065319447895;5831.475167234969;6155.7688462307715;6191.907121393183;6240.812775616739;6258.3428739982555;6307.400796924285;6282;6337.2395312562785;6349.238091429058;6376.0754690570075;6377.936220637781;5719.685417302088;5884.852878678067;5891.911621325684;5908.497777688859;5909.734061967221;6059.424354686386;6236.158118654014;6277.623342599459;6306.590071645328;6314.778982735582;6209.565330426827;6224.284207316126;6232.719527621268;6242.09489624002;6253.405926436982;6147.907781383283;6230.875382492325;6307.936920630735;6328.746850125944;6339.397757213057;6174.5060395168275;6227.657478838707;6236.345183755731;6246.6876656167005;6311.33730958252;6290.150829638032;6298.02380631003;6318.494520438446;6319.842003949846;6324.747010119544;4936.308916751657;5008.198688209869;5011.961603748303;5012.724300163453;5086.364204474441;4448.755318457451;4518.164139634178;4542.682012259176;4560.087982403533;4566.246569316026;5721.713914304268;5751.511121554715;5751.8562035949435;5769.278840144982;5862.325832529228;6281.59169653971;6281.623102613858;6312.242530896322;6314.55798094129;6331.366863313724;4873.756312184377;5757.251557297524;5769.134629805479;5789.392113828072;5800.7099645018425;5387.595930305243;5438.972805135949;5476.479734425223;5489.725513724298;5540.916886246629;6349.206349206349;6359.650219237987;6362.936370636419;6364;6377.904331435032;6267.6866156692895;6274.403931626488;6322.683865806784;6338.90491642635;6361;6235.594686345373;6239.812805615838;6241.3134555197985;6293.811185664437;6344.809655710152;6241.282252541016;6248.593841400295;6260.7495700171485;6265.122882796503;6284;6311.27420346666;6315.5263355247625;6321.841953951188;6331.841703957438;6345.555811093179;5846.123081537633;5965.34381218057;6086.147939288415;6117.3576774439825;6163.291221509584;6071.878562428815;6139.2325959255095;6144.078388241799;6167.5682702210415;6216;6053.975200583417;6054;6054.939450605438;6083.057126145454;6098.115773212948;6125.9693701531205;6168.599041062316;6193.721282542207;6218;6220.160278362363;5854.590178687451;5915.231019967377;5952.732127054206;6031.6667499126615;6036.517078633787 05:19:47 INFO - PROCESS | 1654 | |1;ext.html;510.5048103339759;525.4115390762346;530.2417542913632;532.5499952540104;541.9729013549324;31408.52887206691;41496;42895.57104428959;42983.78508107455;44289.7785511072;43967.78016109923;44208.778956105256;44349.7782511087;44359.55640443595;44411.55588444114;8767.298616110727;9597.424154550714;9777.071178238057;9777.364471309342;9783;8766.736997890073;11974.700632484171;12280.447379867883;12287.754244915097;12455.688607784787;12056.27662340258;12118;12178.634640960785;12199.14605977582;12220.144589878711;12062.57780977666;12109.091818113677;12118.272903625722;12141.757164856741;12142.178673199003;12051.517939282418;12118.697032574168;12122.939385303105;12131.696707582292;12131.878681213164;41303.38044929328;41548.792256038825;41579.7921010396;41736.79131604353;41748.79125604353;3350.8659653613827;3376.6623337666283;3490.89527314181;3550.218951830593;3789.621037896216;31554.862917154765;31580.52629210564;31922;31958;31958.360832783215;3132.8276944768027;3318.070940136766;3416.4021296273054;3416.4362880125;3416.8120753358558;487789;488470;490484.99999999645;491474;492711.5364423191;57960.710196448745;58333;58336.291326497994;58439.70780146072;58483;66055.66972165108;66108.66945665241;66125.66937165284;66178;66207;16683.33266669344;16966;16976.236069376908;16984.32062717502;17032.829671703374;11063.170262230366;11276.830847537294;12773.872261277269;12996.480140794263;13060.34698265083;18085.36701215451;18626.90686546586;18631.484749558636;18639.720404193948;18645.067746612887;5793.594448388572;5899.380565040695;6472.9029064564065;7005.54463959841;7027.43780497559;889.5374405309203;911.284641556375;956.1012648110892;983.3804703037041;1084.837274408845;30216;30276.394472110434;30693.23266918345;30720.663392129463;31056.84471577673;955.5699935029264;1031.5564307347763;1039.5893622019412;1090.6237348114887;1124.3591153042687;13317.600471985856;15277.388904443698;15819.52541423759;15965.882388232945;16175.110368929587;630441;632843;633977;636055;908123;58082.41917580854;58978.820423592144;58997;62797.68601156965;62884;1318.4660212613908;1348.4606157536869;1367.063561460383;1372.457879137755;1375.4910683047337;30518.084457466313;31312.843435782674;31698;31985.8400707995;32253;50227.74886125546;50580.24129637985;50732.23901641552;50753;50771.238431424295;551162;554402;554423;554566;555434;23159;26946;27176.592351115145;27936.720632794226;39984.4002339971;47766.761166193945;48506.810690600505;48656.51343486662;49032.50967490422;49227.26159107688;39828;40247;40674;41158.382624261256;41431.585684142774;39533.2093358137;39675;40081.79754607366;41107.58892411038;41627.167456650095;36689.44965825461;38701.84015957057;39249.21501569895;39584.60415395809;39830.402543962446;34314.82842585771;34483.4827477583;35284.47073293851;35356.64643353534;35357.11607209737;25041.499170016134;25686.743132569183;25753.613695794953;25823;26562.46875062449 05:19:47 INFO - PROCESS | 1654 | |2;jquery.html;344.65018006723164;363.2553265805099;363.7944561322854;375.69568649393995;397.0609508512367;15204.543863684094;16523.50429487116;17744;20304.898475507638;20425.69361459579;16809.495715128567;19671.606567868635;21109.944502774877;21149.894250528727;21319.467013324636;5175.87060323493;5779.7977070802635;6011.428914253139;6025.246844144482;6038.879222415549;5860.208871802295;5938.9703051484685;5971.910421343683;5994.730237139339;6008.48927841134;5635.126555383922;5889.411058894119;5921.792737254197;5944.583879128462;5978.953683105439;5931.466168044875;5985.850353741148;5986.491148252404;5986.8503287417725;5989.191459153024;5783.103618939072;5798.275215598051;6035.275766907956;6087.269527656666;6089.908651370233;13119.212847229197;13326.267055311953;13380.063395562314;23982.640260396107;25062.24813255605;2112.482441801753;2125.6067627488965;2174.7825217478285;2363.3500787283465;2394.7964423024064;9735.485675470474;10185.694429167062;10428.687139385755;12454.750904981849;15268.541943741706;1831.706926891691;1843.6312737452563;1870.4061510268218;1930.0349825087455;1943.9708404373946;113636.4318178412;124939.12591311138;128012.35993820158;128082;128804.35597822044;9328.673496427593;11546.997780576943;11582.305061696325;11600.145662790612;11749.647510574696;14515.564533064025;14583.270836458134;14585.927070364793;14594.63513412152;14614.561563153122;8142.552159631158;8396.412251142483;8441.535715535581;8574.742757717278;8576.228139467477;8312.044114926739;8331;8337.833243335099;8485.0666426693;8514.31885449175;4410.25025745624;4494.258447356149;4516.751578663205;4603.953960460352;4613.884652883705;1608.348618809384;1862.32956135791;1900.6198760248005;1914.6745053340687;1949.2203118752611;860.7503823891002;934.9298802589833;938.4134915677702;957.9952100239454;967.3712087143273;21595.784042159376;22066.558668826532;22140.675229101034;22197.889010554845;22240.999155037738;752.9209433009455;868.5266529741306;876.6975393489236;904.204300215819;905.063259526387;9190.770230744285;11405.258658187191;11476.827847582126;11540.884591154148;11595;581389;586495;595436;600613;605939;86646;127850.72149278388;135205;138112.30943845215;143421.28289358487;945.7541039329686;952.5427794658464;954.4750387286964;979.8775153105862;981.7153025622489;19399.709004365228;19951.159830198518;20049;20121.89939050354;20146.79853201449;52185;54263.69650865927;54299.72850135882;54389.45610543844;55222.723886380314;70618;71235.64382178264;72275.27724722897;72380;74385.25614743783;6906.102206713095;7628.940186149597;7806.804829879071;8018.198180181935;8027.5584842834205;10488.947555262175;12461;12538.122331436713;12837.358132093172;12852.293123878277;10433.478326083818;10829.729256768644;11219.046381057702;11232.3260604364;11373.260738052;10528.684139475828;10687.786244275227;10744.140468762324;11066.336019838835;11083.390413527011;9681.128698417177;10379.325343852626;10808.351498910091;10896.782064358827;11333.433328333715;6901.861962760817;7033.577985320897;7287.890681639674;7410.4442166836625;7506.3994880408345;10500.52747626374;10661.307015043998;10920.2901811382;11093.500792464198;11339.036181924628 05:19:47 INFO - PROCESS | 1654 | |3;mootools.html;1558.501279590531;1558.953231403058;1793.2378739035908;1912.5601111744306;1932.1401976120624;7705.113911900133;8005.999250093738;8198.1801819818;8613.22480976712;8619.224269815715;8199.262066414021;8432.28325592324;8478;8597.828043439127;8607.827843443127;2337.041812856725;2360.586897292967;2368.3368656776042;2371.5019845832394;2375.774301441369;2095.161935225914;2172.706684597583;2309.3764683535437;2316.8957396917094;2344.202970989863;2333.055112679359;2357.634566642173;2362.456634973957;2363.326451961191;2366.988165059181;2325.3024092772102;2328.115316179858;2330.778576035274;2333.346662934381;2346.3312955807514;2302.8963696633523;2320.512692334612;2323.0359400848797;2339.6490526421003;2341.0869760793303;8464.66141354345;8637.568121593895;8659.09079546651;8676.392652514327;8681.001684806202;5091.567216786578;5222.138347172712;5229.450907654717;5232;5234.9476505235225;3402.6937575618313;3561.394562924309;3617.981910090459;3618.6924111450558;3622.510961020255;1041.7031146123395;1071.5820829876277;1074.2211896375143;1078.8813230544633;1102.2945314998408;11147.554097836077;11153.609623663133;11190.160737944687;11204.719882002933;11256.831147532794;4300;4531.524189960072;4573.016801387717;4616.446026476844;4623.052274283764;9644.855327169958;9646.90353096474;9654.227661787181;9670.613175472903;9670.806583868283;5174.120399532013;5180.378354597472;5181.4041385240425;5198.142306519458;5206.323177986837;4586.564276393738;4740.383750112533;4757.476677565465;4768.3085952536685;4775.450823155312;3770.905727356838;3776.074861658856;3812.14226798968;3860.034991252187;3872.593377695358;1156.8785277545906;1184.4018770521004;1225.40567824605;1229.9815502767465;1249.5126900508808;840.6595328891808;859.1751918158637;883.4743327719997;894.7673604862782;914.4878867834034;5526.723663816793;5784.248047753765;5870.295564532198;5889.469947704727;5932.139839723302;928.1090153452626;932.3706498113746;933.8179055084329;935.2891802230218;980.9019098090134;2941.676415594283;3590.4434812603567;3899.122697393064;3917.5690674025836;3990.2418540477215;10099.293049486465;10389.94805025985;10408.427536485415;10439.895601043892;10479.004494573004;10392.896071039344;10402.947985260025;10408.115310198567;10417.687469375931;10449.9477502612;7845.37237021037;7977.880331795028;8031.277185053374;8039.919600803917;8105.432619716681;5439.4288599696465;5678.971605141948;5746.827595172152;5747.396523364986;5782.479576837936;9477.194438472807;9663.806723865342;9697.224222062077;9750.317477776487;9861.260405469475;9461.526923653651;9864.260180486348;9941.155001824925;9983.251256155963;10014.198864091;5960.731767070579;6927.134108236471;6966.651667416711;6984.4063254623925;6986.615736134561;7996.5601891894175;8107.959460202661;8120.187981201832;8130.520191341698;8132.796680083045;6221.9377806223165;7269.127704675473;7287.12554493443;7444.516106453063;7476.514026588255;7250.528715633467;7270.890936635848;7285.562866228043;7324.670389832364;7324.743633972786;6533.379328963741;6728.73085076611;6803.727850886107;6804.863902722017;6813.148356455443;4560.315952607136;4566.178087944202;4590.288505281686;4678.157931572214;4742.976285118552;4843.806247750192;4858.3441235432765;4966.453690094159;5004.2493625956395;5141.665791723526 05:19:47 INFO - PROCESS | 1654 | |4;prototype.html;297.40964186090594;297.7335284919996;305.0923502579825;307.79993004547026;310.19813781375143;4014.3376342903384;4123.83504659814;5443.891122177559;5464.398916119224;5508.338999320077;4616.584507394334;5198.740062996854;5387.919181212285;5398.16328469087;5402.405735369106;3454.740894432915;4149.7302675326155;4153.584641535853;4160.583941605831;4192.413062171291;3448.8620455181763;3573.8206391890744;3575.1777091269023;4093.16090201508;4208.621224089831;3777.1312598102318;4048.5951404859575;4066.4547471960614;4093.263212621727;4100.610442008019;3692.48305237267;4071.145059537501;4095.795210239476;4109.732867363612;4110.301248787713;2949.4396064747843;3497;3511.613722490524;3516.595591506984;3534.8586056557706;4071.3689378146137;4554.859216168428;4698.694584851974;5316.893662126775;5361.624686271963;4042.43405923171;4609.516000819933;4614.085154660812;4645.86062418128;4659.976700116477;5015;5707.686077265748;5734.913976290359;5742.511886489653;5744.798932037401;3502.9841346009625;3697.815109244527;3701.79691600231;3835.4055121456217;3838.098046958962;5950.523958083343;5959.225300710923;5978.611390259619;5979.910301345483;5983.222181116427;3273.8035717857006;3750.0999760057935;3875.399313106473;3906.570277269498;3920.725549211528;3175.9364812703616;3528.523649307362;3541.9551232386507;3917.8852812014516;3937.7243592948794;3906.5974425727973;4093.959060409358;4427.424434823517;4578.008566273682;4597.287420449836;5678.602497825195;5681.744321505542;5710.200571919934;5710.229119068956;5730.484256416942;1251.5932321995206;1292.6251387097825;1295.7019885426216;1300.0834411739702;1318.6901078246603;829.9128591497806;841.0748177005368;880.41892351048;895.9964839379936;897.6319708919311;4664.113818374498;5281.656692314988;5310.628256022042;5311.575073994071;5402.945970540323;5023.522765337287;5640.5463623323985;5835.533157347487;5853.64878107315;5913.46778789911;4601.976990115095;5459.099248623933;5476.096444086681;5498.972505137449;5532.786885245853;2882.8126171798767;2975.4792911240447;3053.3435311408152;3091.4280858041334;3127.561321791956;5876.118582212703;5979.730912108966;6057.42454466825;6092.908606370908;6097.817065488042;5336.493033161843;6004.939950600526;6041.9395806041375;6050.848728781815;6051.213342265477;5727.828165155135;6032.426919442646;6055.455009049207;6062.696865156725;6086.908696369558;5293.920591191212;5494.478024587658;5592.245046918777;5784.124636493475;5918.73365698536;5450.591205659671;5822.70886455667;5864.9413505864395;5874.500667443315;5889.204957330706;5316.6544174628525;5863.912041319469;5965.433283838029;6044.304904935989;6078.574499784972;3471.9305613888087;3518.2787528556073;3803.087259057751;3860.359347277452;3879.262940041383;5928.407159284209;5948.107783832286;5963.211036688889;5991.64050156992;6003.009503431885;4796.904061918812;5372.355317361941;5706.572007099401;5731.971340143273;5758.769649213985;5385.946140538545;5623.381428042994;5703.914441283301;5756.194132821324;5798.449147330997;5185.533302002837;5404.811161414627;5476.972615137058;5595.446891224184;5621.971890140523;5712.6001179917;5724.656520608777;5741.942580574308;5751.568632352674;5819.77784665225;5402.28741919443;5510.559155267489;5573.136163894603;5634.859128521656;5683.516901063456 05:19:47 INFO - PROCESS | 1654 | |5;yui.html;691.3397705191543;698.2598445647612;703.1246098607234;717.7595505505656;722.656738049427;15266.847331526682;15679.529614111567;15684.137372444511;15862.365505379785;15872.12703301318;14005.92997035016;14272.857271427283;14409.423623055092;14443.711125777505;14459.349329280181;690.4821383962028;837.3092198935886;854.2525290370926;859.3769517100084;859.9011113721908;696.1785093589563;836.188896770132;852.6120615120105;870.1037930931119;871.7341210930664;903.7559858838109;905.1355955062899;909.1136142261308;909.7680091576639;911.3119594705988;701.4108149154735;898.6315610599655;901.0088902207585;902.3457992955088;905.7735566108473;650.3366566102549;661.0216879019031;668.3583759590799;668.5353679192963;671.3521451798989;14614.123152610877;14655.780163297559;14657.926710366486;14658.120512769268;14658.853411465961;13098.345082745825;13325.466981320735;13411.798823017663;13505.594832155051;13612.31938403076;12328.137030407875;12424.192427492184;12430.37848107591;12452.937735311356;12957.481700732054;4353.651707863363;4362.1275744851155;4443.089166720814;4550.544945505456;4568.703034302793;102459.48770256176;104394.47802761014;104778.95221047768;106232;106366;10641.574337026432;10647.14822814173;10652.201084918663;10653.57385704563;11018.944905275423;18485.53786155357;20162.193512259346;20615.38153855387;20781.37655870296;20790.1683932641;7505.812354691177;7615.390768738488;7730.961345193238;7754.495957762728;7812.1797211292;4186.413902053715;4266.296061149876;4309;4330.307150855848;4479.55204479556;2688.193541937411;3253.1216571525556;3264.298175892147;3273.2471531547876;3280.7703460757994;613.098744845077;629.5655997361806;641.1888960465016;644.1658052821607;653.1574269192688;540.5837505121035;570.6005795942857;612.8039027511154;620.385818040146;629.3926361061598;8336.333093352518;8508;8547.95726021366;8626.654933802703;8776.254018408506;600.9729562169713;603.2459425717852;625.6465097220056;633.4204203154062;642.2581917884851;3488.720902327808;4006.318925782624;4150.169966006811;4171.478565179353;4318.460192475941;118586;125771.3711431437;127459;128194;131912;109205.45397272962;109801.45099274452;110933;113972;114105;80692.59653701811;83366.58316708499;99820.50089749505;99853.00146998583;103081;7936.2857342839425;7973.641186146521;7977.401694872791;8156.143604921398;8266.917330826614;57851.13223301738;58951;59726.402735973825;59867;60821.39178608157;81061.78407323777;83256;85237;85500;86182;10390.844137338097;10633.361998280128;10731.731706707395;10915.508802104083;11189.384583847966;9961.701148965543;11547.942260288644;11738.647840564796;12204.450799713857;12420.937895310466;9223.723288301362;11319.151063670093;11440.370779607201;11641.53433862636;11774.293542387484;11700.239484433485;11777.293362398283;11777.764444710887;12027.518899243933;12281.815772763595;11177.385243811668;11363.090952723596;11554.422278885853;11667;11711.355875426592;9389.154976052188;9395.248380129437;9721.805563888824;9833.655822046194;10243.94878025605;7172.713091476283;8493.57532123379;8592.914070859211;9275.21160701348;9393.624255029723 05:19:47 INFO - PROCESS | 1654 | __end_tp_report 05:19:47 INFO - PROCESS | 1654 | __start_cc_report 05:19:47 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1462 05:19:47 INFO - PROCESS | 1654 | __end_cc_report 05:19:47 INFO - PROCESS | 1654 | __startTimestamp1472818787088__endTimestamp 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:19:47 INFO - PROCESS | 1654 | Number of tests: 6 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5777.52 Median:6053.98 stddev:660.68 (10.9%) stddev-sans-first:628.81 05:19:47 INFO - PROCESS | 1654 | Values: 3114.3 3322.8 3345.9 3376.0 3420.8 4723.9 5581.5 5661.2 5735.7 5768.9 4715.0 4850.8 5566.8 5763.4 5810.5 5144.6 5148.4 5243.9 5262.4 5393.1 5312.8 5342.9 5366.1 5367.0 5367.1 5174.9 5208.6 5244.5 5289.7 5299.2 5214.8 5216.9 5354.0 5369.8 5378.3 5228.8 5240.1 5249.2 5271.5 5298.7 5643.6 5791.0 5791.9 5805.5 5831.5 6155.8 6191.9 6240.8 6258.3 6307.4 6282.0 6337.2 6349.2 6376.1 6377.9 5719.7 5884.9 5891.9 5908.5 5909.7 6059.4 6236.2 6277.6 6306.6 6314.8 6209.6 6224.3 6232.7 6242.1 6253.4 6147.9 6230.9 6307.9 6328.7 6339.4 6174.5 6227.7 6236.3 6246.7 6311.3 6290.2 6298.0 6318.5 6319.8 6324.7 4936.3 5008.2 5012.0 5012.7 5086.4 4448.8 4518.2 4542.7 4560.1 4566.2 5721.7 5751.5 5751.9 5769.3 5862.3 6281.6 6281.6 6312.2 6314.6 6331.4 4873.8 5757.3 5769.1 5789.4 5800.7 5387.6 5439.0 5476.5 5489.7 5540.9 6349.2 6359.7 6362.9 6364.0 6377.9 6267.7 6274.4 6322.7 6338.9 6361.0 6235.6 6239.8 6241.3 6293.8 6344.8 6241.3 6248.6 6260.7 6265.1 6284.0 6311.3 6315.5 6321.8 6331.8 6345.6 5846.1 5965.3 6086.1 6117.4 6163.3 6071.9 6139.2 6144.1 6167.6 6216.0 6054.0 6054.0 6054.9 6083.1 6098.1 6126.0 6168.6 6193.7 6218.0 6220.2 5854.6 5915.2 5952.7 6031.7 6036.5 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:71784.29 Median:38975.53 stddev:156915.09 (402.6%) stddev-sans-first:157264.03 05:19:47 INFO - PROCESS | 1654 | Values: 510.5 525.4 530.2 532.5 542.0 31408.5 41496.0 42895.6 42983.8 44289.8 43967.8 44208.8 44349.8 44359.6 44411.6 8767.3 9597.4 9777.1 9777.4 9783.0 8766.7 11974.7 12280.4 12287.8 12455.7 12056.3 12118.0 12178.6 12199.1 12220.1 12062.6 12109.1 12118.3 12141.8 12142.2 12051.5 12118.7 12122.9 12131.7 12131.9 41303.4 41548.8 41579.8 41736.8 41748.8 3350.9 3376.7 3490.9 3550.2 3789.6 31554.9 31580.5 31922.0 31958.0 31958.4 3132.8 3318.1 3416.4 3416.4 3416.8 487789.0 488470.0 490485.0 491474.0 492711.5 57960.7 58333.0 58336.3 58439.7 58483.0 66055.7 66108.7 66125.7 66178.0 66207.0 16683.3 16966.0 16976.2 16984.3 17032.8 11063.2 11276.8 12773.9 12996.5 13060.3 18085.4 18626.9 18631.5 18639.7 18645.1 5793.6 5899.4 6472.9 7005.5 7027.4 889.5 911.3 956.1 983.4 1084.8 30216.0 30276.4 30693.2 30720.7 31056.8 955.6 1031.6 1039.6 1090.6 1124.4 13317.6 15277.4 15819.5 15965.9 16175.1 630441.0 632843.0 633977.0 636055.0 908123.0 58082.4 58978.8 58997.0 62797.7 62884.0 1318.5 1348.5 1367.1 1372.5 1375.5 30518.1 31312.8 31698.0 31985.8 32253.0 50227.7 50580.2 50732.2 50753.0 50771.2 551162.0 554402.0 554423.0 554566.0 555434.0 23159.0 26946.0 27176.6 27936.7 39984.4 47766.8 48506.8 48656.5 49032.5 49227.3 39828.0 40247.0 40674.0 41158.4 41431.6 39533.2 39675.0 40081.8 41107.6 41627.2 36689.4 38701.8 39249.2 39584.6 39830.4 34314.8 34483.5 35284.5 35356.6 35357.1 25041.5 25686.7 25753.6 25823.0 26562.5 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34663.52 Median:353.95 stddev:99348.52 (28068.3%) stddev-sans-first:99593.80 05:19:47 INFO - PROCESS | 1654 | Values: 344.7 363.3 363.8 375.7 397.1 15204.5 16523.5 17744.0 20304.9 20425.7 16809.5 19671.6 21109.9 21149.9 21319.5 5175.9 5779.8 6011.4 6025.2 6038.9 5860.2 5939.0 5971.9 5994.7 6008.5 5635.1 5889.4 5921.8 5944.6 5979.0 5931.5 5985.9 5986.5 5986.9 5989.2 5783.1 5798.3 6035.3 6087.3 6089.9 13119.2 13326.3 13380.1 23982.6 25062.2 2112.5 2125.6 2174.8 2363.4 2394.8 9735.5 10185.7 10428.7 12454.8 15268.5 1831.7 1843.6 1870.4 1930.0 1944.0 113636.4 124939.1 128012.4 128082.0 128804.4 9328.7 11547.0 11582.3 11600.1 11749.6 14515.6 14583.3 14585.9 14594.6 14614.6 8142.6 8396.4 8441.5 8574.7 8576.2 8312.0 8331.0 8337.8 8485.1 8514.3 4410.3 4494.3 4516.8 4604.0 4613.9 1608.3 1862.3 1900.6 1914.7 1949.2 860.8 934.9 938.4 958.0 967.4 21595.8 22066.6 22140.7 22197.9 22241.0 752.9 868.5 876.7 904.2 905.1 9190.8 11405.3 11476.8 11540.9 11595.0 581389.0 586495.0 595436.0 600613.0 605939.0 86646.0 127850.7 135205.0 138112.3 143421.3 945.8 952.5 954.5 979.9 981.7 19399.7 19951.2 20049.0 20121.9 20146.8 52185.0 54263.7 54299.7 54389.5 55222.7 70618.0 71235.6 72275.3 72380.0 74385.3 6906.1 7628.9 7806.8 8018.2 8027.6 10488.9 12461.0 12538.1 12837.4 12852.3 10433.5 10829.7 11219.0 11232.3 11373.3 10528.7 10687.8 10744.1 11066.3 11083.4 9681.1 10379.3 10808.4 10896.8 11333.4 6901.9 7033.6 7287.9 7410.4 7506.4 10500.5 10661.3 10920.3 11093.5 11339.0 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5548.88 Median:5116.62 stddev:3082.80 (60.3%) stddev-sans-first:3076.87 05:19:47 INFO - PROCESS | 1654 | Values: 1558.5 1559.0 1793.2 1912.6 1932.1 7705.1 8006.0 8198.2 8613.2 8619.2 8199.3 8432.3 8478.0 8597.8 8607.8 2337.0 2360.6 2368.3 2371.5 2375.8 2095.2 2172.7 2309.4 2316.9 2344.2 2333.1 2357.6 2362.5 2363.3 2367.0 2325.3 2328.1 2330.8 2333.3 2346.3 2302.9 2320.5 2323.0 2339.6 2341.1 8464.7 8637.6 8659.1 8676.4 8681.0 5091.6 5222.1 5229.5 5232.0 5234.9 3402.7 3561.4 3618.0 3618.7 3622.5 1041.7 1071.6 1074.2 1078.9 1102.3 11147.6 11153.6 11190.2 11204.7 11256.8 4300.0 4531.5 4573.0 4616.4 4623.1 9644.9 9646.9 9654.2 9670.6 9670.8 5174.1 5180.4 5181.4 5198.1 5206.3 4586.6 4740.4 4757.5 4768.3 4775.5 3770.9 3776.1 3812.1 3860.0 3872.6 1156.9 1184.4 1225.4 1230.0 1249.5 840.7 859.2 883.5 894.8 914.5 5526.7 5784.2 5870.3 5889.5 5932.1 928.1 932.4 933.8 935.3 980.9 2941.7 3590.4 3899.1 3917.6 3990.2 10099.3 10389.9 10408.4 10439.9 10479.0 10392.9 10402.9 10408.1 10417.7 10449.9 7845.4 7977.9 8031.3 8039.9 8105.4 5439.4 5679.0 5746.8 5747.4 5782.5 9477.2 9663.8 9697.2 9750.3 9861.3 9461.5 9864.3 9941.2 9983.3 10014.2 5960.7 6927.1 6966.7 6984.4 6986.6 7996.6 8108.0 8120.2 8130.5 8132.8 6221.9 7269.1 7287.1 7444.5 7476.5 7250.5 7270.9 7285.6 7324.7 7324.7 6533.4 6728.7 6803.7 6804.9 6813.1 4560.3 4566.2 4590.3 4678.2 4743.0 4843.8 4858.3 4966.5 5004.2 5141.7 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4603.03 Median:5366.99 stddev:1470.54 (27.4%) stddev-sans-first:1438.71 05:19:47 INFO - PROCESS | 1654 | Values: 297.4 297.7 305.1 307.8 310.2 4014.3 4123.8 5443.9 5464.4 5508.3 4616.6 5198.7 5387.9 5398.2 5402.4 3454.7 4149.7 4153.6 4160.6 4192.4 3448.9 3573.8 3575.2 4093.2 4208.6 3777.1 4048.6 4066.5 4093.3 4100.6 3692.5 4071.1 4095.8 4109.7 4110.3 2949.4 3497.0 3511.6 3516.6 3534.9 4071.4 4554.9 4698.7 5316.9 5361.6 4042.4 4609.5 4614.1 4645.9 4660.0 5015.0 5707.7 5734.9 5742.5 5744.8 3503.0 3697.8 3701.8 3835.4 3838.1 5950.5 5959.2 5978.6 5979.9 5983.2 3273.8 3750.1 3875.4 3906.6 3920.7 3175.9 3528.5 3542.0 3917.9 3937.7 3906.6 4094.0 4427.4 4578.0 4597.3 5678.6 5681.7 5710.2 5710.2 5730.5 1251.6 1292.6 1295.7 1300.1 1318.7 829.9 841.1 880.4 896.0 897.6 4664.1 5281.7 5310.6 5311.6 5402.9 5023.5 5640.5 5835.5 5853.6 5913.5 4602.0 5459.1 5476.1 5499.0 5532.8 2882.8 2975.5 3053.3 3091.4 3127.6 5876.1 5979.7 6057.4 6092.9 6097.8 5336.5 6004.9 6041.9 6050.8 6051.2 5727.8 6032.4 6055.5 6062.7 6086.9 5293.9 5494.5 5592.2 5784.1 5918.7 5450.6 5822.7 5864.9 5874.5 5889.2 5316.7 5863.9 5965.4 6044.3 6078.6 3471.9 3518.3 3803.1 3860.4 3879.3 5928.4 5948.1 5963.2 5991.6 6003.0 4796.9 5372.4 5706.6 5732.0 5758.8 5385.9 5623.4 5703.9 5756.2 5798.4 5185.5 5404.8 5477.0 5595.4 5622.0 5712.6 5724.7 5741.9 5751.6 5819.8 5402.3 5510.6 5573.1 5634.9 5683.5 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:22315.42 Median:4402.61 stddev:34885.22 (792.4%) stddev-sans-first:34945.30 05:19:47 INFO - PROCESS | 1654 | Values: 691.3 698.3 703.1 717.8 722.7 15266.8 15679.5 15684.1 15862.4 15872.1 14005.9 14272.9 14409.4 14443.7 14459.3 690.5 837.3 854.3 859.4 859.9 696.2 836.2 852.6 870.1 871.7 903.8 905.1 909.1 909.8 911.3 701.4 898.6 901.0 902.3 905.8 650.3 661.0 668.4 668.5 671.4 14614.1 14655.8 14657.9 14658.1 14658.9 13098.3 13325.5 13411.8 13505.6 13612.3 12328.1 12424.2 12430.4 12452.9 12957.5 4353.7 4362.1 4443.1 4550.5 4568.7 102459.5 104394.5 104779.0 106232.0 106366.0 10641.6 10647.1 10652.2 10653.6 11018.9 18485.5 20162.2 20615.4 20781.4 20790.2 7505.8 7615.4 7731.0 7754.5 7812.2 4186.4 4266.3 4309.0 4330.3 4479.6 2688.2 3253.1 3264.3 3273.2 3280.8 613.1 629.6 641.2 644.2 653.2 540.6 570.6 612.8 620.4 629.4 8336.3 8508.0 8548.0 8626.7 8776.3 601.0 603.2 625.6 633.4 642.3 3488.7 4006.3 4150.2 4171.5 4318.5 118586.0 125771.4 127459.0 128194.0 131912.0 109205.5 109801.5 110933.0 113972.0 114105.0 80692.6 83366.6 99820.5 99853.0 103081.0 7936.3 7973.6 7977.4 8156.1 8266.9 57851.1 58951.0 59726.4 59867.0 60821.4 81061.8 83256.0 85237.0 85500.0 86182.0 10390.8 10633.4 10731.7 10915.5 11189.4 9961.7 11547.9 11738.6 12204.5 12420.9 9223.7 11319.2 11440.4 11641.5 11774.3 11700.2 11777.3 11777.8 12027.5 12281.8 11177.4 11363.1 11554.4 11667.0 11711.4 9389.2 9395.2 9721.8 9833.7 10243.9 7172.7 8493.6 8592.9 9275.2 9393.6 05:19:47 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:19:47 INFO - PROCESS | 1654 | 05:19:47 INFO - TEST-INFO | 1654: exit 0 05:19:47 INFO - TEST-OK | dromaeo_css | took 1118449ms 05:19:47 INFO - TEST-START | kraken 05:19:47 INFO - operating with platform_type : mac_ 05:19:47 INFO - Initialising browser for kraken test... 05:19:47 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpXmZZ_j/profile http://localhost:49266/getInfo.html) 05:19:49 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:19:49 INFO - PROCESS | 1663 | colorDepth:24 05:19:49 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:19:49 INFO - PROCESS | 1663 | __metrics 05:19:50 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:19:50 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:19:50 INFO - TEST-INFO | 1663: exit 0 05:19:50 INFO - Browser initialized. 05:19:50 INFO - Running cycle 1/1 for kraken test... 05:19:50 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpXmZZ_j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:02 INFO - PROCESS | 1664 | RSS: Main: 234139648 05:20:02 INFO - PROCESS | 1664 | 05:20:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:20:06 INFO - PROCESS | 1664 | RSS: Main: 292315136 05:20:06 INFO - PROCESS | 1664 | 05:20:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:20:09 INFO - PROCESS | 1664 | RSS: Main: 288772096 05:20:09 INFO - PROCESS | 1664 | 05:20:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:20:13 INFO - PROCESS | 1664 | RSS: Main: 379600896 05:20:13 INFO - PROCESS | 1664 | 05:20:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:20:16 INFO - PROCESS | 1664 | RSS: Main: 280985600 05:20:16 INFO - PROCESS | 1664 | 05:20:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:20:19 INFO - PROCESS | 1664 | RSS: Main: 283783168 05:20:19 INFO - PROCESS | 1664 | 05:20:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:20:22 INFO - PROCESS | 1664 | RSS: Main: 324075520 05:20:22 INFO - PROCESS | 1664 | 05:20:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:20:25 INFO - PROCESS | 1664 | RSS: Main: 325484544 05:20:25 INFO - PROCESS | 1664 | 05:20:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:20:29 INFO - PROCESS | 1664 | RSS: Main: 325095424 05:20:29 INFO - PROCESS | 1664 | 05:20:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:31 INFO - PROCESS | 1664 | RSS: Main: 298745856 05:20:31 INFO - PROCESS | 1664 | 05:20:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:33 INFO - PROCESS | 1664 | RSS: Main: 304304128 05:20:33 INFO - PROCESS | 1664 | 05:20:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:20:36 INFO - PROCESS | 1664 | RSS: Main: 305152000 05:20:36 INFO - PROCESS | 1664 | 05:20:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:40 INFO - PROCESS | 1664 | RSS: Main: 320806912 05:20:40 INFO - PROCESS | 1664 | 05:20:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:43 INFO - PROCESS | 1664 | RSS: Main: 317022208 05:20:43 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:20:45 INFO - PROCESS | 1664 | RSS: Main: 361054208 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | __start_tp_report 05:20:45 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 05:20:45 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 05:20:45 INFO - PROCESS | 1664 | |i|pagename|runs| 05:20:45 INFO - PROCESS | 1664 | |0;ai-astar;113;113;116;112;118;162;167;114;112;124 05:20:45 INFO - PROCESS | 1664 | |1;audio-beat-detection;180;140;134;162;107;130;124;108;160;107 05:20:45 INFO - PROCESS | 1664 | |2;audio-dft;184;182;182;183;184;182;183;179;182;178 05:20:45 INFO - PROCESS | 1664 | |3;audio-fft;76;73;76;153;58;58;58;60;144;58 05:20:45 INFO - PROCESS | 1664 | |4;audio-oscillator;82;83;137;72;72;80;124;69;70;117 05:20:45 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;86;84;87;83;87;84;97;131;88;85 05:20:45 INFO - PROCESS | 1664 | |6;imaging-darkroom;96;97;97;98;95;96;96;97;96;96 05:20:45 INFO - PROCESS | 1664 | |7;imaging-desaturate;87;84;85;86;86;86;83;85;85;84 05:20:45 INFO - PROCESS | 1664 | |8;json-parse-financial;58;56;58;56;57;58;56;55;55;55 05:20:45 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;67;73;76;72;71;69;71;70;72 05:20:45 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;85;88;85;88;84;88;88;85;89;97 05:20:45 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;139;139;139;138;137;138;137;181;138 05:20:45 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;169;169;167;171;169;169;170;174;234;196 05:20:45 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;68;68;68;68;68;66;67;67;67 05:20:45 INFO - PROCESS | 1664 | __end_tp_report 05:20:45 INFO - PROCESS | 1664 | __start_cc_report 05:20:45 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2073 05:20:45 INFO - PROCESS | 1664 | __end_cc_report 05:20:45 INFO - PROCESS | 1664 | __startTimestamp1472818845912__endTimestamp 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | ------- Summary: start ------- 05:20:45 INFO - PROCESS | 1664 | Number of tests: 14 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:125.10 Median:117.00 stddev:21.11 (18.0%) stddev-sans-first:21.93 05:20:45 INFO - PROCESS | 1664 | Values: 113.0 113.0 116.0 112.0 118.0 162.0 167.0 114.0 112.0 124.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:137.00 stddev:25.49 (18.6%) stddev-sans-first:21.26 05:20:45 INFO - PROCESS | 1664 | Values: 180.0 140.0 134.0 162.0 107.0 130.0 124.0 108.0 160.0 107.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:181.90 Median:182.50 stddev:1.97 (1.1%) stddev-sans-first:1.94 05:20:45 INFO - PROCESS | 1664 | Values: 184.0 182.0 182.0 183.0 184.0 182.0 183.0 179.0 182.0 178.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:81.40 Median:59.00 stddev:36.24 (61.4%) stddev-sans-first:38.39 05:20:45 INFO - PROCESS | 1664 | Values: 76.0 73.0 76.0 153.0 58.0 58.0 58.0 60.0 144.0 58.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:72.00 stddev:25.37 (35.2%) stddev-sans-first:26.72 05:20:45 INFO - PROCESS | 1664 | Values: 82.0 83.0 137.0 72.0 72.0 80.0 124.0 69.0 70.0 117.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:86.50 stddev:14.53 (16.8%) stddev-sans-first:15.29 05:20:45 INFO - PROCESS | 1664 | Values: 86.0 84.0 87.0 83.0 87.0 84.0 97.0 131.0 88.0 85.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 05:20:45 INFO - PROCESS | 1664 | Values: 96.0 97.0 97.0 98.0 95.0 96.0 96.0 97.0 96.0 96.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.20 (1.4%) stddev-sans-first:1.05 05:20:45 INFO - PROCESS | 1664 | Values: 87.0 84.0 85.0 86.0 86.0 86.0 83.0 85.0 85.0 84.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.50 stddev:1.26 (2.2%) stddev-sans-first:1.20 05:20:45 INFO - PROCESS | 1664 | Values: 58.0 56.0 58.0 56.0 57.0 58.0 56.0 55.0 55.0 55.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.50 stddev:3.10 (4.3%) stddev-sans-first:2.54 05:20:45 INFO - PROCESS | 1664 | Values: 65.0 67.0 73.0 76.0 72.0 71.0 69.0 71.0 70.0 72.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.00 stddev:3.71 (4.2%) stddev-sans-first:3.81 05:20:45 INFO - PROCESS | 1664 | Values: 85.0 88.0 85.0 88.0 84.0 88.0 88.0 85.0 89.0 97.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:138.50 stddev:13.62 (9.8%) stddev-sans-first:14.31 05:20:45 INFO - PROCESS | 1664 | Values: 137.0 139.0 139.0 139.0 138.0 137.0 138.0 137.0 181.0 138.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:170.50 stddev:21.15 (12.4%) stddev-sans-first:22.14 05:20:45 INFO - PROCESS | 1664 | Values: 169.0 169.0 167.0 171.0 169.0 169.0 170.0 174.0 234.0 196.0 05:20:45 INFO - PROCESS | 1664 | 05:20:45 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 05:20:45 INFO - PROCESS | 1664 | Values: 67.0 68.0 68.0 68.0 68.0 68.0 66.0 67.0 67.0 67.0 05:20:45 INFO - PROCESS | 1664 | -------- Summary: end -------- 05:20:45 INFO - PROCESS | 1664 | 05:20:46 INFO - TEST-INFO | 1664: exit 0 05:20:46 INFO - TEST-OK | kraken | took 59007ms 05:20:46 INFO - SUITE-END | took 1177s 05:20:47 INFO - Completed test suite (00:19:37) 05:20:47 INFO - javascript benchmark 05:20:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.330418959923, 3322.833858307084, 3345.933081338373, 3375.9831200844023, 3420.828958552072, 4723.85828425147, 5581.49766521013, 5661.179129026289, 5735.741891614877, 5768.913466298004, 4714.9764251178785, 4850.781714822832, 5566.832995010146, 5763.423657634235, 5810.506106980901, 5144.588432925357, 5148.3564554430695, 5243.921341179866, 5262.368515778112, 5393.056215162351, 5312.840614781543, 5342.866428339284, 5366.087765079927, 5367.007103685811, 5367.114426119686, 5174.948250517522, 5208.609354298443, 5244.5279924806955, 5289.709066001349, 5299.231611416323, 5214.843554693365, 5216.947830521684, 5353.973230133864, 5369.785208591652, 5378.327709036371, 5228.79084836606, 5240.109181439165, 5249.212618107276, 5271.499207575274, 5298.735063246822, 5643.633163844337, 5791.015527360359, 5791.913121303184, 5805.5065319447895, 5831.475167234969, 6155.7688462307715, 6191.907121393183, 6240.812775616739, 6258.3428739982555, 6307.400796924285, 6282.0, 6337.2395312562785, 6349.238091429058, 6376.0754690570075, 6377.936220637781, 5719.685417302088, 5884.852878678067, 5891.911621325684, 5908.497777688859, 5909.734061967221, 6059.424354686386, 6236.158118654014, 6277.623342599459, 6306.590071645328, 6314.778982735582, 6209.565330426827, 6224.284207316126, 6232.719527621268, 6242.09489624002, 6253.405926436982, 6147.907781383283, 6230.875382492325, 6307.936920630735, 6328.746850125944, 6339.397757213057, 6174.5060395168275, 6227.657478838707, 6236.345183755731, 6246.6876656167005, 6311.33730958252, 6290.150829638032, 6298.02380631003, 6318.494520438446, 6319.842003949846, 6324.747010119544, 4936.308916751657, 5008.198688209869, 5011.961603748303, 5012.724300163453, 5086.364204474441, 4448.755318457451, 4518.164139634178, 4542.682012259176, 4560.087982403533, 4566.246569316026, 5721.713914304268, 5751.511121554715, 5751.8562035949435, 5769.278840144982, 5862.325832529228, 6281.59169653971, 6281.623102613858, 6312.242530896322, 6314.55798094129, 6331.366863313724, 4873.756312184377, 5757.251557297524, 5769.134629805479, 5789.392113828072, 5800.7099645018425, 5387.595930305243, 5438.972805135949, 5476.479734425223, 5489.725513724298, 5540.916886246629, 6349.206349206349, 6359.650219237987, 6362.936370636419, 6364.0, 6377.904331435032, 6267.6866156692895, 6274.403931626488, 6322.683865806784, 6338.90491642635, 6361.0, 6235.594686345373, 6239.812805615838, 6241.3134555197985, 6293.811185664437, 6344.809655710152, 6241.282252541016, 6248.593841400295, 6260.7495700171485, 6265.122882796503, 6284.0, 6311.27420346666, 6315.5263355247625, 6321.841953951188, 6331.841703957438, 6345.555811093179, 5846.123081537633, 5965.34381218057, 6086.147939288415, 6117.3576774439825, 6163.291221509584, 6071.878562428815, 6139.2325959255095, 6144.078388241799, 6167.5682702210415, 6216.0, 6053.975200583417, 6054.0, 6054.939450605438, 6083.057126145454, 6098.115773212948, 6125.9693701531205, 6168.599041062316, 6193.721282542207, 6218.0, 6220.160278362363, 5854.590178687451, 5915.231019967377, 5952.732127054206, 6031.6667499126615, 6036.517078633787], "value": 5734.40706576188, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.5048103339759, 525.4115390762346, 530.2417542913632, 532.5499952540104, 541.9729013549324, 31408.52887206691, 41496.0, 42895.57104428959, 42983.78508107455, 44289.7785511072, 43967.78016109923, 44208.778956105256, 44349.7782511087, 44359.55640443595, 44411.55588444114, 8767.298616110727, 9597.424154550714, 9777.071178238057, 9777.364471309342, 9783.0, 8766.736997890073, 11974.700632484171, 12280.447379867883, 12287.754244915097, 12455.688607784787, 12056.27662340258, 12118.0, 12178.634640960785, 12199.14605977582, 12220.144589878711, 12062.57780977666, 12109.091818113677, 12118.272903625722, 12141.757164856741, 12142.178673199003, 12051.517939282418, 12118.697032574168, 12122.939385303105, 12131.696707582292, 12131.878681213164, 41303.38044929328, 41548.792256038825, 41579.7921010396, 41736.79131604353, 41748.79125604353, 3350.8659653613827, 3376.6623337666283, 3490.89527314181, 3550.218951830593, 3789.621037896216, 31554.862917154765, 31580.52629210564, 31922.0, 31958.0, 31958.360832783215, 3132.8276944768027, 3318.070940136766, 3416.4021296273054, 3416.4362880125, 3416.8120753358558, 487789.0, 488470.0, 490484.99999999645, 491474.0, 492711.5364423191, 57960.710196448745, 58333.0, 58336.291326497994, 58439.70780146072, 58483.0, 66055.66972165108, 66108.66945665241, 66125.66937165284, 66178.0, 66207.0, 16683.33266669344, 16966.0, 16976.236069376908, 16984.32062717502, 17032.829671703374, 11063.170262230366, 11276.830847537294, 12773.872261277269, 12996.480140794263, 13060.34698265083, 18085.36701215451, 18626.90686546586, 18631.484749558636, 18639.720404193948, 18645.067746612887, 5793.594448388572, 5899.380565040695, 6472.9029064564065, 7005.54463959841, 7027.43780497559, 889.5374405309203, 911.284641556375, 956.1012648110892, 983.3804703037041, 1084.837274408845, 30216.0, 30276.394472110434, 30693.23266918345, 30720.663392129463, 31056.84471577673, 955.5699935029264, 1031.5564307347763, 1039.5893622019412, 1090.6237348114887, 1124.3591153042687, 13317.600471985856, 15277.388904443698, 15819.52541423759, 15965.882388232945, 16175.110368929587, 630441.0, 632843.0, 633977.0, 636055.0, 908123.0, 58082.41917580854, 58978.820423592144, 58997.0, 62797.68601156965, 62884.0, 1318.4660212613908, 1348.4606157536869, 1367.063561460383, 1372.457879137755, 1375.4910683047337, 30518.084457466313, 31312.843435782674, 31698.0, 31985.8400707995, 32253.0, 50227.74886125546, 50580.24129637985, 50732.23901641552, 50753.0, 50771.238431424295, 551162.0, 554402.0, 554423.0, 554566.0, 555434.0, 23159.0, 26946.0, 27176.592351115145, 27936.720632794226, 39984.4002339971, 47766.761166193945, 48506.810690600505, 48656.51343486662, 49032.50967490422, 49227.26159107688, 39828.0, 40247.0, 40674.0, 41158.382624261256, 41431.585684142774, 39533.2093358137, 39675.0, 40081.79754607366, 41107.58892411038, 41627.167456650095, 36689.44965825461, 38701.84015957057, 39249.21501569895, 39584.60415395809, 39830.402543962446, 34314.82842585771, 34483.4827477583, 35284.47073293851, 35356.64643353534, 35357.11607209737, 25041.499170016134, 25686.743132569183, 25753.613695794953, 25823.0, 26562.46875062449], "value": 20753.905226600862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.65018006723164, 363.2553265805099, 363.7944561322854, 375.69568649393995, 397.0609508512367, 15204.543863684094, 16523.50429487116, 17744.0, 20304.898475507638, 20425.69361459579, 16809.495715128567, 19671.606567868635, 21109.944502774877, 21149.894250528727, 21319.467013324636, 5175.87060323493, 5779.7977070802635, 6011.428914253139, 6025.246844144482, 6038.879222415549, 5860.208871802295, 5938.9703051484685, 5971.910421343683, 5994.730237139339, 6008.48927841134, 5635.126555383922, 5889.411058894119, 5921.792737254197, 5944.583879128462, 5978.953683105439, 5931.466168044875, 5985.850353741148, 5986.491148252404, 5986.8503287417725, 5989.191459153024, 5783.103618939072, 5798.275215598051, 6035.275766907956, 6087.269527656666, 6089.908651370233, 13119.212847229197, 13326.267055311953, 13380.063395562314, 23982.640260396107, 25062.24813255605, 2112.482441801753, 2125.6067627488965, 2174.7825217478285, 2363.3500787283465, 2394.7964423024064, 9735.485675470474, 10185.694429167062, 10428.687139385755, 12454.750904981849, 15268.541943741706, 1831.706926891691, 1843.6312737452563, 1870.4061510268218, 1930.0349825087455, 1943.9708404373946, 113636.4318178412, 124939.12591311138, 128012.35993820158, 128082.0, 128804.35597822044, 9328.673496427593, 11546.997780576943, 11582.305061696325, 11600.145662790612, 11749.647510574696, 14515.564533064025, 14583.270836458134, 14585.927070364793, 14594.63513412152, 14614.561563153122, 8142.552159631158, 8396.412251142483, 8441.535715535581, 8574.742757717278, 8576.228139467477, 8312.044114926739, 8331.0, 8337.833243335099, 8485.0666426693, 8514.31885449175, 4410.25025745624, 4494.258447356149, 4516.751578663205, 4603.953960460352, 4613.884652883705, 1608.348618809384, 1862.32956135791, 1900.6198760248005, 1914.6745053340687, 1949.2203118752611, 860.7503823891002, 934.9298802589833, 938.4134915677702, 957.9952100239454, 967.3712087143273, 21595.784042159376, 22066.558668826532, 22140.675229101034, 22197.889010554845, 22240.999155037738, 752.9209433009455, 868.5266529741306, 876.6975393489236, 904.204300215819, 905.063259526387, 9190.770230744285, 11405.258658187191, 11476.827847582126, 11540.884591154148, 11595.0, 581389.0, 586495.0, 595436.0, 600613.0, 605939.0, 86646.0, 127850.72149278388, 135205.0, 138112.30943845215, 143421.28289358487, 945.7541039329686, 952.5427794658464, 954.4750387286964, 979.8775153105862, 981.7153025622489, 19399.709004365228, 19951.159830198518, 20049.0, 20121.89939050354, 20146.79853201449, 52185.0, 54263.69650865927, 54299.72850135882, 54389.45610543844, 55222.723886380314, 70618.0, 71235.64382178264, 72275.27724722897, 72380.0, 74385.25614743783, 6906.102206713095, 7628.940186149597, 7806.804829879071, 8018.198180181935, 8027.5584842834205, 10488.947555262175, 12461.0, 12538.122331436713, 12837.358132093172, 12852.293123878277, 10433.478326083818, 10829.729256768644, 11219.046381057702, 11232.3260604364, 11373.260738052, 10528.684139475828, 10687.786244275227, 10744.140468762324, 11066.336019838835, 11083.390413527011, 9681.128698417177, 10379.325343852626, 10808.351498910091, 10896.782064358827, 11333.433328333715, 6901.861962760817, 7033.577985320897, 7287.890681639674, 7410.4442166836625, 7506.3994880408345, 10500.52747626374, 10661.307015043998, 10920.2901811382, 11093.500792464198, 11339.036181924628], "value": 9390.795276844918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1558.501279590531, 1558.953231403058, 1793.2378739035908, 1912.5601111744306, 1932.1401976120624, 7705.113911900133, 8005.999250093738, 8198.1801819818, 8613.22480976712, 8619.224269815715, 8199.262066414021, 8432.28325592324, 8478.0, 8597.828043439127, 8607.827843443127, 2337.041812856725, 2360.586897292967, 2368.3368656776042, 2371.5019845832394, 2375.774301441369, 2095.161935225914, 2172.706684597583, 2309.3764683535437, 2316.8957396917094, 2344.202970989863, 2333.055112679359, 2357.634566642173, 2362.456634973957, 2363.326451961191, 2366.988165059181, 2325.3024092772102, 2328.115316179858, 2330.778576035274, 2333.346662934381, 2346.3312955807514, 2302.8963696633523, 2320.512692334612, 2323.0359400848797, 2339.6490526421003, 2341.0869760793303, 8464.66141354345, 8637.568121593895, 8659.09079546651, 8676.392652514327, 8681.001684806202, 5091.567216786578, 5222.138347172712, 5229.450907654717, 5232.0, 5234.9476505235225, 3402.6937575618313, 3561.394562924309, 3617.981910090459, 3618.6924111450558, 3622.510961020255, 1041.7031146123395, 1071.5820829876277, 1074.2211896375143, 1078.8813230544633, 1102.2945314998408, 11147.554097836077, 11153.609623663133, 11190.160737944687, 11204.719882002933, 11256.831147532794, 4300.0, 4531.524189960072, 4573.016801387717, 4616.446026476844, 4623.052274283764, 9644.855327169958, 9646.90353096474, 9654.227661787181, 9670.613175472903, 9670.806583868283, 5174.120399532013, 5180.378354597472, 5181.4041385240425, 5198.142306519458, 5206.323177986837, 4586.564276393738, 4740.383750112533, 4757.476677565465, 4768.3085952536685, 4775.450823155312, 3770.905727356838, 3776.074861658856, 3812.14226798968, 3860.034991252187, 3872.593377695358, 1156.8785277545906, 1184.4018770521004, 1225.40567824605, 1229.9815502767465, 1249.5126900508808, 840.6595328891808, 859.1751918158637, 883.4743327719997, 894.7673604862782, 914.4878867834034, 5526.723663816793, 5784.248047753765, 5870.295564532198, 5889.469947704727, 5932.139839723302, 928.1090153452626, 932.3706498113746, 933.8179055084329, 935.2891802230218, 980.9019098090134, 2941.676415594283, 3590.4434812603567, 3899.122697393064, 3917.5690674025836, 3990.2418540477215, 10099.293049486465, 10389.94805025985, 10408.427536485415, 10439.895601043892, 10479.004494573004, 10392.896071039344, 10402.947985260025, 10408.115310198567, 10417.687469375931, 10449.9477502612, 7845.37237021037, 7977.880331795028, 8031.277185053374, 8039.919600803917, 8105.432619716681, 5439.4288599696465, 5678.971605141948, 5746.827595172152, 5747.396523364986, 5782.479576837936, 9477.194438472807, 9663.806723865342, 9697.224222062077, 9750.317477776487, 9861.260405469475, 9461.526923653651, 9864.260180486348, 9941.155001824925, 9983.251256155963, 10014.198864091, 5960.731767070579, 6927.134108236471, 6966.651667416711, 6984.4063254623925, 6986.615736134561, 7996.5601891894175, 8107.959460202661, 8120.187981201832, 8130.520191341698, 8132.796680083045, 6221.9377806223165, 7269.127704675473, 7287.12554493443, 7444.516106453063, 7476.514026588255, 7250.528715633467, 7270.890936635848, 7285.562866228043, 7324.670389832364, 7324.743633972786, 6533.379328963741, 6728.73085076611, 6803.727850886107, 6804.863902722017, 6813.148356455443, 4560.315952607136, 4566.178087944202, 4590.288505281686, 4678.157931572214, 4742.976285118552, 4843.806247750192, 4858.3441235432765, 4966.453690094159, 5004.2493625956395, 5141.665791723526], "value": 4483.517667035035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.40964186090594, 297.7335284919996, 305.0923502579825, 307.79993004547026, 310.19813781375143, 4014.3376342903384, 4123.83504659814, 5443.891122177559, 5464.398916119224, 5508.338999320077, 4616.584507394334, 5198.740062996854, 5387.919181212285, 5398.16328469087, 5402.405735369106, 3454.740894432915, 4149.7302675326155, 4153.584641535853, 4160.583941605831, 4192.413062171291, 3448.8620455181763, 3573.8206391890744, 3575.1777091269023, 4093.16090201508, 4208.621224089831, 3777.1312598102318, 4048.5951404859575, 4066.4547471960614, 4093.263212621727, 4100.610442008019, 3692.48305237267, 4071.145059537501, 4095.795210239476, 4109.732867363612, 4110.301248787713, 2949.4396064747843, 3497.0, 3511.613722490524, 3516.595591506984, 3534.8586056557706, 4071.3689378146137, 4554.859216168428, 4698.694584851974, 5316.893662126775, 5361.624686271963, 4042.43405923171, 4609.516000819933, 4614.085154660812, 4645.86062418128, 4659.976700116477, 5015.0, 5707.686077265748, 5734.913976290359, 5742.511886489653, 5744.798932037401, 3502.9841346009625, 3697.815109244527, 3701.79691600231, 3835.4055121456217, 3838.098046958962, 5950.523958083343, 5959.225300710923, 5978.611390259619, 5979.910301345483, 5983.222181116427, 3273.8035717857006, 3750.0999760057935, 3875.399313106473, 3906.570277269498, 3920.725549211528, 3175.9364812703616, 3528.523649307362, 3541.9551232386507, 3917.8852812014516, 3937.7243592948794, 3906.5974425727973, 4093.959060409358, 4427.424434823517, 4578.008566273682, 4597.287420449836, 5678.602497825195, 5681.744321505542, 5710.200571919934, 5710.229119068956, 5730.484256416942, 1251.5932321995206, 1292.6251387097825, 1295.7019885426216, 1300.0834411739702, 1318.6901078246603, 829.9128591497806, 841.0748177005368, 880.41892351048, 895.9964839379936, 897.6319708919311, 4664.113818374498, 5281.656692314988, 5310.628256022042, 5311.575073994071, 5402.945970540323, 5023.522765337287, 5640.5463623323985, 5835.533157347487, 5853.64878107315, 5913.46778789911, 4601.976990115095, 5459.099248623933, 5476.096444086681, 5498.972505137449, 5532.786885245853, 2882.8126171798767, 2975.4792911240447, 3053.3435311408152, 3091.4280858041334, 3127.561321791956, 5876.118582212703, 5979.730912108966, 6057.42454466825, 6092.908606370908, 6097.817065488042, 5336.493033161843, 6004.939950600526, 6041.9395806041375, 6050.848728781815, 6051.213342265477, 5727.828165155135, 6032.426919442646, 6055.455009049207, 6062.696865156725, 6086.908696369558, 5293.920591191212, 5494.478024587658, 5592.245046918777, 5784.124636493475, 5918.73365698536, 5450.591205659671, 5822.70886455667, 5864.9413505864395, 5874.500667443315, 5889.204957330706, 5316.6544174628525, 5863.912041319469, 5965.433283838029, 6044.304904935989, 6078.574499784972, 3471.9305613888087, 3518.2787528556073, 3803.087259057751, 3860.359347277452, 3879.262940041383, 5928.407159284209, 5948.107783832286, 5963.211036688889, 5991.64050156992, 6003.009503431885, 4796.904061918812, 5372.355317361941, 5706.572007099401, 5731.971340143273, 5758.769649213985, 5385.946140538545, 5623.381428042994, 5703.914441283301, 5756.194132821324, 5798.449147330997, 5185.533302002837, 5404.811161414627, 5476.972615137058, 5595.446891224184, 5621.971890140523, 5712.6001179917, 5724.656520608777, 5741.942580574308, 5751.568632352674, 5819.77784665225, 5402.28741919443, 5510.559155267489, 5573.136163894603, 5634.859128521656, 5683.516901063456], "value": 4129.872247775446, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.3397705191543, 698.2598445647612, 703.1246098607234, 717.7595505505656, 722.656738049427, 15266.847331526682, 15679.529614111567, 15684.137372444511, 15862.365505379785, 15872.12703301318, 14005.92997035016, 14272.857271427283, 14409.423623055092, 14443.711125777505, 14459.349329280181, 690.4821383962028, 837.3092198935886, 854.2525290370926, 859.3769517100084, 859.9011113721908, 696.1785093589563, 836.188896770132, 852.6120615120105, 870.1037930931119, 871.7341210930664, 903.7559858838109, 905.1355955062899, 909.1136142261308, 909.7680091576639, 911.3119594705988, 701.4108149154735, 898.6315610599655, 901.0088902207585, 902.3457992955088, 905.7735566108473, 650.3366566102549, 661.0216879019031, 668.3583759590799, 668.5353679192963, 671.3521451798989, 14614.123152610877, 14655.780163297559, 14657.926710366486, 14658.120512769268, 14658.853411465961, 13098.345082745825, 13325.466981320735, 13411.798823017663, 13505.594832155051, 13612.31938403076, 12328.137030407875, 12424.192427492184, 12430.37848107591, 12452.937735311356, 12957.481700732054, 4353.651707863363, 4362.1275744851155, 4443.089166720814, 4550.544945505456, 4568.703034302793, 102459.48770256176, 104394.47802761014, 104778.95221047768, 106232.0, 106366.0, 10641.574337026432, 10647.14822814173, 10652.201084918663, 10653.57385704563, 11018.944905275423, 18485.53786155357, 20162.193512259346, 20615.38153855387, 20781.37655870296, 20790.1683932641, 7505.812354691177, 7615.390768738488, 7730.961345193238, 7754.495957762728, 7812.1797211292, 4186.413902053715, 4266.296061149876, 4309.0, 4330.307150855848, 4479.55204479556, 2688.193541937411, 3253.1216571525556, 3264.298175892147, 3273.2471531547876, 3280.7703460757994, 613.098744845077, 629.5655997361806, 641.1888960465016, 644.1658052821607, 653.1574269192688, 540.5837505121035, 570.6005795942857, 612.8039027511154, 620.385818040146, 629.3926361061598, 8336.333093352518, 8508.0, 8547.95726021366, 8626.654933802703, 8776.254018408506, 600.9729562169713, 603.2459425717852, 625.6465097220056, 633.4204203154062, 642.2581917884851, 3488.720902327808, 4006.318925782624, 4150.169966006811, 4171.478565179353, 4318.460192475941, 118586.0, 125771.3711431437, 127459.0, 128194.0, 131912.0, 109205.45397272962, 109801.45099274452, 110933.0, 113972.0, 114105.0, 80692.59653701811, 83366.58316708499, 99820.50089749505, 99853.00146998583, 103081.0, 7936.2857342839425, 7973.641186146521, 7977.401694872791, 8156.143604921398, 8266.917330826614, 57851.13223301738, 58951.0, 59726.402735973825, 59867.0, 60821.39178608157, 81061.78407323777, 83256.0, 85237.0, 85500.0, 86182.0, 10390.844137338097, 10633.361998280128, 10731.731706707395, 10915.508802104083, 11189.384583847966, 9961.701148965543, 11547.942260288644, 11738.647840564796, 12204.450799713857, 12420.937895310466, 9223.723288301362, 11319.151063670093, 11440.370779607201, 11641.53433862636, 11774.293542387484, 11700.239484433485, 11777.293362398283, 11777.764444710887, 12027.518899243933, 12281.815772763595, 11177.385243811668, 11363.090952723596, 11554.422278885853, 11667.0, 11711.355875426592, 9389.154976052188, 9395.248380129437, 9721.805563888824, 9833.655822046194, 10243.94878025605, 7172.713091476283, 8493.57532123379, 8592.914070859211, 9275.21160701348, 9393.624255029723], "value": 7261.4032582424215, "unit": "score"}], "name": "dromaeo_css", "value": 7291.535978058209, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 116.0, 112.0, 118.0, 162.0, 167.0, 114.0, 112.0, 124.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 140.0, 134.0, 162.0, 107.0, 130.0, 124.0, 108.0, 160.0, 107.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 182.0, 183.0, 184.0, 182.0, 183.0, 179.0, 182.0, 178.0], "value": 181.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 76.0, 153.0, 58.0, 58.0, 58.0, 60.0, 144.0, 58.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 137.0, 72.0, 72.0, 80.0, 124.0, 69.0, 70.0, 117.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 83.0, 87.0, 84.0, 97.0, 131.0, 88.0, 85.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 98.0, 95.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 85.0, 86.0, 86.0, 86.0, 83.0, 85.0, 85.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 56.0, 57.0, 58.0, 56.0, 55.0, 55.0, 55.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 73.0, 76.0, 72.0, 71.0, 69.0, 71.0, 70.0, 72.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 88.0, 85.0, 89.0, 97.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 139.0, 139.0, 138.0, 137.0, 138.0, 137.0, 181.0, 138.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 167.0, 171.0, 169.0, 169.0, 170.0, 174.0, 234.0, 196.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 66.0, 67.0, 67.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1490.1, "alertThreshold": 2.0}]} 05:20:47 INFO - Return code: 0 05:20:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:20:47 INFO - # TBPL SUCCESS # 05:20:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:47 INFO - 2016-09-02 05:20:47.235 screenresolution[1668:9998] starting screenresolution argv=screenresolution get 05:20:47 INFO - 2016-09-02 05:20:47.238 screenresolution[1668:9998] Display 0: 1600x1200x32@60 05:20:47 INFO - 2016-09-02 05:20:47.244 screenresolution[1669:10001] starting screenresolution argv=screenresolution list 05:20:47 INFO - Available Modes on Display 0 05:20:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:47 INFO - Intel Iris: 05:20:47 INFO - Chipset Model: Intel Iris 05:20:47 INFO - Type: GPU 05:20:47 INFO - Bus: Built-In 05:20:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:47 INFO - Vendor: Intel (0x8086) 05:20:47 INFO - Device ID: 0x0a2e 05:20:47 INFO - Revision ID: 0x0009 05:20:47 INFO - Displays: 05:20:47 INFO - Display: 05:20:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:47 INFO - Display Serial Number: 1600x1200 60 05:20:47 INFO - Main Display: Yes 05:20:47 INFO - Mirror: Off 05:20:47 INFO - Online: Yes 05:20:47 INFO - Rotation: Supported 05:20:47 INFO - Return code: 0 05:20:47 INFO - Running post-action listener: _resource_record_post_action 05:20:47 INFO - [mozharness: 2016-09-02 12:20:47.482763Z] Finished run-tests step (success) 05:20:47 INFO - Running post-run listener: _resource_record_post_run 05:20:47 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37274624; Write bytes: 705154560; Read time: 239; Write time: 1976 05:20:47 INFO - TinderboxPrint: CPU usage
53.5% 05:20:47 INFO - TinderboxPrint: I/O read bytes / time
37,274,624 / 239 05:20:47 INFO - TinderboxPrint: I/O write bytes / time
705,154,560 / 1,976 05:20:47 INFO - TinderboxPrint: CPU idle
2,257.5 (46.5%) 05:20:47 INFO - TinderboxPrint: CPU system
965.7 (19.9%) 05:20:47 INFO - TinderboxPrint: CPU user
1,626.5 (33.5%) 05:20:47 INFO - TinderboxPrint: Swap in / out
443,195,392 / 0 05:20:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398858752; Write bytes: 392693248; Read time: 29483; Write time: 816 05:20:47 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35275776; Write bytes: 295806976; Read time: 222; Write time: 1116 05:20:47 INFO - Running post-run listener: _upload_blobber_files 05:20:47 INFO - Blob upload gear active. 05:20:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:47 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 05:20:47 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'] 05:20:47 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 05:20:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:20:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:49 INFO - (blobuploader) - INFO - Done attempting. 05:20:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:20:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:20:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:50 INFO - (blobuploader) - INFO - Done attempting. 05:20:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:20:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:20:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:53 INFO - (blobuploader) - INFO - Done attempting. 05:20:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:20:53 INFO - Return code: 0 05:20:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:20:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:20:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aed66656e582baa8e9a599d002c3da7b210886bbff4e6ede7273330f90cf3515c87aa1ccacfcfa319828112d5f955d6eb9f304c687f5065b8202752d737866c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5080f89efeb301c3c635aaf590f045e5994fa2d52dda158a053bb6c5c8f0ec2339b105236c32dbc1af7311f8c5271a3c76f887ee08f5aba9b28d841864335efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07a5c6059cad898f7cfc7e49daa77d5a04e07250b83bd5613633705848e11fd64b3926c73d663a993f5b0bfe483ecc86b96eda254e51b89b2237ff3b79c02a62"} 05:20:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:20:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:20:53 INFO - Contents: 05:20:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aed66656e582baa8e9a599d002c3da7b210886bbff4e6ede7273330f90cf3515c87aa1ccacfcfa319828112d5f955d6eb9f304c687f5065b8202752d737866c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5080f89efeb301c3c635aaf590f045e5994fa2d52dda158a053bb6c5c8f0ec2339b105236c32dbc1af7311f8c5271a3c76f887ee08f5aba9b28d841864335efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07a5c6059cad898f7cfc7e49daa77d5a04e07250b83bd5613633705848e11fd64b3926c73d663a993f5b0bfe483ecc86b96eda254e51b89b2237ff3b79c02a62"} 05:20:53 INFO - Running post-run listener: copy_logs_to_upload_dir 05:20:53 INFO - Copying logs to upload dir... 05:20:53 INFO - mkdir: /builds/slave/test/build/upload/logs 05:20:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.574050 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-02 05:20:53.618316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 05:20:53.621430) ========= 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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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/5aed66656e582baa8e9a599d002c3da7b210886bbff4e6ede7273330f90cf3515c87aa1ccacfcfa319828112d5f955d6eb9f304c687f5065b8202752d737866c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5080f89efeb301c3c635aaf590f045e5994fa2d52dda158a053bb6c5c8f0ec2339b105236c32dbc1af7311f8c5271a3c76f887ee08f5aba9b28d841864335efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07a5c6059cad898f7cfc7e49daa77d5a04e07250b83bd5613633705848e11fd64b3926c73d663a993f5b0bfe483ecc86b96eda254e51b89b2237ff3b79c02a62"} build_url:https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012066 build_url: 'https://queue.taskcluster.net/v1/task/TiBfQliuSyGfAkHVKxnFeg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aed66656e582baa8e9a599d002c3da7b210886bbff4e6ede7273330f90cf3515c87aa1ccacfcfa319828112d5f955d6eb9f304c687f5065b8202752d737866c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5080f89efeb301c3c635aaf590f045e5994fa2d52dda158a053bb6c5c8f0ec2339b105236c32dbc1af7311f8c5271a3c76f887ee08f5aba9b28d841864335efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07a5c6059cad898f7cfc7e49daa77d5a04e07250b83bd5613633705848e11fd64b3926c73d663a993f5b0bfe483ecc86b96eda254e51b89b2237ff3b79c02a62"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 05:20:53.655616) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 05:20:53.655942) ========= 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.pIhkcGEwQP/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.NOxkqcQdMg/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 05:20:53.704871) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 05:20:53.705197) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 05:20:53.705536) ========= ========= Total master_lag: 0.17 =========