builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0273 starttime: 1470971386.94 results: success (0) buildid: 20160811183317 builduid: f5472141520f4406b8ba9cf0d7157895 revision: ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:46.935406) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:46.935941) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:46.936328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:47.175491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:47.175920) ========= 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.TPi2J0zjTm/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.ivHytiOGHD/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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-08-11 20:09:47-- 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% 5.79M=0.002s 2016-08-11 20:09:47 (5.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087294 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:47.285531) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:47.285894) ========= 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.TPi2J0zjTm/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.ivHytiOGHD/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.020052 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:47.335222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 20:09:47.335624) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TPi2J0zjTm/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.ivHytiOGHD/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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-08-11 20:09:47,418 truncating revision to first 12 chars 2016-08-11 20:09:47,418 Setting DEBUG logging. 2016-08-11 20:09:47,418 attempt 1/10 2016-08-11 20:09:47,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/ebbebe3e2aee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 20:09:48,478 unpacking tar archive at: autoland-ebbebe3e2aee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 20:09:48.698813) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:48.699150) ========= script_repo_revision: ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:48.699558) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:48.699842) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 20:09:48.717572) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-11 20:09:48.717888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TPi2J0zjTm/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.ivHytiOGHD/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:09:48 INFO - MultiFileLogger online at 20160811 20:09:48 in /builds/slave/test 20:09:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:09:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:09:48 INFO - {'append_to_log': False, 20:09:48 INFO - 'base_work_dir': '/builds/slave/test', 20:09:48 INFO - 'blob_upload_branch': 'Autoland', 20:09:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:09:48 INFO - 'branch': 'Autoland', 20:09:48 INFO - 'buildbot_json_path': 'buildprops.json', 20:09:48 INFO - 'config_files': ('talos/mac_config.py',), 20:09:48 INFO - 'default_actions': ('clobber', 20:09:48 INFO - 'read-buildbot-config', 20:09:48 INFO - 'download-and-extract', 20:09:48 INFO - 'populate-webroot', 20:09:48 INFO - 'create-virtualenv', 20:09:48 INFO - 'install', 20:09:48 INFO - 'run-tests'), 20:09:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:09:48 INFO - 'download_minidump_stackwalk': True, 20:09:48 INFO - 'download_symbols': 'ondemand', 20:09:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:09:48 INFO - 'tooltool.py': '/tools/tooltool.py', 20:09:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:09:48 INFO - '/tools/misc-python/virtualenv.py')}, 20:09:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:09:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:09:48 INFO - 'installer_path': 'installer.exe', 20:09:48 INFO - 'log_level': 'info', 20:09:48 INFO - 'log_name': 'talos', 20:09:48 INFO - 'log_to_console': True, 20:09:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:09:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:09:48 INFO - 'opt_config_files': (), 20:09:48 INFO - 'pip_index': False, 20:09:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:09:48 INFO - 'cmd': ('bash', 20:09:48 INFO - '-c', 20:09:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:09:48 INFO - 'enabled': True, 20:09:48 INFO - 'halt_on_failure': False, 20:09:48 INFO - 'name': 'check_screen_resolution'},), 20:09:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:09:48 INFO - 'cmd': ('bash', 20:09:48 INFO - '-c', 20:09:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:09:48 INFO - 'enabled': True, 20:09:48 INFO - 'halt_on_failure': False, 20:09:48 INFO - 'name': 'check_screen_resolution'},), 20:09:48 INFO - 'run_cmd_checks_enabled': True, 20:09:48 INFO - 'sps_profile': False, 20:09:48 INFO - 'sps_profile_interval': 0, 20:09:48 INFO - 'suite': 'dromaeojs', 20:09:48 INFO - 'system_bits': '32', 20:09:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:09:48 INFO - 'title': 't-yosemite-r7-0273', 20:09:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:09:48 INFO - 'use_talos_json': True, 20:09:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:09:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:09:48 INFO - 'work_dir': 'build'} 20:09:48 INFO - [mozharness: 2016-08-12 03:09:48.923391Z] Running clobber step. 20:09:48 INFO - Running pre-action listener: _resource_record_pre_action 20:09:48 INFO - Running main action method: clobber 20:09:48 INFO - rmtree: /builds/slave/test/build 20:09:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:09:50 INFO - Running post-action listener: _resource_record_post_action 20:09:50 INFO - [mozharness: 2016-08-12 03:09:50.408823Z] Finished clobber step (success) 20:09:50 INFO - [mozharness: 2016-08-12 03:09:50.409001Z] Running read-buildbot-config step. 20:09:50 INFO - Running pre-action listener: _resource_record_pre_action 20:09:50 INFO - Running main action method: read_buildbot_config 20:09:50 INFO - Using buildbot properties: 20:09:50 INFO - { 20:09:50 INFO - "project": "", 20:09:50 INFO - "product": "firefox", 20:09:50 INFO - "script_repo_revision": "production", 20:09:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:09:50 INFO - "repository": "", 20:09:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:09:50 INFO - "buildid": "20160811183317", 20:09:50 INFO - "pgo_build": "False", 20:09:50 INFO - "basedir": "/builds/slave/test", 20:09:50 INFO - "buildnumber": 80, 20:09:50 INFO - "slavename": "t-yosemite-r7-0273", 20:09:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:09:50 INFO - "platform": "yosemite_r7", 20:09:50 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:09:50 INFO - "branch": "autoland", 20:09:50 INFO - "repo_path": "integration/autoland", 20:09:50 INFO - "revision": "ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a", 20:09:50 INFO - "stage_platform": "macosx64", 20:09:50 INFO - "builduid": "f5472141520f4406b8ba9cf0d7157895", 20:09:50 INFO - "slavebuilddir": "test" 20:09:50 INFO - } 20:09:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:09:50 INFO - Running post-action listener: _resource_record_post_action 20:09:50 INFO - [mozharness: 2016-08-12 03:09:50.413224Z] Finished read-buildbot-config step (success) 20:09:50 INFO - [mozharness: 2016-08-12 03:09:50.413333Z] Running download-and-extract step. 20:09:50 INFO - Running pre-action listener: _resource_record_pre_action 20:09:50 INFO - Running main action method: download_and_extract 20:09:50 INFO - mkdir: /builds/slave/test/build/tests 20:09: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')]} 20:09:50 INFO - trying https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:09:50 INFO - Downloading https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:09:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:09:51 INFO - Downloaded 1199 bytes. 20:09:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:09:51 INFO - Using the following test package requirements: 20:09:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:09:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:09:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:09:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'jsshell-mac.zip'], 20:09:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:09:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:09:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:09:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:09:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:09:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:09:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:09:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:51 INFO - trying https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:09:51 INFO - Downloading https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:09:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:09:52 INFO - Downloaded 16328612 bytes. 20:09:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:09:59 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 20:09: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')]} 20:09:59 INFO - trying https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:09:59 INFO - Downloading https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:09:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:10:00 INFO - Downloaded 16328612 bytes. 20:10:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:10: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')]} 20:10:06 INFO - trying https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:10:06 INFO - Downloading https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:10:08 INFO - Downloaded 14413719 bytes. 20:10:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 20:10: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')]} 20:10:08 INFO - trying https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:10:08 INFO - Downloading https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/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 20:10:11 INFO - Downloaded 110970908 bytes. 20:10:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:10:11 INFO - mkdir: /builds/slave/test/properties 20:10:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:10:11 INFO - Writing to file /builds/slave/test/properties/build_url 20:10:11 INFO - Contents: 20:10:11 INFO - build_url:https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:10:11 INFO - Running post-action listener: _resource_record_post_action 20:10:11 INFO - Running post-action listener: set_extra_try_arguments 20:10:11 INFO - [mozharness: 2016-08-12 03:10:11.952189Z] Finished download-and-extract step (success) 20:10:11 INFO - [mozharness: 2016-08-12 03:10:11.952315Z] Running populate-webroot step. 20:10:11 INFO - Running pre-action listener: _resource_record_pre_action 20:10:11 INFO - Running main action method: populate_webroot 20:10:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:10:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:10:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:10:11 INFO - '--tppagecycles', 20:10:11 INFO - '7'], 20:10:11 INFO - 'tests': ['tsvgm']}}, 20:10:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:10:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:10:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:10:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:10:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['damp', 'tps']}, 20:10:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'tests': ['damp', 'tps']}, 20:10:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['dromaeo_dom']}, 20:10:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:10:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['basic_compositor_video']}, 20:10:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:10:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['a11yr', 20:10:11 INFO - 'ts_paint', 20:10:11 INFO - 'tpaint', 20:10:11 INFO - 'sessionrestore', 20:10:11 INFO - 'sessionrestore_no_auto_restore', 20:10:11 INFO - 'tabpaint']}, 20:10:11 INFO - 'other-e10s': {'tests': ['a11yr', 20:10:11 INFO - 'ts_paint', 20:10:11 INFO - 'tpaint', 20:10:11 INFO - 'sessionrestore', 20:10:11 INFO - 'sessionrestore_no_auto_restore', 20:10:11 INFO - 'tabpaint']}, 20:10:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:10:11 INFO - 'ts_paint', 20:10:11 INFO - 'tpaint', 20:10:11 INFO - 'sessionrestore', 20:10:11 INFO - 'sessionrestore_no_auto_restore', 20:10:11 INFO - 'tabpaint']}, 20:10:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:10:11 INFO - 'ts_paint', 20:10:11 INFO - 'tpaint', 20:10:11 INFO - 'sessionrestore', 20:10:11 INFO - 'sessionrestore_no_auto_restore', 20:10:11 INFO - 'tabpaint']}, 20:10:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['a11yr', 20:10:11 INFO - 'ts_paint', 20:10:11 INFO - 'tpaint', 20:10:11 INFO - 'sessionrestore', 20:10:11 INFO - 'sessionrestore_no_auto_restore', 20:10:11 INFO - 'tabpaint']}, 20:10:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['a11yr', 20:10:11 INFO - 'ts_paint', 20:10:11 INFO - 'tpaint', 20:10:11 INFO - 'sessionrestore', 20:10:11 INFO - 'sessionrestore_no_auto_restore', 20:10:11 INFO - 'tabpaint']}, 20:10:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['tsvgx', 20:10:11 INFO - 'tsvgr_opacity', 20:10:11 INFO - 'tart', 20:10:11 INFO - 'tscrollx', 20:10:11 INFO - 'cart']}, 20:10:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:10:11 INFO - 'tsvgr_opacity', 20:10:11 INFO - 'tart', 20:10:11 INFO - 'tscrollx', 20:10:11 INFO - 'cart']}, 20:10:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'talos_options': ['--disable-e10s'], 20:10:11 INFO - 'tests': ['tp5o']}, 20:10:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'tests': ['tp5o']}, 20:10:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'talos_options': ['--disable-e10s', 20:10:11 INFO - '--xperf_path', 20:10:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:11 INFO - 'tests': ['tp5n']}, 20:10:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:10:11 INFO - 'talos_options': ['--xperf_path', 20:10:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:11 INFO - 'tests': ['tp5n']}}, 20:10:11 INFO - 'talos.zip': {'path': '', 20:10:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:10:11 INFO - Running post-action listener: _resource_record_post_action 20:10:11 INFO - [mozharness: 2016-08-12 03:10:11.971043Z] Finished populate-webroot step (success) 20:10:11 INFO - [mozharness: 2016-08-12 03:10:11.971156Z] Running create-virtualenv step. 20:10:11 INFO - Running pre-action listener: _resource_record_pre_action 20:10:11 INFO - Running main action method: create_virtualenv 20:10:11 INFO - Creating virtualenv /builds/slave/test/build/venv 20:10:11 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 20:10:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:10:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:10:12 INFO - Using real prefix '/tools/python27' 20:10:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:10:12 INFO - Installing distribute.............................................................................................................................................................................................done. 20:10:15 INFO - Installing pip.................done. 20:10:15 INFO - Return code: 0 20:10:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:10:15 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')]} 20:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:15 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')]} 20:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:15 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:15 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 20:10:15 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 20:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:15 INFO - 'HOME': '/Users/cltbld', 20:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:15 INFO - 'LOGNAME': 'cltbld', 20:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:15 INFO - 'MOZ_NO_REMOTE': '1', 20:10:15 INFO - 'NO_EM_RESTART': '1', 20:10:15 INFO - 'PAGER': '/bin/cat', 20:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:15 INFO - 'PWD': '/builds/slave/test', 20:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:15 INFO - 'SHELL': '/bin/bash', 20:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:15 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:15 INFO - 'USER': 'cltbld', 20:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:15 INFO - 'XPC_FLAGS': '0x0', 20:10:15 INFO - 'XPC_SERVICE_NAME': '0', 20:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:15 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 20:10:15 INFO - Cleaning up... 20:10:15 INFO - Return code: 0 20:10:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:10:15 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')]} 20:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:15 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')]} 20:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:15 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:15 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 20:10:15 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 20:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:15 INFO - 'HOME': '/Users/cltbld', 20:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:15 INFO - 'LOGNAME': 'cltbld', 20:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:15 INFO - 'MOZ_NO_REMOTE': '1', 20:10:15 INFO - 'NO_EM_RESTART': '1', 20:10:15 INFO - 'PAGER': '/bin/cat', 20:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:15 INFO - 'PWD': '/builds/slave/test', 20:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:15 INFO - 'SHELL': '/bin/bash', 20:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:15 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:15 INFO - 'USER': 'cltbld', 20:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:15 INFO - 'XPC_FLAGS': '0x0', 20:10:15 INFO - 'XPC_SERVICE_NAME': '0', 20:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:16 INFO - Downloading/unpacking psutil>=3.1.1 20:10:16 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 20:10:16 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 20:10:16 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 20:10:16 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 20:10:16 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 20:10:16 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 20:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:19 INFO - Installing collected packages: psutil 20:10:19 INFO - Running setup.py install for psutil 20:10:19 INFO - building 'psutil._psutil_osx' extension 20:10:19 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 20:10:19 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 20:10:20 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 20:10:20 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 20:10:20 INFO - building 'psutil._psutil_posix' extension 20:10:20 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 20:10:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:10:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:10:20 INFO - ^ 20:10:20 INFO - 1 warning generated. 20:10:20 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 20:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:20 INFO - Successfully installed psutil 20:10:20 INFO - Cleaning up... 20:10:20 INFO - Return code: 0 20:10:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:20 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:20 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 20:10:20 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 20:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:20 INFO - 'HOME': '/Users/cltbld', 20:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:20 INFO - 'LOGNAME': 'cltbld', 20:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:20 INFO - 'MOZ_NO_REMOTE': '1', 20:10:20 INFO - 'NO_EM_RESTART': '1', 20:10:20 INFO - 'PAGER': '/bin/cat', 20:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:20 INFO - 'PWD': '/builds/slave/test', 20:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:20 INFO - 'SHELL': '/bin/bash', 20:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:20 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:20 INFO - 'USER': 'cltbld', 20:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:20 INFO - 'XPC_FLAGS': '0x0', 20:10:20 INFO - 'XPC_SERVICE_NAME': '0', 20:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10: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 20:10:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:10:23 INFO - Installing collected packages: mozsystemmonitor 20:10:23 INFO - Running setup.py install for mozsystemmonitor 20:10:23 INFO - Successfully installed mozsystemmonitor 20:10:23 INFO - Cleaning up... 20:10:23 INFO - Return code: 0 20:10:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:23 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:23 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 20:10:23 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 20:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:23 INFO - 'HOME': '/Users/cltbld', 20:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:23 INFO - 'LOGNAME': 'cltbld', 20:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:23 INFO - 'MOZ_NO_REMOTE': '1', 20:10:23 INFO - 'NO_EM_RESTART': '1', 20:10:23 INFO - 'PAGER': '/bin/cat', 20:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:23 INFO - 'PWD': '/builds/slave/test', 20:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:23 INFO - 'SHELL': '/bin/bash', 20:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:23 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:23 INFO - 'USER': 'cltbld', 20:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:23 INFO - 'XPC_FLAGS': '0x0', 20:10:23 INFO - 'XPC_SERVICE_NAME': '0', 20:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:23 INFO - Downloading/unpacking jsonschema==2.5.1 20:10:23 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 20:10:23 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 20:10:23 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 20:10:23 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 20:10:23 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 20:10:23 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 20:10:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:10:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:26 INFO - Downloading functools32-3.2.3-2.tar.gz 20:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:10:26 INFO - warning: no files found matching '*.txt' 20:10:26 INFO - no previously-included directories found matching 'build' 20:10:26 INFO - no previously-included directories found matching 'dist' 20:10:26 INFO - no previously-included directories found matching '.git*' 20:10:26 INFO - Installing collected packages: jsonschema, functools32 20:10:26 INFO - Running setup.py install for functools32 20:10:26 INFO - warning: no files found matching '*.txt' 20:10:26 INFO - no previously-included directories found matching 'build' 20:10:26 INFO - no previously-included directories found matching 'dist' 20:10:26 INFO - no previously-included directories found matching '.git*' 20:10:26 INFO - Successfully installed jsonschema functools32 20:10:26 INFO - Cleaning up... 20:10:26 INFO - Return code: 0 20:10:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:26 INFO - 'HOME': '/Users/cltbld', 20:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:26 INFO - 'LOGNAME': 'cltbld', 20:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:26 INFO - 'MOZ_NO_REMOTE': '1', 20:10:26 INFO - 'NO_EM_RESTART': '1', 20:10:26 INFO - 'PAGER': '/bin/cat', 20:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:26 INFO - 'PWD': '/builds/slave/test', 20:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:26 INFO - 'SHELL': '/bin/bash', 20:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:26 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:26 INFO - 'USER': 'cltbld', 20:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:26 INFO - 'XPC_FLAGS': '0x0', 20:10:26 INFO - 'XPC_SERVICE_NAME': '0', 20:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:10:27 INFO - Cleaning up... 20:10:27 INFO - Return code: 0 20:10:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:27 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:27 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 20:10:27 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 20:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:27 INFO - 'HOME': '/Users/cltbld', 20:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:27 INFO - 'LOGNAME': 'cltbld', 20:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:27 INFO - 'MOZ_NO_REMOTE': '1', 20:10:27 INFO - 'NO_EM_RESTART': '1', 20:10:27 INFO - 'PAGER': '/bin/cat', 20:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:27 INFO - 'PWD': '/builds/slave/test', 20:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:27 INFO - 'SHELL': '/bin/bash', 20:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:27 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:27 INFO - 'USER': 'cltbld', 20:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:27 INFO - 'XPC_FLAGS': '0x0', 20:10:27 INFO - 'XPC_SERVICE_NAME': '0', 20:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:27 INFO - Downloading/unpacking blobuploader==1.2.4 20:10:27 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 20:10:27 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 20:10:27 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 20:10:27 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 20:10:27 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 20:10:27 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 20:10:29 INFO - Downloading blobuploader-1.2.4.tar.gz 20:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:10:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:10:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 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 20:10:30 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 20:10:31 INFO - Downloading docopt-0.6.1.tar.gz 20:10:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:10:31 INFO - Installing collected packages: blobuploader, requests, docopt 20:10:31 INFO - Running setup.py install for blobuploader 20:10:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:10:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:10:31 INFO - Running setup.py install for requests 20:10:31 INFO - Running setup.py install for docopt 20:10:31 INFO - Successfully installed blobuploader requests docopt 20:10:31 INFO - Cleaning up... 20:10:32 INFO - Return code: 0 20:10:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:32 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')]} 20:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:32 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')]} 20:10:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:32 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:32 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 20:10:32 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 20:10:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:32 INFO - 'HOME': '/Users/cltbld', 20:10:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:32 INFO - 'LOGNAME': 'cltbld', 20:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:32 INFO - 'MOZ_NO_REMOTE': '1', 20:10:32 INFO - 'NO_EM_RESTART': '1', 20:10:32 INFO - 'PAGER': '/bin/cat', 20:10:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:32 INFO - 'PWD': '/builds/slave/test', 20:10:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:32 INFO - 'SHELL': '/bin/bash', 20:10:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:32 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:32 INFO - 'USER': 'cltbld', 20:10:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:32 INFO - 'XPC_FLAGS': '0x0', 20:10:32 INFO - 'XPC_SERVICE_NAME': '0', 20:10:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-rB3hb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ImVBem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-1WDVdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-t7xn8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-NTmoDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-V52bUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:32 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-_BPcFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-7Vs2EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Wl3tKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Qnx4oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-DattD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Y5WWbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-U4EeCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-YiQCWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-aRa8lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ffbXSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:33 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-xYmC4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:10:34 INFO - Running setup.py install for manifestparser 20:10:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:10:34 INFO - Running setup.py install for mozcrash 20:10:34 INFO - Running setup.py install for mozdebug 20:10:34 INFO - Running setup.py install for mozdevice 20:10:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:10:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:10:34 INFO - Running setup.py install for mozfile 20:10:34 INFO - Running setup.py install for mozhttpd 20:10:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:10:34 INFO - Running setup.py install for mozinfo 20:10:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Running setup.py install for mozInstall 20:10:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Running setup.py install for mozleak 20:10:35 INFO - Running setup.py install for mozlog 20:10:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Running setup.py install for moznetwork 20:10:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Running setup.py install for mozprocess 20:10:35 INFO - Running setup.py install for mozprofile 20:10:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:10:35 INFO - Running setup.py install for mozrunner 20:10:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:10:36 INFO - Running setup.py install for mozscreenshot 20:10:36 INFO - Running setup.py install for moztest 20:10:36 INFO - Running setup.py install for mozversion 20:10:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:10:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:10:36 INFO - Cleaning up... 20:10:36 INFO - Return code: 0 20:10:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:36 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:36 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 20:10:36 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 20:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:36 INFO - 'HOME': '/Users/cltbld', 20:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:36 INFO - 'LOGNAME': 'cltbld', 20:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:36 INFO - 'MOZ_NO_REMOTE': '1', 20:10:36 INFO - 'NO_EM_RESTART': '1', 20:10:36 INFO - 'PAGER': '/bin/cat', 20:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:36 INFO - 'PWD': '/builds/slave/test', 20:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:36 INFO - 'SHELL': '/bin/bash', 20:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:36 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:36 INFO - 'USER': 'cltbld', 20:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:36 INFO - 'XPC_FLAGS': '0x0', 20:10:36 INFO - 'XPC_SERVICE_NAME': '0', 20:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:36 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-qQ2VqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:36 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)) 20:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:36 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-SnpkJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-gJ4MiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-K4PRoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-4CE2oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-hC2R1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-8VphMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-trSXE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-q5hDBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-jE2q8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:37 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)) 20:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:37 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Dc2_sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:38 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)) 20:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:38 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-aJirSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:38 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)) 20:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:38 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-kDdGB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:38 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)) 20:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:38 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-_SiBY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:38 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)) 20:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:38 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-u1aArp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:38 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)) 20:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:38 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-8lYqmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:38 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)) 20:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:38 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-R6astw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:38 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)) 20:10:38 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)) 20:10:38 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)) 20:10:38 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)) 20:10:38 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)) 20:10:38 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)) 20:10:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:41 INFO - Downloading blessings-1.6.tar.gz 20:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:10:41 INFO - Installing collected packages: blessings 20:10:41 INFO - Running setup.py install for blessings 20:10:41 INFO - Successfully installed blessings 20:10:41 INFO - Cleaning up... 20:10:41 INFO - Return code: 0 20:10:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:41 INFO - Reading from file tmpfile_stdout 20:10:41 INFO - Current package versions: 20:10:41 INFO - blessings == 1.6 20:10:41 INFO - blobuploader == 1.2.4 20:10:41 INFO - docopt == 0.6.1 20:10:41 INFO - functools32 == 3.2.3-2 20:10:41 INFO - jsonschema == 2.5.1 20:10:41 INFO - manifestparser == 1.1 20:10:41 INFO - mozInstall == 1.12 20:10:41 INFO - mozcrash == 0.17 20:10:41 INFO - mozdebug == 0.1 20:10:41 INFO - mozdevice == 0.48 20:10:41 INFO - mozfile == 1.2 20:10:41 INFO - mozhttpd == 0.7 20:10:41 INFO - mozinfo == 0.9 20:10:41 INFO - mozleak == 0.1 20:10:41 INFO - mozlog == 3.3 20:10:41 INFO - moznetwork == 0.27 20:10:41 INFO - mozprocess == 0.23 20:10:41 INFO - mozprofile == 0.28 20:10:41 INFO - mozrunner == 6.12 20:10:41 INFO - mozscreenshot == 0.1 20:10:41 INFO - mozsystemmonitor == 0.3 20:10:41 INFO - moztest == 0.7 20:10:41 INFO - mozversion == 1.4 20:10:41 INFO - psutil == 3.1.1 20:10:41 INFO - requests == 1.2.3 20:10:41 INFO - wsgiref == 0.1.2 20:10:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:42 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 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:42 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 20:10:42 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 20:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:42 INFO - 'HOME': '/Users/cltbld', 20:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:42 INFO - 'LOGNAME': 'cltbld', 20:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:42 INFO - 'MOZ_NO_REMOTE': '1', 20:10:42 INFO - 'NO_EM_RESTART': '1', 20:10:42 INFO - 'PAGER': '/bin/cat', 20:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:42 INFO - 'PWD': '/builds/slave/test', 20:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:42 INFO - 'SHELL': '/bin/bash', 20:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:42 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:42 INFO - 'USER': 'cltbld', 20:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:42 INFO - 'XPC_FLAGS': '0x0', 20:10:42 INFO - 'XPC_SERVICE_NAME': '0', 20:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 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)) 20:10:42 INFO - Cleaning up... 20:10:42 INFO - Return code: 0 20:10:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a821a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84a2486100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, '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.TPi2J0zjTm/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.ivHytiOGHD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 20:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:42 INFO - 'HOME': '/Users/cltbld', 20:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:42 INFO - 'LOGNAME': 'cltbld', 20:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:42 INFO - 'MOZ_NO_REMOTE': '1', 20:10:42 INFO - 'NO_EM_RESTART': '1', 20:10:42 INFO - 'PAGER': '/bin/cat', 20:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:42 INFO - 'PWD': '/builds/slave/test', 20:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:42 INFO - 'SHELL': '/bin/bash', 20:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:10:42 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:42 INFO - 'USER': 'cltbld', 20:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:42 INFO - 'XPC_FLAGS': '0x0', 20:10:42 INFO - 'XPC_SERVICE_NAME': '0', 20:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:10:42 INFO - Cleaning up... 20:10:42 INFO - Return code: 0 20:10:42 INFO - Running post-action listener: _resource_record_post_action 20:10:42 INFO - Running post-action listener: _start_resource_monitoring 20:10:42 INFO - Starting resource monitoring. 20:10:42 INFO - [mozharness: 2016-08-12 03:10:42.826540Z] Finished create-virtualenv step (success) 20:10:42 INFO - [mozharness: 2016-08-12 03:10:42.827553Z] Running install step. 20:10:42 INFO - Running pre-action listener: _resource_record_pre_action 20:10:42 INFO - Running main action method: install 20:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:43 INFO - Reading from file tmpfile_stdout 20:10:43 INFO - Detecting whether we're running mozinstall >=1.0... 20:10:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:10:43 INFO - Reading from file tmpfile_stdout 20:10:43 INFO - Output received: 20:10:43 INFO - Usage: mozinstall [options] installer 20:10:43 INFO - Options: 20:10:43 INFO - -h, --help show this help message and exit 20:10:43 INFO - -d DEST, --destination=DEST 20:10:43 INFO - Directory to install application into. [default: 20:10:43 INFO - "/builds/slave/test"] 20:10:43 INFO - --app=APP Application being installed. [default: firefox] 20:10:43 INFO - mkdir: /builds/slave/test/build/application 20:10:43 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'] 20:10:43 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 20:11:16 INFO - Reading from file tmpfile_stdout 20:11:16 INFO - Output received: 20:11:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:11:16 INFO - Running post-action listener: _resource_record_post_action 20:11:16 INFO - [mozharness: 2016-08-12 03:11:16.798031Z] Finished install step (success) 20:11:16 INFO - [mozharness: 2016-08-12 03:11:16.798168Z] Running run-tests step. 20:11:16 INFO - Running pre-action listener: _resource_record_pre_action 20:11:16 INFO - Running main action method: run_tests 20:11:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:11:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:11:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:11:16 INFO - 2016-08-11 20:11:16.812 screenresolution[1917:12073] starting screenresolution argv=screenresolution get 20:11:16 INFO - 2016-08-11 20:11:16.815 screenresolution[1917:12073] Display 0: 1600x1200x32@60 20:11:16 INFO - 2016-08-11 20:11:16.821 screenresolution[1918:12076] starting screenresolution argv=screenresolution list 20:11:16 INFO - Available Modes on Display 0 20:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:11:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:11:17 INFO - Intel Iris: 20:11:17 INFO - Chipset Model: Intel Iris 20:11:17 INFO - Type: GPU 20:11:17 INFO - Bus: Built-In 20:11:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:11:17 INFO - Vendor: Intel (0x8086) 20:11:17 INFO - Device ID: 0x0a2e 20:11:17 INFO - Revision ID: 0x0009 20:11:17 INFO - Displays: 20:11:17 INFO - Display: 20:11:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:11:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:11:17 INFO - Display Serial Number: 1600x1200 60 20:11:17 INFO - Main Display: Yes 20:11:17 INFO - Mirror: Off 20:11:17 INFO - Online: Yes 20:11:17 INFO - Rotation: Supported 20:11:17 INFO - Return code: 0 20:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:11:17 INFO - Python 2.7.3 20:11:17 INFO - Return code: 0 20:11:17 INFO - grabbing minidump binary from tooltool 20:11: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')]} 20:11:17 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 0x10a785d30>, '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 0x10a4eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a77db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:11:17 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 20:11:17 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 20:11:17 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 20:11:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:11:17 INFO - Return code: 0 20:11:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:11:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:11:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:11:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:11:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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 20:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0273 --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 20:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPi2J0zjTm/Render', 20:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:11:17 INFO - 'HOME': '/Users/cltbld', 20:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:11:17 INFO - 'LOGNAME': 'cltbld', 20:11:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:11:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:17 INFO - 'MOZ_NO_REMOTE': '1', 20:11:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:11:17 INFO - 'NO_EM_RESTART': '1', 20:11:17 INFO - 'PAGER': '/bin/cat', 20:11:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:17 INFO - 'PWD': '/builds/slave/test', 20:11:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:11:17 INFO - 'SHELL': '/bin/bash', 20:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ivHytiOGHD/Listeners', 20:11:17 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 20:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:11:17 INFO - 'USER': 'cltbld', 20:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:17 INFO - 'XPC_FLAGS': '0x0', 20:11:17 INFO - 'XPC_SERVICE_NAME': '0', 20:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:11:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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 20:11:17 INFO - mozversion application_buildid: 20160811183317 20:11:17 INFO - mozversion application_changeset: ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a 20:11:17 INFO - mozversion application_display_name: Nightly 20:11:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:11:17 INFO - mozversion application_name: Firefox 20:11:17 INFO - mozversion application_remotingname: firefox 20:11:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:11:17 INFO - mozversion application_vendor: Mozilla 20:11:17 INFO - mozversion application_version: 51.0a1 20:11:17 INFO - mozversion platform_buildid: 20160811183317 20:11:17 INFO - mozversion platform_changeset: ebbebe3e2aeed6fc6434ae7c4b7c9b9460b0c93a 20:11:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:11:17 INFO - mozversion platform_version: 51.0a1 20:11:17 INFO - using testdate: 1470971477 20:11:17 INFO - actual date: 1470971477 20:11:17 INFO - starting webserver on 'localhost:50881' 20:11:17 INFO - SUITE-START | Running 2 tests 20:11:17 INFO - TEST-START | dromaeo_css 20:11:17 INFO - operating with platform_type : mac_ 20:11:17 INFO - Initialising browser for dromaeo_css test... 20:11:17 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp0zgY13/profile http://localhost:50881/getInfo.html) 20:11:19 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 20:11:19 INFO - PROCESS | 1928 | colorDepth:24 20:11:19 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 20:11:19 INFO - PROCESS | 1928 | __metrics 20:11:19 INFO - PROCESS | 1928 | 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" 20:11:19 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:11:20 INFO - TEST-INFO | 1928: exit 0 20:11:20 INFO - Browser initialized. 20:11:20 INFO - Running cycle 1/1 for dromaeo_css test... 20:11:20 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp0zgY13/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:11:32 INFO - PROCESS | 1930 | RSS: Main: 243433472 20:11:32 INFO - PROCESS | 1930 | 20:12:24 INFO - PROCESS | 1930 | 1470971544178 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] 20:14:36 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:50881/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50881/tests/dromaeo/cssquery-ext.html) 20:14:37 INFO - PROCESS | 1930 | RSS: Main: 349302784 20:14:37 INFO - PROCESS | 1930 | 20:17:40 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:50881/tests/dromaeo/cssquery-ext.html (next: http://localhost:50881/tests/dromaeo/cssquery-jquery.html) 20:17:40 INFO - PROCESS | 1930 | RSS: Main: 384561152 20:17:40 INFO - PROCESS | 1930 | 20:20:42 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:50881/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50881/tests/dromaeo/cssquery-mootools.html) 20:20:43 INFO - PROCESS | 1930 | RSS: Main: 376971264 20:20:43 INFO - PROCESS | 1930 | 20:23:46 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:50881/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50881/tests/dromaeo/cssquery-prototype.html) 20:23:46 INFO - PROCESS | 1930 | RSS: Main: 348237824 20:23:46 INFO - PROCESS | 1930 | 20:26:50 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:50881/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50881/tests/dromaeo/cssquery-yui.html) 20:26:50 INFO - PROCESS | 1930 | RSS: Main: 342200320 20:26:50 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:50881/tests/dromaeo/cssquery-yui.html (next: http://localhost:50881/tests/dromaeo/cssquery-dojo.html) 20:29:54 INFO - PROCESS | 1930 | RSS: Main: 492957696 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | __start_tp_report 20:29:54 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 20:29:54 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 20:29:54 INFO - PROCESS | 1930 | |i|pagename|runs| 20:29:54 INFO - PROCESS | 1930 | |0;dojo.html;2771.2933202033482;3322.3189246204515;3341.465365541513;3343.1976325681844;3380.8843081783016;4987.202047672373;5232.947670523294;5439.5376393006545;5593.468620481058;5598.804041858531;5554.666719996793;5556.472135147154;5562.666240025601;5565.220869078333;5598.4961353478175;5088.8982220355665;5118.027574760802;5147.22791581262;5189.429162792108;5203.219517072451;5083.872903177413;5166.914947860953;5167.457416971201;5184.015037142931;5195.272661827328;5073.847784566469;5092.719900405476;5113.105206588851;5119.974400128012;5171.586273098162;5151.742412879341;5172.870678233036;5185.274061631375;5198.610104242197;5233.136532472137;4982.427020892607;5028.044671512437;5032.874178145539;5112.46319136489;5115.053715062688;5467.507924286793;5535.169724541311;5547.3343198816;5547.389787123413;5583.6928968906695;6098.700162976515;6110.938890611126;6128.172696685935;6132.0070976932675;6136.110264011693;5971.193888824996;5977.193078934375;6126.9452004266695;6198.535109866778;6254.6247225166635;5698.31620205578;5707.686077265707;5758.827235182951;5762.394948530427;5773.422657734193;6283.905741413882;6357.364263573606;6362.077498762654;6364.395382438661;6373.235211774617;5615.101583746663;5943.257092863392;6037.72830222641;6039.245094363205;6051;5896.646201227854;6230.532710046765;6243.219597550306;6258.687065646699;6264.874702505925;5458.8908221835345;6110.083487476825;6119.265688117454;6131.785387511416;6154.323024467305;5868.383819698955;6102.511799056065;6140.293866205354;6142.754289828356;6170.290416602148;4786.545278198566;4896.559309662148;4904.950950490521;4908.631852611068;4909.975450122726;4342.500612429548;4406.889827754268;4407.911841763147;4420.557944205554;4442.4669039715445;5517.61376703635;5529.972350138304;5543.556515478752;5551.306086739158;5590.5248053915875;6131.877362452726;6185.319614842364;6197.349278325711;6201.7519299228425;6218;5432.565394768409;5469.206964990054;5506.256655351556;5547.362053363834;5564.387917329091;4555.362249285103;5385.407605163429;5393.4336894626285;5403.675779453167;5431.022415965164;6317.684115794284;6318.147050148171;6331.620102793847;6332.1135041094285;6351.55539112267;5963.970180149072;6005.729742161526;6032.245969253843;6075.088736689532;6172.321044685171;6292.244930608356;6312.4634406076;6318.494520438446;6322.33615470369;6339.936600633934;5944.227250457413;5958.791442299499;5966.224390829165;5976.641401515923;6000.939990600213;5948.553858460719;5981.2822461303185;6053.031514957585;6063.363346848518;6083.330833608211;5883.882322353615;5901.704914754331;5906.379830117776;5924.9927512323875;5947.881042379042;5864.530837533072;5878.94121058801;5893.44012318829;5916.467517923407;5918.615290006136;5626.071698169756;5764.769409223584;5778.653280803165;5781.739821708117;5813.331466881363;6016.398360163948;6028.4272994065495;6051.5461340400525;6053.788117416046;6104.847378815388;5838.94161058384;5841.18223448726;5849.298084229919;5860.09168578871;5864.237649105589 20:29:54 INFO - PROCESS | 1930 | |1;ext.html;469.5867636479897;500.5445045009042;513.2327170879536;516.1844286028077;520.4041372628338;31249.687503124962;31554.895578654723;40985.3852192217;41702.58297417033;41808.163836723244;43270.56729432705;43606.12787744251;43651.12697746043;43701.562984370146;43711;9654;9813.754656133582;9837.950810245975;9844.261680374;9870.161036311887;9738.610455581767;9806.460644664541;9806.509674516281;9814.362066465657;9817;9812;9880.555375008105;9888.060634239735;9893.4558599277;9938.850917236246;9777.217822574212;9850.261230407748;9850.655227067018;9863.01369863008;9863.90136098637;9633.518324083769;9701.90298097017;9722.854157187649;9741.366811157252;9750.853737193947;40745;41112.97217569555;41134.58865411337;41261.587384126076;41316.58683413157;3344;3430.9313813723834;3664.0656632558794;3715.145516531186;3952.383803308786;32289;32315;32473.6752632473;32493;32561.348773024405;3228.170221674982;3268.4770436730246;3368.898933032013;3374.6119196292248;3375.6118046424485;508131.4593427046;510311;512545;515274;515579;56981.58674925876;57168.42831571631;57252.713736431055;57277;57451.71274143686;60150;60271;60328.39671603315;60396.39603603996;60401.69799150976;16000.8799384042;16134.35462581507;16147.515574532783;16170.838291617169;16177.676446471005;12691.111622186543;12768.872311276955;12776.361181940865;12789.424475898606;12823.422945967453;12476;15052.261356318337;15069.47256846027;15070.547883563511;15204;6161.691915404212;6235.407636274546;6265.27949285838;6324.26868147795;6336;744.2966396755014;888.9377743557889;939.8496240601471;1034.8240799064026;1079.3254216114929;29841.253968650522;30158.84920575383;30364.392712145633;31100.844495777375;31174.844125779226;823.5429336718179;1034.095166729112;1093.7648405592836;1152.9711757206137;1190.8570971483477;15481.767773483407;15547.766783498257;15621.843781562038;15727.213639318217;15960.760588590949;667811;685589;686392;686964;689786;58291.417085829446;58634.41365586375;58653.41346586565;58694.7065264671;58761;1350.6015725360846;1387.036009973059;1411.0686946615185;1411.4636438153436;1418.7162567486544;27814;29440;29510.409791803613;30284.940027098946;30659.386812264074;42883.571164287954;49850;49857.75071124621;49953;49953.50046499634;592057;596532;597195;598824;599863;38983.805080974416;40031.39952900767;40330.59669403268;40363;40501.79749101236;48041.27938080861;48276.034479309514;48767.0246595059;48936;49205.07949205051;39341.2131757369;40056.998575035854;40233.79883100683;40382;41136.38295425511;31266.687333126378;39004.02489937774;39630.40554391744;39859.20281594294;40209.3916243347;31274.53088203633;33310.5003424944;33979.66020339864;33985.66014339825;38983;27729.029483967963;34396.14009649678;34942;35066.29867402689;35153.945381638594;25540.744592553838;25561.616575751752;25737.485250295264;25984.220473385827;26303 20:29:54 INFO - PROCESS | 1930 | |2;jquery.html;376.91707824278666;377.34907284933496;383.39232316777895;393.49042989328854;408.66897812771657;18829.340973065944;20573.485662858435;20999;21247.36257912261;21282.042308096134;11545.307281563111;12114.909658130766;12119.878801211986;12682;21201.57596848062;5342.5993050521265;5618.775248990036;5642.097264437691;5643.971780141093;5645.379008309083;5364.785408583672;6167.321594624587;6212.192414986068;6219.751209951596;6231.252249730016;5929.614575052629;6188.47397971173;6188.81433556994;6194.163787888645;6196.411340922605;6109.938900610981;6240.87518249637;6242.4069713377385;6252.593581417239;6255.906161407583;4451.799669014902;5164.509371609691;5385.461453854622;5468.179773034037;5482.2873026506695;13061.608151755367;13112.606621801266;13202.73594528104;13203.537876174383;13212.471501139942;2235.6758270050914;2319.4433335999406;2345.1323010486058;2349.0251545608553;3004.53429718394;8950.597223124974;9409.717708468757;9897.208223342115;10108.696739097839;10281.228907831868;1793.7309403589566;1902.876313039648;1922.327185485069;1968.3897991622644;1980.7028945658124;89742.5512872438;120017.7998220024;120217.7978220224;127506;130123.34938325343;11212.66362009141;11611.419429028683;11630.592929247436;11685.941570292263;11866;14595.854041459663;14614.903882208877;14624.122552646875;14642.853571464362;14672.193029383487;8299.543525106177;8532;8581.485110893242;8686.739397818075;8718.997315308694;8399.202075802788;8428.8314233715;8435.283000944988;8607.827843443221;8686.609102590399;4428.158649856581;4518.502964673883;4521.59305662492;4598.678092533491;4611.930821037688;1624.7969003874516;1871.7941026487076;1933.1880610143774;1938.2634598852346;1944.319488179126;817.337956255435;821.6056292979284;875.6322344615277;910.8906931168169;970.4322971062007;20402.28591999273;21194;21316.680249796267;21320.680189797167;21408.764829169057;892.5001998880648;907.3875134284332;915.2174890468686;915.6612053540233;947.9052094790466;10643.574257029786;11024.028766973697;11142.27575207609;11163.162762792823;11184.496697648625;585756;601137;610096;616394;617656;80927.59536202281;83378.332433351;129748;139738;145714.27142864218;800.7117437722416;980.5048450532436;991.6777047459605;991.9156871665845;992.265723364705;18492.722609160603;18928.905355473136;18961.336353227573;19057.523561911064;19084.427467176007;53039;54915.07797227078;56232;56627;57229.427705724076;70699.18344263661;80361.59819200866;83004;83247.5837620808;83473;7712.383009359126;7943.960280198562;7986.560739159402;8382.874256886274;8401.243888050105;7056.294370562902;11188.496517656888;12823.807642885551;13346.733065338833;13416.731665366833;11151.386673733023;11152.16358773079;11350.602728904449;11386.089112870784;11409;10557.313774604627;10934.56261749521;11224.719382015188;11319.603813866475;11353.886461135284;8625.784355391166;10831.891681083089;11104.61133860311;11383.658490244974;11388.14588905852;7116.217216106328;7295.9860158611955;7447.478676492594;7631.084269887649;8277.668893244203;9370.531473426438;10259.794804104025;10829.18781091437;11281.661550153509;11697.181197316106 20:29:54 INFO - PROCESS | 1930 | |3;mootools.html;1374.1686279800715;1505.232331510929;1545.2428310128037;1728;1886.1983656945795;7829.060512738472;8363.330933525318;8369.12124226955;8374.665013399457;8378.20407061329;8129.187081291868;8533.658653653847;8737.388382813206;8742.606582703778;8858.15847494487;2338.9064437422485;2372.833901626887;2377.3699969508093;2379.9881000594974;2382.297222319411;1981.7621885373776;2298.6322188449853;2332.4518738096535;2356.1988923765916;2362.48025434405;2328.3364241191266;2352.717673879137;2358.174638876397;2367.4199821043867;2375.406148462884;2281.1217681192693;2317.2353123469215;2317.3279748872806;2323.5933711600487;2352.752960939094;2318.397216723647;2323.5933711600574;2328.2898715891647;2328.580855446019;2330.6154484510034;8719.476831390137;8892.8666070009;8919.464832110094;8924.55377231136;8935.151160639729;5161.974190129063;5208.8437346879655;5369.516743493065;5377.3009508764;5377.569794416438;2980.910572682823;3541.327147841902;3544.149404143013;3548.503209550665;3566.2154326048226;1071.7642118733866;1090.1006669497617;1099.1591432554103;1110.9888901110967;1113.0427832064418;12011.339376334297;12143.696407589792;12144.817827732591;12165.391730413445;12211.694707632292;4506.59440650343;4538.909221815546;4624.007008182879;4629.791659375336;4674.135284972291;9361.157495825408;9373.343865929319;9377.765555861022;9401.294902882311;9423.575939082757;5276.683398996072;5293.735313234323;5296.152615581488;5297.152455607084;5297.496737809979;4100.051446630222;4408.118376324684;4505.617022553054;4822.276658501253;4948.505149485023;3735.2716220337315;3751.8874433767032;3819.4722111155206;3852.9614703853163;3868.883933482;954.88063992001;1141.9143564232716;1153.8096214124744;1155.0932517973517;1155.92486488378;907.8275127725711;910.1944778870615;922.5802259971697;943.9427840818328;976.6679329028022;4927.408710954708;5784.421557844182;5850.678212698257;5967.76128954837;6256;357.4423898718008;438.97585632789867;440.30431917570803;456.2380824023888;462.7709283904489;3279.3769183854993;4106.357457017158;4139.751614903115;4191.664666826647;4262.808173632194;10774;11097.33415995043;11192.216544841784;11194.608188713355;11210.66368008961;11190.32858028521;11210.159238057178;11216.327020378803;11220.775584488429;11236.831447528293;7376.704931802775;8178.3048440882;8324.45891017094;8432.24109830106;8435.95782021086;5886.705664716833;6080.7567697291615;6091.933911565547;6208.751649933952;6263;10035.448050357008;10083.546240419346;10271.383716977005;10274.486275686037;10290.794184116126;8922.063183365653;9383.765405864908;9989.300748947504;10321.948390258001;10456.581736730446;7079;7695.038120234971;7759.961200193963;7880.2513761192595;7886.329661978567;7993.280604745601;8440.53577053268;8554.144585541397;8561.61472733741;8568.871466927876;7661.693532258647;7662.578558179353;7723.420743444377;7756.340711039625;7761.844763104819;7397.556146631219;7457.366123879531;7472.215417381097;7550;7572.356349710336;7046.859062818818;7093.113360829896;7158.821029474305;7165.498415110892;7174.282571742784;4958.25626156065;5102.260172275073;5116.437191908813;5126.7949282029795;5285.127953887565;4227.365895115758;5020.47285035066;5139.665921715076;5170.896582068262;5323.494268044529 20:29:54 INFO - PROCESS | 1930 | |4;prototype.html;297.7647658349904;300.77141372556855;312.8091863962983;314.33518109199053;319.75538712884645;5225.817096401626;5268.5521730652945;5288.471152884709;5298.788048478061;5303.454341915883;4862.100511405392;4885;5077.568406685427;5119.820806271781;5187.429382767892;3934.409838524223;3991.5210174779068;4004.7373234122774;4033.919321613566;4047.291723948312;3541.769784963969;3985.2228815381013;4014.237294913957;4028.516578010628;4073.5722749111214;3612.349777040132;3788.3938569828833;3959.722819402643;3985.880423587297;3990.9224509382593;3051.6643169251365;3342.8715536312634;3635.509206257161;3640.1445660269815;3942.48747662802;3440.4667276572172;3471.9652803471895;3480.3735327641016;3490.4938783876196;3508.912277193065;3951.360185523033;4171.707980441371;4484.282514797616;5065.696058236481;5135.332406787131;3651.5028838176486;4110.609492098246;4439.7367934568065;4549.7042692224895;4570.3373010913565;5491.505764481176;5511.724413779295;5515.310586176728;5522.392536820947;5568.359638641527;3354.630990591033;3822.48396466479;3843.4042723377643;3844.711646626486;3851.268259030775;5353.544948679367;5734.569907256972;5749.8275051748515;5795.971020144872;5835.183074369592;2928.868200930963;3614.7288953328607;3777.565579958285;3802.3345914465413;3830.578636349999;3902.1464804218335;3905.9804700976883;3906.3945088511327;3919.941200881989;3925.823337949799;3696.9075773105887;4498.785327961432;4536.4102666653125;4549.110510513529;4564.12870723002;5571.916421253684;5573.27547418833;5574.247476590608;5580.469855363734;5645.2096706461125;1188.7339982918338;1301.6550614087275;1346.9426500197299;1347.3600039980931;1352.1616597709483;809.2595275323131;837.0666706622194;860.7934095816959;874.6807415293372;896.9327300452492;4369.978150109229;4375.168717943644;4423.447069116361;4990.251462280687;5082.56037102193;4426.114777044604;5097.209932460474;5448.673079615236;5550.333959924754;5558.916616250759;4220.957790422118;4377.343398490252;4408.955910440919;4457.5706240099225;4985.102681517289;2299.9540009199723;2886.1095982131737;2959.334149816317;2964.288570743006;2971.6136902202575;5814.941850581525;5822.980978328776;5828.6794226317115;5829.647952427286;5836.970815145982;5190.740462976836;5466.808661696821;5774.249347584871;5836;5862.973979553561;4814.927776083362;4919.557239848359;5133.89621231437;5845.590808643524;5863.794767183128;5141.920196758722;5391.568674506108;5579.7489112990825;5727.0550359190265;5746.3966283541295;4855.523682921665;4875.829345972874;5247.685138891678;5583.705613687781;5632.464915832989;5802.941970580409;5824.883502330014;5829.883402331845;5923.703814809329;5929.746696255568;2872.3680790226117;3189.250526126405;3809.2381523694817;3826.5790763016603;3836.5396152461885;4493.91012179748;4884.314911355576;5521.199426083115;5702.6293290935955;5727.427257274406;4858.813533909825;5453.072977593739;5515;5547.089302042132;5586.217929489793;4488.092461453748;5115.1559992600805;5280.244533458548;5495.890082198253;5552.139418390156;5417.968261901787;5422.213779002102;5431.56547476193;5433.755481003285;5441.972790136024;4559.179347717442;5217.269582258411;5238.292830467838;5386;5431.049566325956;5428.430014848384;5438.755256013568;5444.918326225031;5455.263539422127;5456.290682211287 20:29:54 INFO - PROCESS | 1930 | |5;yui.html;718.2350796401831;722.3138018882061;777.0209535984661;813.463114344532;823.1439303124748;15046;16317.918410407961;16342.10118443485;16355.427560035401;16374.672506549861;14834.332455039523;15017.924910375461;15046.849531504682;15060.623484412896;15129.167895765728;695.5791745993677;713.2190251674434;861.939814028745;865.0354854337415;888.2449917570046;872.5593575244493;877.8068824858553;882.4396508217264;885.1458342699294;888.1296329596998;931.2596485793811;931.7111695374456;932.4778124250407;932.9860052099224;935.349931797401;921.617528725578;922.6678395777515;923.6213152607432;925.6343744220995;933.0343094896817;541.0477559495278;665.4277321503503;682.0757873081952;686.6841253023615;690.944724422045;14934.775978360334;14959.700805983819;14961.700765984619;14962.17708026058;14981.625459363495;12049.759004819854;13670.384832682554;13960.790588141186;14110.576682699535;14180.361883715257;10116.94941525295;10674;12904.16122952005;12977.480900763958;13422.194668319931;4234.428352172448;4311.008468052366;4608.8386906458445;4706.717596944194;4816.759162041884;106984;112199.43900280446;112338.43830780876;113528.29707554393;113649.43175284153;10871.83692244617;11013.339199648046;11101.500432480558;11251.662450126345;11462.598809041643;18229.270829166686;21999.010044548035;22117.33647990563;22146.78192699417;22152.22467213672;7800.648970796327;7800.960995194988;7845.333146682482;7939.3251573615735;8058.717944871902;4413.845515406945;4460.130274596488;4465.665075119379;4554.339620754972;4590.059037897223;3531.29374125176;3634.818259087035;3640.0535860676414;3647.015305867401;3647.8358473868207;554.9861253468615;661.9503537234726;672.0356288725596;672.4821887146869;673.7338751193251;564.8644325361884;610.954178436619;620.2618883528587;630.7540059376847;631.6115588912786;7392.630368481555;8221.753347399588;8680.739577812676;8837.779055523662;8892.688755893512;517.6971471689104;584.8531929421807;629.116091890894;631.5989346764837;638.9424951754364;4229.703920725519;4479.552044795494;4490.765039614133;4510;4549.408576884984;96453.5177324109;97916.53125203127;98408.80300057073;142823;149078;87186;87949;113238.43380783043;115584;126737.83155421003;73307.63346183235;94640.52679736695;107895;109625;111365;9384.76538086526;9943.801123977335;10020.34867733595;10063;10078.546465409221;46639;50343;54248;58127.70936145461;64153.67923160354;64577.70844582988;73053;76726.23273767415;82373.76439353294;97029.51485242529;11282.379469129277;12894.226346419246;13385.063045586716;13395;13576;12396.380180991095;14928.402863885323;15034.924825375803;15127.092374457568;15517.922410387875;14249.430022798972;14353.92823035878;14398.640033999234;14567.562973110824;14697.706045879237;11749.353785541878;13764.587062388144;14155.64610884736;14527.782083268548;14962.102273863604;11936.343501107522;11993.700157496132;12082.939585302018;12133.939330303292;12493.18794278369;10485.895141048491;11021.504032318726;11201.271917325348;11299.717507062389;11309.321440713584;10456.843147352947;11272.267302625303;11604.94197529007;11722.472488737856;11961.820572691242 20:29:54 INFO - PROCESS | 1930 | __end_tp_report 20:29:54 INFO - PROCESS | 1930 | __start_cc_report 20:29:54 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1151 20:29:54 INFO - PROCESS | 1930 | __end_cc_report 20:29:54 INFO - PROCESS | 1930 | __startTimestamp1470972594322__endTimestamp 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | ------- Summary: start ------- 20:29:54 INFO - PROCESS | 1930 | Number of tests: 6 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5630.11 Median:5838.94 stddev:643.07 (11.0%) stddev-sans-first:604.68 20:29:54 INFO - PROCESS | 1930 | Values: 2771.3 3322.3 3341.5 3343.2 3380.9 4987.2 5232.9 5439.5 5593.5 5598.8 5554.7 5556.5 5562.7 5565.2 5598.5 5088.9 5118.0 5147.2 5189.4 5203.2 5083.9 5166.9 5167.5 5184.0 5195.3 5073.8 5092.7 5113.1 5120.0 5171.6 5151.7 5172.9 5185.3 5198.6 5233.1 4982.4 5028.0 5032.9 5112.5 5115.1 5467.5 5535.2 5547.3 5547.4 5583.7 6098.7 6110.9 6128.2 6132.0 6136.1 5971.2 5977.2 6126.9 6198.5 6254.6 5698.3 5707.7 5758.8 5762.4 5773.4 6283.9 6357.4 6362.1 6364.4 6373.2 5615.1 5943.3 6037.7 6039.2 6051.0 5896.6 6230.5 6243.2 6258.7 6264.9 5458.9 6110.1 6119.3 6131.8 6154.3 5868.4 6102.5 6140.3 6142.8 6170.3 4786.5 4896.6 4905.0 4908.6 4910.0 4342.5 4406.9 4407.9 4420.6 4442.5 5517.6 5530.0 5543.6 5551.3 5590.5 6131.9 6185.3 6197.3 6201.8 6218.0 5432.6 5469.2 5506.3 5547.4 5564.4 4555.4 5385.4 5393.4 5403.7 5431.0 6317.7 6318.1 6331.6 6332.1 6351.6 5964.0 6005.7 6032.2 6075.1 6172.3 6292.2 6312.5 6318.5 6322.3 6339.9 5944.2 5958.8 5966.2 5976.6 6000.9 5948.6 5981.3 6053.0 6063.4 6083.3 5883.9 5901.7 5906.4 5925.0 5947.9 5864.5 5878.9 5893.4 5916.5 5918.6 5626.1 5764.8 5778.7 5781.7 5813.3 6016.4 6028.4 6051.5 6053.8 6104.8 5838.9 5841.2 5849.3 5860.1 5864.2 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:72712.51 Median:41135.49 stddev:160958.30 (391.3%) stddev-sans-first:161318.43 20:29:54 INFO - PROCESS | 1930 | Values: 469.6 500.5 513.2 516.2 520.4 31249.7 31554.9 40985.4 41702.6 41808.2 43270.6 43606.1 43651.1 43701.6 43711.0 9654.0 9813.8 9838.0 9844.3 9870.2 9738.6 9806.5 9806.5 9814.4 9817.0 9812.0 9880.6 9888.1 9893.5 9938.9 9777.2 9850.3 9850.7 9863.0 9863.9 9633.5 9701.9 9722.9 9741.4 9750.9 40745.0 41113.0 41134.6 41261.6 41316.6 3344.0 3430.9 3664.1 3715.1 3952.4 32289.0 32315.0 32473.7 32493.0 32561.3 3228.2 3268.5 3368.9 3374.6 3375.6 508131.5 510311.0 512545.0 515274.0 515579.0 56981.6 57168.4 57252.7 57277.0 57451.7 60150.0 60271.0 60328.4 60396.4 60401.7 16000.9 16134.4 16147.5 16170.8 16177.7 12691.1 12768.9 12776.4 12789.4 12823.4 12476.0 15052.3 15069.5 15070.5 15204.0 6161.7 6235.4 6265.3 6324.3 6336.0 744.3 888.9 939.8 1034.8 1079.3 29841.3 30158.8 30364.4 31100.8 31174.8 823.5 1034.1 1093.8 1153.0 1190.9 15481.8 15547.8 15621.8 15727.2 15960.8 667811.0 685589.0 686392.0 686964.0 689786.0 58291.4 58634.4 58653.4 58694.7 58761.0 1350.6 1387.0 1411.1 1411.5 1418.7 27814.0 29440.0 29510.4 30284.9 30659.4 42883.6 49850.0 49857.8 49953.0 49953.5 592057.0 596532.0 597195.0 598824.0 599863.0 38983.8 40031.4 40330.6 40363.0 40501.8 48041.3 48276.0 48767.0 48936.0 49205.1 39341.2 40057.0 40233.8 40382.0 41136.4 31266.7 39004.0 39630.4 39859.2 40209.4 31274.5 33310.5 33979.7 33985.7 38983.0 27729.0 34396.1 34942.0 35066.3 35153.9 25540.7 25561.6 25737.5 25984.2 26303.0 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:34476.87 Median:2418.41 stddev:101095.64 (4180.2%) stddev-sans-first:101346.82 20:29:54 INFO - PROCESS | 1930 | Values: 376.9 377.3 383.4 393.5 408.7 18829.3 20573.5 20999.0 21247.4 21282.0 11545.3 12114.9 12119.9 12682.0 21201.6 5342.6 5618.8 5642.1 5644.0 5645.4 5364.8 6167.3 6212.2 6219.8 6231.3 5929.6 6188.5 6188.8 6194.2 6196.4 6109.9 6240.9 6242.4 6252.6 6255.9 4451.8 5164.5 5385.5 5468.2 5482.3 13061.6 13112.6 13202.7 13203.5 13212.5 2235.7 2319.4 2345.1 2349.0 3004.5 8950.6 9409.7 9897.2 10108.7 10281.2 1793.7 1902.9 1922.3 1968.4 1980.7 89742.6 120017.8 120217.8 127506.0 130123.3 11212.7 11611.4 11630.6 11685.9 11866.0 14595.9 14614.9 14624.1 14642.9 14672.2 8299.5 8532.0 8581.5 8686.7 8719.0 8399.2 8428.8 8435.3 8607.8 8686.6 4428.2 4518.5 4521.6 4598.7 4611.9 1624.8 1871.8 1933.2 1938.3 1944.3 817.3 821.6 875.6 910.9 970.4 20402.3 21194.0 21316.7 21320.7 21408.8 892.5 907.4 915.2 915.7 947.9 10643.6 11024.0 11142.3 11163.2 11184.5 585756.0 601137.0 610096.0 616394.0 617656.0 80927.6 83378.3 129748.0 139738.0 145714.3 800.7 980.5 991.7 991.9 992.3 18492.7 18928.9 18961.3 19057.5 19084.4 53039.0 54915.1 56232.0 56627.0 57229.4 70699.2 80361.6 83004.0 83247.6 83473.0 7712.4 7944.0 7986.6 8382.9 8401.2 7056.3 11188.5 12823.8 13346.7 13416.7 11151.4 11152.2 11350.6 11386.1 11409.0 10557.3 10934.6 11224.7 11319.6 11353.9 8625.8 10831.9 11104.6 11383.7 11388.1 7116.2 7296.0 7447.5 7631.1 8277.7 9370.5 10259.8 10829.2 11281.7 11697.2 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5719.42 Median:4874.84 stddev:3286.29 (67.4%) stddev-sans-first:3279.28 20:29:54 INFO - PROCESS | 1930 | Values: 1374.2 1505.2 1545.2 1728.0 1886.2 7829.1 8363.3 8369.1 8374.7 8378.2 8129.2 8533.7 8737.4 8742.6 8858.2 2338.9 2372.8 2377.4 2380.0 2382.3 1981.8 2298.6 2332.5 2356.2 2362.5 2328.3 2352.7 2358.2 2367.4 2375.4 2281.1 2317.2 2317.3 2323.6 2352.8 2318.4 2323.6 2328.3 2328.6 2330.6 8719.5 8892.9 8919.5 8924.6 8935.2 5162.0 5208.8 5369.5 5377.3 5377.6 2980.9 3541.3 3544.1 3548.5 3566.2 1071.8 1090.1 1099.2 1111.0 1113.0 12011.3 12143.7 12144.8 12165.4 12211.7 4506.6 4538.9 4624.0 4629.8 4674.1 9361.2 9373.3 9377.8 9401.3 9423.6 5276.7 5293.7 5296.2 5297.2 5297.5 4100.1 4408.1 4505.6 4822.3 4948.5 3735.3 3751.9 3819.5 3853.0 3868.9 954.9 1141.9 1153.8 1155.1 1155.9 907.8 910.2 922.6 943.9 976.7 4927.4 5784.4 5850.7 5967.8 6256.0 357.4 439.0 440.3 456.2 462.8 3279.4 4106.4 4139.8 4191.7 4262.8 10774.0 11097.3 11192.2 11194.6 11210.7 11190.3 11210.2 11216.3 11220.8 11236.8 7376.7 8178.3 8324.5 8432.2 8436.0 5886.7 6080.8 6091.9 6208.8 6263.0 10035.4 10083.5 10271.4 10274.5 10290.8 8922.1 9383.8 9989.3 10321.9 10456.6 7079.0 7695.0 7760.0 7880.3 7886.3 7993.3 8440.5 8554.1 8561.6 8568.9 7661.7 7662.6 7723.4 7756.3 7761.8 7397.6 7457.4 7472.2 7550.0 7572.4 7046.9 7093.1 7158.8 7165.5 7174.3 4958.3 5102.3 5116.4 5126.8 5285.1 4227.4 5020.5 5139.7 5170.9 5323.5 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4410.29 Median:4902.28 stddev:1378.36 (28.1%) stddev-sans-first:1347.23 20:29:54 INFO - PROCESS | 1930 | Values: 297.8 300.8 312.8 314.3 319.8 5225.8 5268.6 5288.5 5298.8 5303.5 4862.1 4885.0 5077.6 5119.8 5187.4 3934.4 3991.5 4004.7 4033.9 4047.3 3541.8 3985.2 4014.2 4028.5 4073.6 3612.3 3788.4 3959.7 3985.9 3990.9 3051.7 3342.9 3635.5 3640.1 3942.5 3440.5 3472.0 3480.4 3490.5 3508.9 3951.4 4171.7 4484.3 5065.7 5135.3 3651.5 4110.6 4439.7 4549.7 4570.3 5491.5 5511.7 5515.3 5522.4 5568.4 3354.6 3822.5 3843.4 3844.7 3851.3 5353.5 5734.6 5749.8 5796.0 5835.2 2928.9 3614.7 3777.6 3802.3 3830.6 3902.1 3906.0 3906.4 3919.9 3925.8 3696.9 4498.8 4536.4 4549.1 4564.1 5571.9 5573.3 5574.2 5580.5 5645.2 1188.7 1301.7 1346.9 1347.4 1352.2 809.3 837.1 860.8 874.7 896.9 4370.0 4375.2 4423.4 4990.3 5082.6 4426.1 5097.2 5448.7 5550.3 5558.9 4221.0 4377.3 4409.0 4457.6 4985.1 2300.0 2886.1 2959.3 2964.3 2971.6 5814.9 5823.0 5828.7 5829.6 5837.0 5190.7 5466.8 5774.2 5836.0 5863.0 4814.9 4919.6 5133.9 5845.6 5863.8 5141.9 5391.6 5579.7 5727.1 5746.4 4855.5 4875.8 5247.7 5583.7 5632.5 5802.9 5824.9 5829.9 5923.7 5929.7 2872.4 3189.3 3809.2 3826.6 3836.5 4493.9 4884.3 5521.2 5702.6 5727.4 4858.8 5453.1 5515.0 5547.1 5586.2 4488.1 5115.2 5280.2 5495.9 5552.1 5418.0 5422.2 5431.6 5433.8 5442.0 4559.2 5217.3 5238.3 5386.0 5431.0 5428.4 5438.8 5444.9 5455.3 5456.3 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:22563.76 Median:3938.77 stddev:34439.24 (874.4%) stddev-sans-first:34496.79 20:29:54 INFO - PROCESS | 1930 | Values: 718.2 722.3 777.0 813.5 823.1 15046.0 16317.9 16342.1 16355.4 16374.7 14834.3 15017.9 15046.8 15060.6 15129.2 695.6 713.2 861.9 865.0 888.2 872.6 877.8 882.4 885.1 888.1 931.3 931.7 932.5 933.0 935.3 921.6 922.7 923.6 925.6 933.0 541.0 665.4 682.1 686.7 690.9 14934.8 14959.7 14961.7 14962.2 14981.6 12049.8 13670.4 13960.8 14110.6 14180.4 10116.9 10674.0 12904.2 12977.5 13422.2 4234.4 4311.0 4608.8 4706.7 4816.8 106984.0 112199.4 112338.4 113528.3 113649.4 10871.8 11013.3 11101.5 11251.7 11462.6 18229.3 21999.0 22117.3 22146.8 22152.2 7800.6 7801.0 7845.3 7939.3 8058.7 4413.8 4460.1 4465.7 4554.3 4590.1 3531.3 3634.8 3640.1 3647.0 3647.8 555.0 662.0 672.0 672.5 673.7 564.9 611.0 620.3 630.8 631.6 7392.6 8221.8 8680.7 8837.8 8892.7 517.7 584.9 629.1 631.6 638.9 4229.7 4479.6 4490.8 4510.0 4549.4 96453.5 97916.5 98408.8 142823.0 149078.0 87186.0 87949.0 113238.4 115584.0 126737.8 73307.6 94640.5 107895.0 109625.0 111365.0 9384.8 9943.8 10020.3 10063.0 10078.5 46639.0 50343.0 54248.0 58127.7 64153.7 64577.7 73053.0 76726.2 82373.8 97029.5 11282.4 12894.2 13385.1 13395.0 13576.0 12396.4 14928.4 15034.9 15127.1 15517.9 14249.4 14353.9 14398.6 14567.6 14697.7 11749.4 13764.6 14155.6 14527.8 14962.1 11936.3 11993.7 12082.9 12133.9 12493.2 10485.9 11021.5 11201.3 11299.7 11309.3 10456.8 11272.3 11604.9 11722.5 11961.8 20:29:54 INFO - PROCESS | 1930 | -------- Summary: end -------- 20:29:54 INFO - PROCESS | 1930 | 20:29:54 INFO - TEST-INFO | 1930: exit 0 20:29:54 INFO - TEST-OK | dromaeo_css | took 1117542ms 20:29:54 INFO - TEST-START | kraken 20:29:54 INFO - operating with platform_type : mac_ 20:29:54 INFO - Initialising browser for kraken test... 20:29:54 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpNaUQ1z/profile http://localhost:50881/getInfo.html) 20:29:57 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 20:29:57 INFO - PROCESS | 1936 | colorDepth:24 20:29:57 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 20:29:57 INFO - PROCESS | 1936 | __metrics 20:29:57 INFO - PROCESS | 1936 | 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" 20:29:57 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:29:58 INFO - TEST-INFO | 1936: exit 0 20:29:58 INFO - Browser initialized. 20:29:58 INFO - Running cycle 1/1 for kraken test... 20:29:58 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpNaUQ1z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:30:10 INFO - PROCESS | 1937 | RSS: Main: 243548160 20:30:10 INFO - PROCESS | 1937 | 20:30:12 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50881/tests/kraken/driver.html?testName=audio-beat-detection) 20:30:13 INFO - PROCESS | 1937 | RSS: Main: 293269504 20:30:13 INFO - PROCESS | 1937 | 20:30:16 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50881/tests/kraken/driver.html?testName=audio-dft) 20:30:16 INFO - PROCESS | 1937 | RSS: Main: 304795648 20:30:16 INFO - PROCESS | 1937 | 20:30:20 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50881/tests/kraken/driver.html?testName=audio-fft) 20:30:20 INFO - PROCESS | 1937 | RSS: Main: 394121216 20:30:20 INFO - PROCESS | 1937 | 20:30:22 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50881/tests/kraken/driver.html?testName=audio-oscillator) 20:30:23 INFO - PROCESS | 1937 | RSS: Main: 298041344 20:30:23 INFO - PROCESS | 1937 | 20:30:25 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50881/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:30:26 INFO - PROCESS | 1937 | RSS: Main: 302178304 20:30:26 INFO - PROCESS | 1937 | 20:30:28 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50881/tests/kraken/driver.html?testName=imaging-darkroom) 20:30:29 INFO - PROCESS | 1937 | RSS: Main: 338309120 20:30:29 INFO - PROCESS | 1937 | 20:30:32 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50881/tests/kraken/driver.html?testName=imaging-desaturate) 20:30:33 INFO - PROCESS | 1937 | RSS: Main: 341241856 20:30:33 INFO - PROCESS | 1937 | 20:30:36 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50881/tests/kraken/driver.html?testName=json-parse-financial) 20:30:36 INFO - PROCESS | 1937 | RSS: Main: 340283392 20:30:36 INFO - PROCESS | 1937 | 20:30:38 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50881/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:30:38 INFO - PROCESS | 1937 | RSS: Main: 316821504 20:30:38 INFO - PROCESS | 1937 | 20:30:40 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:30:41 INFO - PROCESS | 1937 | RSS: Main: 320569344 20:30:41 INFO - PROCESS | 1937 | 20:30:43 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:30:44 INFO - PROCESS | 1937 | RSS: Main: 325152768 20:30:44 INFO - PROCESS | 1937 | 20:30:47 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:30:47 INFO - PROCESS | 1937 | RSS: Main: 331132928 20:30:47 INFO - PROCESS | 1937 | 20:30:50 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:30:51 INFO - PROCESS | 1937 | RSS: Main: 332316672 20:30:51 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50881/tests/kraken/driver.html?testName=ai-astar) 20:30:53 INFO - PROCESS | 1937 | RSS: Main: 375582720 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | __start_tp_report 20:30:53 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 20:30:53 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 20:30:53 INFO - PROCESS | 1937 | |i|pagename|runs| 20:30:53 INFO - PROCESS | 1937 | |0;ai-astar;103;113;114;113;113;114;112;112;113;113 20:30:53 INFO - PROCESS | 1937 | |1;audio-beat-detection;144;106;107;165;107;106;126;106;139;108 20:30:53 INFO - PROCESS | 1937 | |2;audio-dft;183;185;186;186;185;182;182;183;183;186 20:30:53 INFO - PROCESS | 1937 | |3;audio-fft;90;57;58;85;56;56;63;65;71;59 20:30:53 INFO - PROCESS | 1937 | |4;audio-oscillator;86;67;100;71;71;68;97;70;68;103 20:30:53 INFO - PROCESS | 1937 | |5;imaging-gaussian-blur;87;85;87;85;86;84;90;84;88;84 20:30:53 INFO - PROCESS | 1937 | |6;imaging-darkroom;92;93;108;153;152;92;93;92;93;92 20:30:53 INFO - PROCESS | 1937 | |7;imaging-desaturate;85;87;81;87;84;84;85;116;135;85 20:30:53 INFO - PROCESS | 1937 | |8;json-parse-financial;55;50;52;52;54;52;53;53;51;52 20:30:53 INFO - PROCESS | 1937 | |9;json-stringify-tinderbox;67;67;73;72;72;78;73;70;73;73 20:30:53 INFO - PROCESS | 1937 | |10;stanford-crypto-aes;85;87;86;100;112;86;88;88;89;80 20:30:53 INFO - PROCESS | 1937 | |11;stanford-crypto-ccm;143;144;140;146;165;142;140;141;173;160 20:30:53 INFO - PROCESS | 1937 | |12;stanford-crypto-pbkdf2;179;179;181;175;176;179;177;175;177;180 20:30:53 INFO - PROCESS | 1937 | |13;stanford-crypto-sha256-iterative;69;69;72;87;74;68;69;68;67;67 20:30:53 INFO - PROCESS | 1937 | __end_tp_report 20:30:53 INFO - PROCESS | 1937 | __start_cc_report 20:30:53 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,2044 20:30:53 INFO - PROCESS | 1937 | __end_cc_report 20:30:53 INFO - PROCESS | 1937 | __startTimestamp1470972653495__endTimestamp 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | ------- Summary: start ------- 20:30:53 INFO - PROCESS | 1937 | Number of tests: 14 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.23 (2.9%) stddev-sans-first:0.71 20:30:53 INFO - PROCESS | 1937 | Values: 103.0 113.0 114.0 113.0 113.0 114.0 112.0 112.0 113.0 113.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:117.00 stddev:21.21 (18.1%) stddev-sans-first:20.86 20:30:53 INFO - PROCESS | 1937 | Values: 144.0 106.0 107.0 165.0 107.0 106.0 126.0 106.0 139.0 108.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:1.66 (0.9%) stddev-sans-first:1.72 20:30:53 INFO - PROCESS | 1937 | Values: 183.0 185.0 186.0 186.0 185.0 182.0 182.0 183.0 183.0 186.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#3] audio-fft Cycles:10 Average:66.00 Median:64.00 stddev:12.32 (19.2%) stddev-sans-first:9.53 20:30:53 INFO - PROCESS | 1937 | Values: 90.0 57.0 58.0 85.0 56.0 56.0 63.0 65.0 71.0 59.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:70.50 stddev:14.81 (21.0%) stddev-sans-first:15.55 20:30:53 INFO - PROCESS | 1937 | Values: 86.0 67.0 100.0 71.0 71.0 68.0 97.0 70.0 68.0 103.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:86.50 stddev:2.00 (2.3%) stddev-sans-first:2.09 20:30:53 INFO - PROCESS | 1937 | Values: 87.0 85.0 87.0 85.0 86.0 84.0 90.0 84.0 88.0 84.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:92.00 stddev:24.99 (27.2%) stddev-sans-first:25.99 20:30:53 INFO - PROCESS | 1937 | Values: 92.0 93.0 108.0 153.0 152.0 92.0 93.0 92.0 93.0 92.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:85.00 stddev:17.84 (21.0%) stddev-sans-first:18.69 20:30:53 INFO - PROCESS | 1937 | Values: 85.0 87.0 81.0 87.0 84.0 84.0 85.0 116.0 135.0 85.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:1.43 (2.7%) stddev-sans-first:1.17 20:30:53 INFO - PROCESS | 1937 | Values: 55.0 50.0 52.0 52.0 54.0 52.0 53.0 53.0 51.0 52.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:73.00 stddev:3.22 (4.4%) stddev-sans-first:2.92 20:30:53 INFO - PROCESS | 1937 | Values: 67.0 67.0 73.0 72.0 72.0 78.0 73.0 70.0 73.0 73.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.50 stddev:9.18 (10.6%) stddev-sans-first:9.55 20:30:53 INFO - PROCESS | 1937 | Values: 85.0 87.0 86.0 100.0 112.0 86.0 88.0 88.0 89.0 80.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:145.00 stddev:12.00 (8.3%) stddev-sans-first:12.50 20:30:53 INFO - PROCESS | 1937 | Values: 143.0 144.0 140.0 146.0 165.0 142.0 140.0 141.0 173.0 160.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:179.00 stddev:2.10 (1.2%) stddev-sans-first:2.18 20:30:53 INFO - PROCESS | 1937 | Values: 179.0 179.0 181.0 175.0 176.0 179.0 177.0 175.0 177.0 180.0 20:30:53 INFO - PROCESS | 1937 | 20:30:53 INFO - PROCESS | 1937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:69.00 stddev:6.04 (8.7%) stddev-sans-first:6.36 20:30:53 INFO - PROCESS | 1937 | Values: 69.0 69.0 72.0 87.0 74.0 68.0 69.0 68.0 67.0 67.0 20:30:53 INFO - PROCESS | 1937 | -------- Summary: end -------- 20:30:53 INFO - PROCESS | 1937 | 20:30:54 INFO - TEST-INFO | 1937: exit 0 20:30:54 INFO - TEST-OK | kraken | took 59385ms 20:30:54 INFO - SUITE-END | took 1176s 20:30:54 INFO - Completed test suite (00:19:37) 20:30:54 INFO - javascript benchmark 20:30:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2771.2933202033482, 3322.3189246204515, 3341.465365541513, 3343.1976325681844, 3380.8843081783016, 4987.202047672373, 5232.947670523294, 5439.5376393006545, 5593.468620481058, 5598.804041858531, 5554.666719996793, 5556.472135147154, 5562.666240025601, 5565.220869078333, 5598.4961353478175, 5088.8982220355665, 5118.027574760802, 5147.22791581262, 5189.429162792108, 5203.219517072451, 5083.872903177413, 5166.914947860953, 5167.457416971201, 5184.015037142931, 5195.272661827328, 5073.847784566469, 5092.719900405476, 5113.105206588851, 5119.974400128012, 5171.586273098162, 5151.742412879341, 5172.870678233036, 5185.274061631375, 5198.610104242197, 5233.136532472137, 4982.427020892607, 5028.044671512437, 5032.874178145539, 5112.46319136489, 5115.053715062688, 5467.507924286793, 5535.169724541311, 5547.3343198816, 5547.389787123413, 5583.6928968906695, 6098.700162976515, 6110.938890611126, 6128.172696685935, 6132.0070976932675, 6136.110264011693, 5971.193888824996, 5977.193078934375, 6126.9452004266695, 6198.535109866778, 6254.6247225166635, 5698.31620205578, 5707.686077265707, 5758.827235182951, 5762.394948530427, 5773.422657734193, 6283.905741413882, 6357.364263573606, 6362.077498762654, 6364.395382438661, 6373.235211774617, 5615.101583746663, 5943.257092863392, 6037.72830222641, 6039.245094363205, 6051.0, 5896.646201227854, 6230.532710046765, 6243.219597550306, 6258.687065646699, 6264.874702505925, 5458.8908221835345, 6110.083487476825, 6119.265688117454, 6131.785387511416, 6154.323024467305, 5868.383819698955, 6102.511799056065, 6140.293866205354, 6142.754289828356, 6170.290416602148, 4786.545278198566, 4896.559309662148, 4904.950950490521, 4908.631852611068, 4909.975450122726, 4342.500612429548, 4406.889827754268, 4407.911841763147, 4420.557944205554, 4442.4669039715445, 5517.61376703635, 5529.972350138304, 5543.556515478752, 5551.306086739158, 5590.5248053915875, 6131.877362452726, 6185.319614842364, 6197.349278325711, 6201.7519299228425, 6218.0, 5432.565394768409, 5469.206964990054, 5506.256655351556, 5547.362053363834, 5564.387917329091, 4555.362249285103, 5385.407605163429, 5393.4336894626285, 5403.675779453167, 5431.022415965164, 6317.684115794284, 6318.147050148171, 6331.620102793847, 6332.1135041094285, 6351.55539112267, 5963.970180149072, 6005.729742161526, 6032.245969253843, 6075.088736689532, 6172.321044685171, 6292.244930608356, 6312.4634406076, 6318.494520438446, 6322.33615470369, 6339.936600633934, 5944.227250457413, 5958.791442299499, 5966.224390829165, 5976.641401515923, 6000.939990600213, 5948.553858460719, 5981.2822461303185, 6053.031514957585, 6063.363346848518, 6083.330833608211, 5883.882322353615, 5901.704914754331, 5906.379830117776, 5924.9927512323875, 5947.881042379042, 5864.530837533072, 5878.94121058801, 5893.44012318829, 5916.467517923407, 5918.615290006136, 5626.071698169756, 5764.769409223584, 5778.653280803165, 5781.739821708117, 5813.331466881363, 6016.398360163948, 6028.4272994065495, 6051.5461340400525, 6053.788117416046, 6104.847378815388, 5838.94161058384, 5841.18223448726, 5849.298084229919, 5860.09168578871, 5864.237649105589], "value": 5587.827996205792, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.5867636479897, 500.5445045009042, 513.2327170879536, 516.1844286028077, 520.4041372628338, 31249.687503124962, 31554.895578654723, 40985.3852192217, 41702.58297417033, 41808.163836723244, 43270.56729432705, 43606.12787744251, 43651.12697746043, 43701.562984370146, 43711.0, 9654.0, 9813.754656133582, 9837.950810245975, 9844.261680374, 9870.161036311887, 9738.610455581767, 9806.460644664541, 9806.509674516281, 9814.362066465657, 9817.0, 9812.0, 9880.555375008105, 9888.060634239735, 9893.4558599277, 9938.850917236246, 9777.217822574212, 9850.261230407748, 9850.655227067018, 9863.01369863008, 9863.90136098637, 9633.518324083769, 9701.90298097017, 9722.854157187649, 9741.366811157252, 9750.853737193947, 40745.0, 41112.97217569555, 41134.58865411337, 41261.587384126076, 41316.58683413157, 3344.0, 3430.9313813723834, 3664.0656632558794, 3715.145516531186, 3952.383803308786, 32289.0, 32315.0, 32473.6752632473, 32493.0, 32561.348773024405, 3228.170221674982, 3268.4770436730246, 3368.898933032013, 3374.6119196292248, 3375.6118046424485, 508131.4593427046, 510311.0, 512545.0, 515274.0, 515579.0, 56981.58674925876, 57168.42831571631, 57252.713736431055, 57277.0, 57451.71274143686, 60150.0, 60271.0, 60328.39671603315, 60396.39603603996, 60401.69799150976, 16000.8799384042, 16134.35462581507, 16147.515574532783, 16170.838291617169, 16177.676446471005, 12691.111622186543, 12768.872311276955, 12776.361181940865, 12789.424475898606, 12823.422945967453, 12476.0, 15052.261356318337, 15069.47256846027, 15070.547883563511, 15204.0, 6161.691915404212, 6235.407636274546, 6265.27949285838, 6324.26868147795, 6336.0, 744.2966396755014, 888.9377743557889, 939.8496240601471, 1034.8240799064026, 1079.3254216114929, 29841.253968650522, 30158.84920575383, 30364.392712145633, 31100.844495777375, 31174.844125779226, 823.5429336718179, 1034.095166729112, 1093.7648405592836, 1152.9711757206137, 1190.8570971483477, 15481.767773483407, 15547.766783498257, 15621.843781562038, 15727.213639318217, 15960.760588590949, 667811.0, 685589.0, 686392.0, 686964.0, 689786.0, 58291.417085829446, 58634.41365586375, 58653.41346586565, 58694.7065264671, 58761.0, 1350.6015725360846, 1387.036009973059, 1411.0686946615185, 1411.4636438153436, 1418.7162567486544, 27814.0, 29440.0, 29510.409791803613, 30284.940027098946, 30659.386812264074, 42883.571164287954, 49850.0, 49857.75071124621, 49953.0, 49953.50046499634, 592057.0, 596532.0, 597195.0, 598824.0, 599863.0, 38983.805080974416, 40031.39952900767, 40330.59669403268, 40363.0, 40501.79749101236, 48041.27938080861, 48276.034479309514, 48767.0246595059, 48936.0, 49205.07949205051, 39341.2131757369, 40056.998575035854, 40233.79883100683, 40382.0, 41136.38295425511, 31266.687333126378, 39004.02489937774, 39630.40554391744, 39859.20281594294, 40209.3916243347, 31274.53088203633, 33310.5003424944, 33979.66020339864, 33985.66014339825, 38983.0, 27729.029483967963, 34396.14009649678, 34942.0, 35066.29867402689, 35153.945381638594, 25540.744592553838, 25561.616575751752, 25737.485250295264, 25984.220473385827, 26303.0], "value": 20085.69141491586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.91707824278666, 377.34907284933496, 383.39232316777895, 393.49042989328854, 408.66897812771657, 18829.340973065944, 20573.485662858435, 20999.0, 21247.36257912261, 21282.042308096134, 11545.307281563111, 12114.909658130766, 12119.878801211986, 12682.0, 21201.57596848062, 5342.5993050521265, 5618.775248990036, 5642.097264437691, 5643.971780141093, 5645.379008309083, 5364.785408583672, 6167.321594624587, 6212.192414986068, 6219.751209951596, 6231.252249730016, 5929.614575052629, 6188.47397971173, 6188.81433556994, 6194.163787888645, 6196.411340922605, 6109.938900610981, 6240.87518249637, 6242.4069713377385, 6252.593581417239, 6255.906161407583, 4451.799669014902, 5164.509371609691, 5385.461453854622, 5468.179773034037, 5482.2873026506695, 13061.608151755367, 13112.606621801266, 13202.73594528104, 13203.537876174383, 13212.471501139942, 2235.6758270050914, 2319.4433335999406, 2345.1323010486058, 2349.0251545608553, 3004.53429718394, 8950.597223124974, 9409.717708468757, 9897.208223342115, 10108.696739097839, 10281.228907831868, 1793.7309403589566, 1902.876313039648, 1922.327185485069, 1968.3897991622644, 1980.7028945658124, 89742.5512872438, 120017.7998220024, 120217.7978220224, 127506.0, 130123.34938325343, 11212.66362009141, 11611.419429028683, 11630.592929247436, 11685.941570292263, 11866.0, 14595.854041459663, 14614.903882208877, 14624.122552646875, 14642.853571464362, 14672.193029383487, 8299.543525106177, 8532.0, 8581.485110893242, 8686.739397818075, 8718.997315308694, 8399.202075802788, 8428.8314233715, 8435.283000944988, 8607.827843443221, 8686.609102590399, 4428.158649856581, 4518.502964673883, 4521.59305662492, 4598.678092533491, 4611.930821037688, 1624.7969003874516, 1871.7941026487076, 1933.1880610143774, 1938.2634598852346, 1944.319488179126, 817.337956255435, 821.6056292979284, 875.6322344615277, 910.8906931168169, 970.4322971062007, 20402.28591999273, 21194.0, 21316.680249796267, 21320.680189797167, 21408.764829169057, 892.5001998880648, 907.3875134284332, 915.2174890468686, 915.6612053540233, 947.9052094790466, 10643.574257029786, 11024.028766973697, 11142.27575207609, 11163.162762792823, 11184.496697648625, 585756.0, 601137.0, 610096.0, 616394.0, 617656.0, 80927.59536202281, 83378.332433351, 129748.0, 139738.0, 145714.27142864218, 800.7117437722416, 980.5048450532436, 991.6777047459605, 991.9156871665845, 992.265723364705, 18492.722609160603, 18928.905355473136, 18961.336353227573, 19057.523561911064, 19084.427467176007, 53039.0, 54915.07797227078, 56232.0, 56627.0, 57229.427705724076, 70699.18344263661, 80361.59819200866, 83004.0, 83247.5837620808, 83473.0, 7712.383009359126, 7943.960280198562, 7986.560739159402, 8382.874256886274, 8401.243888050105, 7056.294370562902, 11188.496517656888, 12823.807642885551, 13346.733065338833, 13416.731665366833, 11151.386673733023, 11152.16358773079, 11350.602728904449, 11386.089112870784, 11409.0, 10557.313774604627, 10934.56261749521, 11224.719382015188, 11319.603813866475, 11353.886461135284, 8625.784355391166, 10831.891681083089, 11104.61133860311, 11383.658490244974, 11388.14588905852, 7116.217216106328, 7295.9860158611955, 7447.478676492594, 7631.084269887649, 8277.668893244203, 9370.531473426438, 10259.794804104025, 10829.18781091437, 11281.661550153509, 11697.181197316106], "value": 9242.646878637448, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.1686279800715, 1505.232331510929, 1545.2428310128037, 1728.0, 1886.1983656945795, 7829.060512738472, 8363.330933525318, 8369.12124226955, 8374.665013399457, 8378.20407061329, 8129.187081291868, 8533.658653653847, 8737.388382813206, 8742.606582703778, 8858.15847494487, 2338.9064437422485, 2372.833901626887, 2377.3699969508093, 2379.9881000594974, 2382.297222319411, 1981.7621885373776, 2298.6322188449853, 2332.4518738096535, 2356.1988923765916, 2362.48025434405, 2328.3364241191266, 2352.717673879137, 2358.174638876397, 2367.4199821043867, 2375.406148462884, 2281.1217681192693, 2317.2353123469215, 2317.3279748872806, 2323.5933711600487, 2352.752960939094, 2318.397216723647, 2323.5933711600574, 2328.2898715891647, 2328.580855446019, 2330.6154484510034, 8719.476831390137, 8892.8666070009, 8919.464832110094, 8924.55377231136, 8935.151160639729, 5161.974190129063, 5208.8437346879655, 5369.516743493065, 5377.3009508764, 5377.569794416438, 2980.910572682823, 3541.327147841902, 3544.149404143013, 3548.503209550665, 3566.2154326048226, 1071.7642118733866, 1090.1006669497617, 1099.1591432554103, 1110.9888901110967, 1113.0427832064418, 12011.339376334297, 12143.696407589792, 12144.817827732591, 12165.391730413445, 12211.694707632292, 4506.59440650343, 4538.909221815546, 4624.007008182879, 4629.791659375336, 4674.135284972291, 9361.157495825408, 9373.343865929319, 9377.765555861022, 9401.294902882311, 9423.575939082757, 5276.683398996072, 5293.735313234323, 5296.152615581488, 5297.152455607084, 5297.496737809979, 4100.051446630222, 4408.118376324684, 4505.617022553054, 4822.276658501253, 4948.505149485023, 3735.2716220337315, 3751.8874433767032, 3819.4722111155206, 3852.9614703853163, 3868.883933482, 954.88063992001, 1141.9143564232716, 1153.8096214124744, 1155.0932517973517, 1155.92486488378, 907.8275127725711, 910.1944778870615, 922.5802259971697, 943.9427840818328, 976.6679329028022, 4927.408710954708, 5784.421557844182, 5850.678212698257, 5967.76128954837, 6256.0, 357.4423898718008, 438.97585632789867, 440.30431917570803, 456.2380824023888, 462.7709283904489, 3279.3769183854993, 4106.357457017158, 4139.751614903115, 4191.664666826647, 4262.808173632194, 10774.0, 11097.33415995043, 11192.216544841784, 11194.608188713355, 11210.66368008961, 11190.32858028521, 11210.159238057178, 11216.327020378803, 11220.775584488429, 11236.831447528293, 7376.704931802775, 8178.3048440882, 8324.45891017094, 8432.24109830106, 8435.95782021086, 5886.705664716833, 6080.7567697291615, 6091.933911565547, 6208.751649933952, 6263.0, 10035.448050357008, 10083.546240419346, 10271.383716977005, 10274.486275686037, 10290.794184116126, 8922.063183365653, 9383.765405864908, 9989.300748947504, 10321.948390258001, 10456.581736730446, 7079.0, 7695.038120234971, 7759.961200193963, 7880.2513761192595, 7886.329661978567, 7993.280604745601, 8440.53577053268, 8554.144585541397, 8561.61472733741, 8568.871466927876, 7661.693532258647, 7662.578558179353, 7723.420743444377, 7756.340711039625, 7761.844763104819, 7397.556146631219, 7457.366123879531, 7472.215417381097, 7550.0, 7572.356349710336, 7046.859062818818, 7093.113360829896, 7158.821029474305, 7165.498415110892, 7174.282571742784, 4958.25626156065, 5102.260172275073, 5116.437191908813, 5126.7949282029795, 5285.127953887565, 4227.365895115758, 5020.47285035066, 5139.665921715076, 5170.896582068262, 5323.494268044529], "value": 4483.626926283198, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.7647658349904, 300.77141372556855, 312.8091863962983, 314.33518109199053, 319.75538712884645, 5225.817096401626, 5268.5521730652945, 5288.471152884709, 5298.788048478061, 5303.454341915883, 4862.100511405392, 4885.0, 5077.568406685427, 5119.820806271781, 5187.429382767892, 3934.409838524223, 3991.5210174779068, 4004.7373234122774, 4033.919321613566, 4047.291723948312, 3541.769784963969, 3985.2228815381013, 4014.237294913957, 4028.516578010628, 4073.5722749111214, 3612.349777040132, 3788.3938569828833, 3959.722819402643, 3985.880423587297, 3990.9224509382593, 3051.6643169251365, 3342.8715536312634, 3635.509206257161, 3640.1445660269815, 3942.48747662802, 3440.4667276572172, 3471.9652803471895, 3480.3735327641016, 3490.4938783876196, 3508.912277193065, 3951.360185523033, 4171.707980441371, 4484.282514797616, 5065.696058236481, 5135.332406787131, 3651.5028838176486, 4110.609492098246, 4439.7367934568065, 4549.7042692224895, 4570.3373010913565, 5491.505764481176, 5511.724413779295, 5515.310586176728, 5522.392536820947, 5568.359638641527, 3354.630990591033, 3822.48396466479, 3843.4042723377643, 3844.711646626486, 3851.268259030775, 5353.544948679367, 5734.569907256972, 5749.8275051748515, 5795.971020144872, 5835.183074369592, 2928.868200930963, 3614.7288953328607, 3777.565579958285, 3802.3345914465413, 3830.578636349999, 3902.1464804218335, 3905.9804700976883, 3906.3945088511327, 3919.941200881989, 3925.823337949799, 3696.9075773105887, 4498.785327961432, 4536.4102666653125, 4549.110510513529, 4564.12870723002, 5571.916421253684, 5573.27547418833, 5574.247476590608, 5580.469855363734, 5645.2096706461125, 1188.7339982918338, 1301.6550614087275, 1346.9426500197299, 1347.3600039980931, 1352.1616597709483, 809.2595275323131, 837.0666706622194, 860.7934095816959, 874.6807415293372, 896.9327300452492, 4369.978150109229, 4375.168717943644, 4423.447069116361, 4990.251462280687, 5082.56037102193, 4426.114777044604, 5097.209932460474, 5448.673079615236, 5550.333959924754, 5558.916616250759, 4220.957790422118, 4377.343398490252, 4408.955910440919, 4457.5706240099225, 4985.102681517289, 2299.9540009199723, 2886.1095982131737, 2959.334149816317, 2964.288570743006, 2971.6136902202575, 5814.941850581525, 5822.980978328776, 5828.6794226317115, 5829.647952427286, 5836.970815145982, 5190.740462976836, 5466.808661696821, 5774.249347584871, 5836.0, 5862.973979553561, 4814.927776083362, 4919.557239848359, 5133.89621231437, 5845.590808643524, 5863.794767183128, 5141.920196758722, 5391.568674506108, 5579.7489112990825, 5727.0550359190265, 5746.3966283541295, 4855.523682921665, 4875.829345972874, 5247.685138891678, 5583.705613687781, 5632.464915832989, 5802.941970580409, 5824.883502330014, 5829.883402331845, 5923.703814809329, 5929.746696255568, 2872.3680790226117, 3189.250526126405, 3809.2381523694817, 3826.5790763016603, 3836.5396152461885, 4493.91012179748, 4884.314911355576, 5521.199426083115, 5702.6293290935955, 5727.427257274406, 4858.813533909825, 5453.072977593739, 5515.0, 5547.089302042132, 5586.217929489793, 4488.092461453748, 5115.1559992600805, 5280.244533458548, 5495.890082198253, 5552.139418390156, 5417.968261901787, 5422.213779002102, 5431.56547476193, 5433.755481003285, 5441.972790136024, 4559.179347717442, 5217.269582258411, 5238.292830467838, 5386.0, 5431.049566325956, 5428.430014848384, 5438.755256013568, 5444.918326225031, 5455.263539422127, 5456.290682211287], "value": 3977.227896716288, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.2350796401831, 722.3138018882061, 777.0209535984661, 813.463114344532, 823.1439303124748, 15046.0, 16317.918410407961, 16342.10118443485, 16355.427560035401, 16374.672506549861, 14834.332455039523, 15017.924910375461, 15046.849531504682, 15060.623484412896, 15129.167895765728, 695.5791745993677, 713.2190251674434, 861.939814028745, 865.0354854337415, 888.2449917570046, 872.5593575244493, 877.8068824858553, 882.4396508217264, 885.1458342699294, 888.1296329596998, 931.2596485793811, 931.7111695374456, 932.4778124250407, 932.9860052099224, 935.349931797401, 921.617528725578, 922.6678395777515, 923.6213152607432, 925.6343744220995, 933.0343094896817, 541.0477559495278, 665.4277321503503, 682.0757873081952, 686.6841253023615, 690.944724422045, 14934.775978360334, 14959.700805983819, 14961.700765984619, 14962.17708026058, 14981.625459363495, 12049.759004819854, 13670.384832682554, 13960.790588141186, 14110.576682699535, 14180.361883715257, 10116.94941525295, 10674.0, 12904.16122952005, 12977.480900763958, 13422.194668319931, 4234.428352172448, 4311.008468052366, 4608.8386906458445, 4706.717596944194, 4816.759162041884, 106984.0, 112199.43900280446, 112338.43830780876, 113528.29707554393, 113649.43175284153, 10871.83692244617, 11013.339199648046, 11101.500432480558, 11251.662450126345, 11462.598809041643, 18229.270829166686, 21999.010044548035, 22117.33647990563, 22146.78192699417, 22152.22467213672, 7800.648970796327, 7800.960995194988, 7845.333146682482, 7939.3251573615735, 8058.717944871902, 4413.845515406945, 4460.130274596488, 4465.665075119379, 4554.339620754972, 4590.059037897223, 3531.29374125176, 3634.818259087035, 3640.0535860676414, 3647.015305867401, 3647.8358473868207, 554.9861253468615, 661.9503537234726, 672.0356288725596, 672.4821887146869, 673.7338751193251, 564.8644325361884, 610.954178436619, 620.2618883528587, 630.7540059376847, 631.6115588912786, 7392.630368481555, 8221.753347399588, 8680.739577812676, 8837.779055523662, 8892.688755893512, 517.6971471689104, 584.8531929421807, 629.116091890894, 631.5989346764837, 638.9424951754364, 4229.703920725519, 4479.552044795494, 4490.765039614133, 4510.0, 4549.408576884984, 96453.5177324109, 97916.53125203127, 98408.80300057073, 142823.0, 149078.0, 87186.0, 87949.0, 113238.43380783043, 115584.0, 126737.83155421003, 73307.63346183235, 94640.52679736695, 107895.0, 109625.0, 111365.0, 9384.76538086526, 9943.801123977335, 10020.34867733595, 10063.0, 10078.546465409221, 46639.0, 50343.0, 54248.0, 58127.70936145461, 64153.67923160354, 64577.70844582988, 73053.0, 76726.23273767415, 82373.76439353294, 97029.51485242529, 11282.379469129277, 12894.226346419246, 13385.063045586716, 13395.0, 13576.0, 12396.380180991095, 14928.402863885323, 15034.924825375803, 15127.092374457568, 15517.922410387875, 14249.430022798972, 14353.92823035878, 14398.640033999234, 14567.562973110824, 14697.706045879237, 11749.353785541878, 13764.587062388144, 14155.64610884736, 14527.782083268548, 14962.102273863604, 11936.343501107522, 11993.700157496132, 12082.939585302018, 12133.939330303292, 12493.18794278369, 10485.895141048491, 11021.504032318726, 11201.271917325348, 11299.717507062389, 11309.321440713584, 10456.843147352947, 11272.267302625303, 11604.94197529007, 11722.472488737856, 11961.820572691242], "value": 7685.620866590157, "unit": "score"}], "name": "dromaeo_css", "value": 7224.545766379784, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 113.0, 113.0, 114.0, 112.0, 112.0, 113.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 106.0, 107.0, 165.0, 107.0, 106.0, 126.0, 106.0, 139.0, 108.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 186.0, 186.0, 185.0, 182.0, 182.0, 183.0, 183.0, 186.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 57.0, 58.0, 85.0, 56.0, 56.0, 63.0, 65.0, 71.0, 59.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 67.0, 100.0, 71.0, 71.0, 68.0, 97.0, 70.0, 68.0, 103.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 86.0, 84.0, 90.0, 84.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 108.0, 153.0, 152.0, 92.0, 93.0, 92.0, 93.0, 92.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 81.0, 87.0, 84.0, 84.0, 85.0, 116.0, 135.0, 85.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 50.0, 52.0, 52.0, 54.0, 52.0, 53.0, 53.0, 51.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 73.0, 72.0, 72.0, 78.0, 73.0, 70.0, 73.0, 73.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 100.0, 112.0, 86.0, 88.0, 88.0, 89.0, 80.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 140.0, 146.0, 165.0, 142.0, 140.0, 141.0, 173.0, 160.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 181.0, 175.0, 176.0, 179.0, 177.0, 175.0, 177.0, 180.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 72.0, 87.0, 74.0, 68.0, 69.0, 68.0, 67.0, 67.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1461.0, "alertThreshold": 2.0}]} 20:30:54 INFO - Return code: 0 20:30:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:30:54 INFO - # TBPL SUCCESS # 20:30:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:30:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:30:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:30:54 INFO - 2016-08-11 20:30:54.819 screenresolution[1942:14342] starting screenresolution argv=screenresolution get 20:30:54 INFO - 2016-08-11 20:30:54.821 screenresolution[1942:14342] Display 0: 1600x1200x32@60 20:30:54 INFO - 2016-08-11 20:30:54.827 screenresolution[1943:14346] starting screenresolution argv=screenresolution list 20:30:54 INFO - Available Modes on Display 0 20:30:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:30:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:30:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:30:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:30:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:30:55 INFO - Intel Iris: 20:30:55 INFO - Chipset Model: Intel Iris 20:30:55 INFO - Type: GPU 20:30:55 INFO - Bus: Built-In 20:30:55 INFO - VRAM (Dynamic, Max): 1536 MB 20:30:55 INFO - Vendor: Intel (0x8086) 20:30:55 INFO - Device ID: 0x0a2e 20:30:55 INFO - Revision ID: 0x0009 20:30:55 INFO - Displays: 20:30:55 INFO - Display: 20:30:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:30:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:30:55 INFO - Display Serial Number: 1600x1200 60 20:30:55 INFO - Main Display: Yes 20:30:55 INFO - Mirror: Off 20:30:55 INFO - Online: Yes 20:30:55 INFO - Rotation: Supported 20:30:55 INFO - Return code: 0 20:30:55 INFO - Running post-action listener: _resource_record_post_action 20:30:55 INFO - [mozharness: 2016-08-12 03:30:55.061050Z] Finished run-tests step (success) 20:30:55 INFO - Running post-run listener: _resource_record_post_run 20:30:55 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 1114112; Write bytes: 704818688; Read time: 12; Write time: 2024 20:30:55 INFO - TinderboxPrint: CPU usage
53.4% 20:30:55 INFO - TinderboxPrint: I/O read bytes / time
1,114,112 / 12 20:30:55 INFO - TinderboxPrint: I/O write bytes / time
704,818,688 / 2,024 20:30:55 INFO - TinderboxPrint: CPU idle
2,257.3 (46.6%) 20:30:55 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 20:30:55 INFO - TinderboxPrint: CPU user
1,619.7 (33.4%) 20:30:55 INFO - TinderboxPrint: Swap in / out
405,647,360 / 0 20:30:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382630400; Write bytes: 377476608; Read time: 28162; Write time: 840 20:30:55 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 1114112; Write bytes: 297711616; Read time: 12; Write time: 1115 20:30:55 INFO - Running post-run listener: _upload_blobber_files 20:30:55 INFO - Blob upload gear active. 20:30:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:30:55 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 20:30:55 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:30:55 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:30:55 INFO - (blobuploader) - INFO - Open directory for files ... 20:30:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:30:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:30:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:56 INFO - (blobuploader) - INFO - Done attempting. 20:30:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:30:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:57 INFO - (blobuploader) - INFO - Done attempting. 20:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:30:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:59 INFO - (blobuploader) - INFO - Done attempting. 20:30:59 INFO - (blobuploader) - INFO - Iteration through files over. 20:30:59 INFO - Return code: 0 20:30:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:30:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:30:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cc5ccc492cf9be15ade34f343fbb4da6712a1728f2d608ad871a603c858ba48c316a06c44b045247c92a62d3100f9632996b4653993f4b6d82dd8b98b248951", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c37177605c8f17a56535014d8e0f028e32fec97f2295a368f2d19a40fc70082a5ce7394ba2d3963afffa653bcab630d0e2f1316f439acf4a390d82761a7984c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc1c12397c469c850455dd4c8f34dddc6ea6d3aa4f506b39704593ae55f7b1510476f2646520b371f0a01dc3cf9aac784ab1d7e9e13c7169d150cd86b00d1e27"} 20:30:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:30:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:30:59 INFO - Contents: 20:30:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cc5ccc492cf9be15ade34f343fbb4da6712a1728f2d608ad871a603c858ba48c316a06c44b045247c92a62d3100f9632996b4653993f4b6d82dd8b98b248951", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c37177605c8f17a56535014d8e0f028e32fec97f2295a368f2d19a40fc70082a5ce7394ba2d3963afffa653bcab630d0e2f1316f439acf4a390d82761a7984c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc1c12397c469c850455dd4c8f34dddc6ea6d3aa4f506b39704593ae55f7b1510476f2646520b371f0a01dc3cf9aac784ab1d7e9e13c7169d150cd86b00d1e27"} 20:30:59 INFO - Running post-run listener: copy_logs_to_upload_dir 20:30:59 INFO - Copying logs to upload dir... 20:30:59 INFO - mkdir: /builds/slave/test/build/upload/logs 20:30:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.260973 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-11 20:31:00.021553) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 20:31:00.025427) ========= 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.TPi2J0zjTm/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.ivHytiOGHD/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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/Autoland/sha512/4cc5ccc492cf9be15ade34f343fbb4da6712a1728f2d608ad871a603c858ba48c316a06c44b045247c92a62d3100f9632996b4653993f4b6d82dd8b98b248951", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c37177605c8f17a56535014d8e0f028e32fec97f2295a368f2d19a40fc70082a5ce7394ba2d3963afffa653bcab630d0e2f1316f439acf4a390d82761a7984c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc1c12397c469c850455dd4c8f34dddc6ea6d3aa4f506b39704593ae55f7b1510476f2646520b371f0a01dc3cf9aac784ab1d7e9e13c7169d150cd86b00d1e27"} build_url:https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/X41KY8dhQNCx4d7_-lSZBg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cc5ccc492cf9be15ade34f343fbb4da6712a1728f2d608ad871a603c858ba48c316a06c44b045247c92a62d3100f9632996b4653993f4b6d82dd8b98b248951", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c37177605c8f17a56535014d8e0f028e32fec97f2295a368f2d19a40fc70082a5ce7394ba2d3963afffa653bcab630d0e2f1316f439acf4a390d82761a7984c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bc1c12397c469c850455dd4c8f34dddc6ea6d3aa4f506b39704593ae55f7b1510476f2646520b371f0a01dc3cf9aac784ab1d7e9e13c7169d150cd86b00d1e27"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 20:31:00.059844) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 20:31:00.060448) ========= 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.TPi2J0zjTm/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.ivHytiOGHD/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.005186 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 20:31:00.111114) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 20:31:00.111551) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 20:31:00.112072) ========= ========= Total master_lag: 0.18 =========