builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1471300434.66 results: success (0) buildid: 20160815124617 builduid: 13efb8ff66d74fcd800e78af9bffc413 revision: 90418dcd93dbcbe0166653878e04126df0712eba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.661873) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.662355) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.662666) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.688797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.689210) ========= 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.jU41PqdMkq/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.2MGnIM2cax/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-15 15:33:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.08M=0.001s 2016-08-15 15:33:54 (9.08 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124696 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.835528) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.835860) ========= 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.jU41PqdMkq/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.2MGnIM2cax/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.020514 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:54.884529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 15:33:54.884901) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 90418dcd93dbcbe0166653878e04126df0712eba --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 90418dcd93dbcbe0166653878e04126df0712eba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jU41PqdMkq/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.2MGnIM2cax/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-15 15:33:54,967 truncating revision to first 12 chars 2016-08-15 15:33:54,967 Setting DEBUG logging. 2016-08-15 15:33:54,967 attempt 1/10 2016-08-15 15:33:54,967 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/90418dcd93db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 15:33:56,257 unpacking tar archive at: autoland-90418dcd93db/testing/mozharness/ program finished with exit code 0 elapsedTime=1.552347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 15:33:56.459665) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:56.460031) ========= script_repo_revision: 90418dcd93dbcbe0166653878e04126df0712eba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:56.460424) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:56.460704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 15:33:56.479687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-15 15:33:56.479982) ========= /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.jU41PqdMkq/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.2MGnIM2cax/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:33:56 INFO - MultiFileLogger online at 20160815 15:33:56 in /builds/slave/test 15:33:56 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 15:33:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:33:56 INFO - {'append_to_log': False, 15:33:56 INFO - 'base_work_dir': '/builds/slave/test', 15:33:56 INFO - 'blob_upload_branch': 'Autoland', 15:33:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:33:56 INFO - 'branch': 'Autoland', 15:33:56 INFO - 'buildbot_json_path': 'buildprops.json', 15:33:56 INFO - 'config_files': ('talos/mac_config.py',), 15:33:56 INFO - 'default_actions': ('clobber', 15:33:56 INFO - 'read-buildbot-config', 15:33:56 INFO - 'download-and-extract', 15:33:56 INFO - 'populate-webroot', 15:33:56 INFO - 'create-virtualenv', 15:33:56 INFO - 'install', 15:33:56 INFO - 'run-tests'), 15:33:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:33:56 INFO - 'download_minidump_stackwalk': True, 15:33:56 INFO - 'download_symbols': 'ondemand', 15:33:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:33:56 INFO - 'tooltool.py': '/tools/tooltool.py', 15:33:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:33:56 INFO - '/tools/misc-python/virtualenv.py')}, 15:33:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:33:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:33:56 INFO - 'installer_path': 'installer.exe', 15:33:56 INFO - 'log_level': 'info', 15:33:56 INFO - 'log_name': 'talos', 15:33:56 INFO - 'log_to_console': True, 15:33:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:33:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:33:56 INFO - 'opt_config_files': (), 15:33:56 INFO - 'pip_index': False, 15:33:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:33:56 INFO - 'cmd': ('bash', 15:33:56 INFO - '-c', 15:33:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:33:56 INFO - 'enabled': True, 15:33:56 INFO - 'halt_on_failure': False, 15:33:56 INFO - 'name': 'check_screen_resolution'},), 15:33:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:33:56 INFO - 'cmd': ('bash', 15:33:56 INFO - '-c', 15:33:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:33:56 INFO - 'enabled': True, 15:33:56 INFO - 'halt_on_failure': False, 15:33:56 INFO - 'name': 'check_screen_resolution'},), 15:33:56 INFO - 'run_cmd_checks_enabled': True, 15:33:56 INFO - 'sps_profile': False, 15:33:56 INFO - 'sps_profile_interval': 0, 15:33:56 INFO - 'suite': 'dromaeojs', 15:33:56 INFO - 'system_bits': '32', 15:33:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:33:56 INFO - 'title': 't-yosemite-r7-0212', 15:33:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:33:56 INFO - 'use_talos_json': True, 15:33:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:33:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:33:56 INFO - 'work_dir': 'build'} 15:33:56 INFO - [mozharness: 2016-08-15 22:33:56.673272Z] Running clobber step. 15:33:56 INFO - Running pre-action listener: _resource_record_pre_action 15:33:56 INFO - Running main action method: clobber 15:33:56 INFO - rmtree: /builds/slave/test/build 15:33:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:33:57 INFO - Running post-action listener: _resource_record_post_action 15:33:57 INFO - [mozharness: 2016-08-15 22:33:57.572150Z] Finished clobber step (success) 15:33:57 INFO - [mozharness: 2016-08-15 22:33:57.572267Z] Running read-buildbot-config step. 15:33:57 INFO - Running pre-action listener: _resource_record_pre_action 15:33:57 INFO - Running main action method: read_buildbot_config 15:33:57 INFO - Using buildbot properties: 15:33:57 INFO - { 15:33:57 INFO - "project": "", 15:33:57 INFO - "product": "firefox", 15:33:57 INFO - "script_repo_revision": "production", 15:33:57 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:33:57 INFO - "repository": "", 15:33:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:33:57 INFO - "buildid": "20160815124617", 15:33:57 INFO - "pgo_build": "False", 15:33:57 INFO - "basedir": "/builds/slave/test", 15:33:57 INFO - "buildnumber": 116, 15:33:57 INFO - "slavename": "t-yosemite-r7-0212", 15:33:57 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:33:57 INFO - "platform": "yosemite_r7", 15:33:57 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:33:57 INFO - "branch": "autoland", 15:33:57 INFO - "repo_path": "integration/autoland", 15:33:57 INFO - "revision": "90418dcd93dbcbe0166653878e04126df0712eba", 15:33:57 INFO - "stage_platform": "macosx64", 15:33:57 INFO - "builduid": "13efb8ff66d74fcd800e78af9bffc413", 15:33:57 INFO - "slavebuilddir": "test" 15:33:57 INFO - } 15:33:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:33:57 INFO - Running post-action listener: _resource_record_post_action 15:33:57 INFO - [mozharness: 2016-08-15 22:33:57.575231Z] Finished read-buildbot-config step (success) 15:33:57 INFO - [mozharness: 2016-08-15 22:33:57.575337Z] Running download-and-extract step. 15:33:57 INFO - Running pre-action listener: _resource_record_pre_action 15:33:57 INFO - Running main action method: download_and_extract 15:33:57 INFO - mkdir: /builds/slave/test/build/tests 15:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:33:57 INFO - trying https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:33:57 INFO - Downloading https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:33:58 INFO - Downloaded 1199 bytes. 15:33:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:33:58 INFO - Using the following test package requirements: 15:33:58 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:33:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:33:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:33:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'jsshell-mac.zip'], 15:33:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:33:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:33:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:33:58 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:33:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:33:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:33:58 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:33:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:33:58 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')]} 15:33:58 INFO - trying https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:33:58 INFO - Downloading https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:33:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:33:59 INFO - Downloaded 16331650 bytes. 15:33:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:34:06 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 15:34: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')]} 15:34:06 INFO - trying https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:34:06 INFO - Downloading https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:34:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:34:07 INFO - Downloaded 16331650 bytes. 15:34:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:14 INFO - trying https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:34:14 INFO - Downloading https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:34:15 INFO - Downloaded 14413719 bytes. 15:34:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:34: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')]} 15:34:15 INFO - trying https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:34:15 INFO - Downloading https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:34:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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 15:34:20 INFO - Downloaded 111019485 bytes. 15:34:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:34:20 INFO - mkdir: /builds/slave/test/properties 15:34:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:34:20 INFO - Writing to file /builds/slave/test/properties/build_url 15:34:20 INFO - Contents: 15:34:20 INFO - build_url:https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:34:21 INFO - Running post-action listener: _resource_record_post_action 15:34:21 INFO - Running post-action listener: set_extra_try_arguments 15:34:21 INFO - [mozharness: 2016-08-15 22:34:21.058250Z] Finished download-and-extract step (success) 15:34:21 INFO - [mozharness: 2016-08-15 22:34:21.058396Z] Running populate-webroot step. 15:34:21 INFO - Running pre-action listener: _resource_record_pre_action 15:34:21 INFO - Running main action method: populate_webroot 15:34:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:34:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:34:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:34:21 INFO - '--tppagecycles', 15:34:21 INFO - '7'], 15:34:21 INFO - 'tests': ['tsvgm']}}, 15:34:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:34:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:34:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:34:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:34:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['damp', 'tps']}, 15:34:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'tests': ['damp', 'tps']}, 15:34:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['dromaeo_dom']}, 15:34:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:34:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['basic_compositor_video']}, 15:34:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:34:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['a11yr', 15:34:21 INFO - 'ts_paint', 15:34:21 INFO - 'tpaint', 15:34:21 INFO - 'sessionrestore', 15:34:21 INFO - 'sessionrestore_no_auto_restore', 15:34:21 INFO - 'tabpaint']}, 15:34:21 INFO - 'other-e10s': {'tests': ['a11yr', 15:34:21 INFO - 'ts_paint', 15:34:21 INFO - 'tpaint', 15:34:21 INFO - 'sessionrestore', 15:34:21 INFO - 'sessionrestore_no_auto_restore', 15:34:21 INFO - 'tabpaint']}, 15:34:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:34:21 INFO - 'ts_paint', 15:34:21 INFO - 'tpaint', 15:34:21 INFO - 'sessionrestore', 15:34:21 INFO - 'sessionrestore_no_auto_restore', 15:34:21 INFO - 'tabpaint']}, 15:34:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:34:21 INFO - 'ts_paint', 15:34:21 INFO - 'tpaint', 15:34:21 INFO - 'sessionrestore', 15:34:21 INFO - 'sessionrestore_no_auto_restore', 15:34:21 INFO - 'tabpaint']}, 15:34:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['a11yr', 15:34:21 INFO - 'ts_paint', 15:34:21 INFO - 'tpaint', 15:34:21 INFO - 'sessionrestore', 15:34:21 INFO - 'sessionrestore_no_auto_restore', 15:34:21 INFO - 'tabpaint']}, 15:34:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['a11yr', 15:34:21 INFO - 'ts_paint', 15:34:21 INFO - 'tpaint', 15:34:21 INFO - 'sessionrestore', 15:34:21 INFO - 'sessionrestore_no_auto_restore', 15:34:21 INFO - 'tabpaint']}, 15:34:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['tsvgx', 15:34:21 INFO - 'tsvgr_opacity', 15:34:21 INFO - 'tart', 15:34:21 INFO - 'tscrollx', 15:34:21 INFO - 'cart']}, 15:34:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:34:21 INFO - 'tsvgr_opacity', 15:34:21 INFO - 'tart', 15:34:21 INFO - 'tscrollx', 15:34:21 INFO - 'cart']}, 15:34:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'talos_options': ['--disable-e10s'], 15:34:21 INFO - 'tests': ['tp5o']}, 15:34:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'tests': ['tp5o']}, 15:34:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'talos_options': ['--disable-e10s', 15:34:21 INFO - '--xperf_path', 15:34:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:21 INFO - 'tests': ['tp5n']}, 15:34:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:21 INFO - 'talos_options': ['--xperf_path', 15:34:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:21 INFO - 'tests': ['tp5n']}}, 15:34:21 INFO - 'talos.zip': {'path': '', 15:34:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:34:21 INFO - Running post-action listener: _resource_record_post_action 15:34:21 INFO - [mozharness: 2016-08-15 22:34:21.077792Z] Finished populate-webroot step (success) 15:34:21 INFO - [mozharness: 2016-08-15 22:34:21.077875Z] Running create-virtualenv step. 15:34:21 INFO - Running pre-action listener: _resource_record_pre_action 15:34:21 INFO - Running main action method: create_virtualenv 15:34:21 INFO - Creating virtualenv /builds/slave/test/build/venv 15:34:21 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 15:34:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:34:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:34:21 INFO - Using real prefix '/tools/python27' 15:34:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:34:21 INFO - Installing distribute.............................................................................................................................................................................................done. 15:34:25 INFO - Installing pip.................done. 15:34:25 INFO - Return code: 0 15:34:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:34:25 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')]} 15:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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')]} 15:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:25 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 15:34:25 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 15:34:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:25 INFO - 'HOME': '/Users/cltbld', 15:34:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:25 INFO - 'LOGNAME': 'cltbld', 15:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:25 INFO - 'MOZ_NO_REMOTE': '1', 15:34:25 INFO - 'NO_EM_RESTART': '1', 15:34:25 INFO - 'PAGER': '/bin/cat', 15:34:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:25 INFO - 'PWD': '/builds/slave/test', 15:34:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:25 INFO - 'SHELL': '/bin/bash', 15:34:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:25 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:25 INFO - 'USER': 'cltbld', 15:34:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:25 INFO - 'XPC_FLAGS': '0x0', 15:34:25 INFO - 'XPC_SERVICE_NAME': '0', 15:34:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:25 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 15:34:25 INFO - Cleaning up... 15:34:25 INFO - Return code: 0 15:34:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:34:25 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')]} 15:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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')]} 15:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:25 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 15:34:25 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 15:34:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:25 INFO - 'HOME': '/Users/cltbld', 15:34:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:25 INFO - 'LOGNAME': 'cltbld', 15:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:25 INFO - 'MOZ_NO_REMOTE': '1', 15:34:25 INFO - 'NO_EM_RESTART': '1', 15:34:25 INFO - 'PAGER': '/bin/cat', 15:34:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:25 INFO - 'PWD': '/builds/slave/test', 15:34:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:25 INFO - 'SHELL': '/bin/bash', 15:34:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:25 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:25 INFO - 'USER': 'cltbld', 15:34:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:25 INFO - 'XPC_FLAGS': '0x0', 15:34:25 INFO - 'XPC_SERVICE_NAME': '0', 15:34:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:25 INFO - Downloading/unpacking psutil>=3.1.1 15:34:25 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 15:34:25 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 15:34:25 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 15:34:25 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 15:34:25 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 15:34:25 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 15:34:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:34:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:29 INFO - Installing collected packages: psutil 15:34:29 INFO - Running setup.py install for psutil 15:34:29 INFO - building 'psutil._psutil_osx' extension 15:34:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:34:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:34:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:34:29 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 15:34:29 INFO - building 'psutil._psutil_posix' extension 15:34:29 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 15:34:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:34:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:34:29 INFO - ^ 15:34:29 INFO - 1 warning generated. 15:34:29 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 15:34:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:30 INFO - Successfully installed psutil 15:34:30 INFO - Cleaning up... 15:34:30 INFO - Return code: 0 15:34:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:34:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:34:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:30 INFO - 'HOME': '/Users/cltbld', 15:34:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:30 INFO - 'LOGNAME': 'cltbld', 15:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:30 INFO - 'MOZ_NO_REMOTE': '1', 15:34:30 INFO - 'NO_EM_RESTART': '1', 15:34:30 INFO - 'PAGER': '/bin/cat', 15:34:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:30 INFO - 'PWD': '/builds/slave/test', 15:34:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:30 INFO - 'SHELL': '/bin/bash', 15:34:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:30 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:30 INFO - 'USER': 'cltbld', 15:34:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:30 INFO - 'XPC_FLAGS': '0x0', 15:34:30 INFO - 'XPC_SERVICE_NAME': '0', 15:34:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34: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 15:34:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:34:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:34:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:34:32 INFO - Installing collected packages: mozsystemmonitor 15:34:32 INFO - Running setup.py install for mozsystemmonitor 15:34:33 INFO - Successfully installed mozsystemmonitor 15:34:33 INFO - Cleaning up... 15:34:33 INFO - Return code: 0 15:34:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:34:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:33 INFO - 'HOME': '/Users/cltbld', 15:34:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:33 INFO - 'LOGNAME': 'cltbld', 15:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:33 INFO - 'MOZ_NO_REMOTE': '1', 15:34:33 INFO - 'NO_EM_RESTART': '1', 15:34:33 INFO - 'PAGER': '/bin/cat', 15:34:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:33 INFO - 'PWD': '/builds/slave/test', 15:34:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:33 INFO - 'SHELL': '/bin/bash', 15:34:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:33 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:33 INFO - 'USER': 'cltbld', 15:34:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:33 INFO - 'XPC_FLAGS': '0x0', 15:34:33 INFO - 'XPC_SERVICE_NAME': '0', 15:34:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:33 INFO - Downloading/unpacking jsonschema==2.5.1 15:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:34:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:34:35 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 15:34:35 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 15:34:35 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 15:34:35 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 15:34:35 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 15:34:35 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 15:34:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:34:36 INFO - warning: no files found matching '*.txt' 15:34:36 INFO - no previously-included directories found matching 'build' 15:34:36 INFO - no previously-included directories found matching 'dist' 15:34:36 INFO - no previously-included directories found matching '.git*' 15:34:36 INFO - Installing collected packages: jsonschema, functools32 15:34:36 INFO - Running setup.py install for functools32 15:34:36 INFO - warning: no files found matching '*.txt' 15:34:36 INFO - no previously-included directories found matching 'build' 15:34:36 INFO - no previously-included directories found matching 'dist' 15:34:36 INFO - no previously-included directories found matching '.git*' 15:34:36 INFO - Successfully installed jsonschema functools32 15:34:36 INFO - Cleaning up... 15:34:36 INFO - Return code: 0 15:34:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:34:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:36 INFO - 'HOME': '/Users/cltbld', 15:34:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:36 INFO - 'LOGNAME': 'cltbld', 15:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:36 INFO - 'MOZ_NO_REMOTE': '1', 15:34:36 INFO - 'NO_EM_RESTART': '1', 15:34:36 INFO - 'PAGER': '/bin/cat', 15:34:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:36 INFO - 'PWD': '/builds/slave/test', 15:34:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:36 INFO - 'SHELL': '/bin/bash', 15:34:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:36 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:36 INFO - 'USER': 'cltbld', 15:34:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:36 INFO - 'XPC_FLAGS': '0x0', 15:34:36 INFO - 'XPC_SERVICE_NAME': '0', 15:34:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:34:36 INFO - Cleaning up... 15:34:36 INFO - Return code: 0 15:34:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:34:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:36 INFO - 'HOME': '/Users/cltbld', 15:34:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:36 INFO - 'LOGNAME': 'cltbld', 15:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:36 INFO - 'MOZ_NO_REMOTE': '1', 15:34:36 INFO - 'NO_EM_RESTART': '1', 15:34:36 INFO - 'PAGER': '/bin/cat', 15:34:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:36 INFO - 'PWD': '/builds/slave/test', 15:34:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:36 INFO - 'SHELL': '/bin/bash', 15:34:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:36 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:36 INFO - 'USER': 'cltbld', 15:34:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:36 INFO - 'XPC_FLAGS': '0x0', 15:34:36 INFO - 'XPC_SERVICE_NAME': '0', 15:34:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:37 INFO - Downloading/unpacking blobuploader==1.2.4 15:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:39 INFO - Downloading blobuploader-1.2.4.tar.gz 15:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:34:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:34:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:40 INFO - Downloading docopt-0.6.1.tar.gz 15:34:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:34:40 INFO - Installing collected packages: blobuploader, requests, docopt 15:34:40 INFO - Running setup.py install for blobuploader 15:34:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:34:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:34:40 INFO - Running setup.py install for requests 15:34:41 INFO - Running setup.py install for docopt 15:34:41 INFO - Successfully installed blobuploader requests docopt 15:34:41 INFO - Cleaning up... 15:34:41 INFO - Return code: 0 15:34:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:41 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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:41 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 15:34:41 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 15:34:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:41 INFO - 'HOME': '/Users/cltbld', 15:34:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:41 INFO - 'LOGNAME': 'cltbld', 15:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:41 INFO - 'MOZ_NO_REMOTE': '1', 15:34:41 INFO - 'NO_EM_RESTART': '1', 15:34:41 INFO - 'PAGER': '/bin/cat', 15:34:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:41 INFO - 'PWD': '/builds/slave/test', 15:34:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:41 INFO - 'SHELL': '/bin/bash', 15:34:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:41 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:41 INFO - 'USER': 'cltbld', 15:34:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:41 INFO - 'XPC_FLAGS': '0x0', 15:34:41 INFO - 'XPC_SERVICE_NAME': '0', 15:34:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:41 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-SuRqtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-DM791K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-akLVhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-x23gdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-FW9Y9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-CYR09B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-NpRTMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fBjf9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-BYJDgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-S6oR9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:42 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-CsWfk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:43 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-kLYRNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:43 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-xmMmwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:43 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-hzXKlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:43 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-luBrAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:43 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-qIBBJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:43 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-2HxMpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:34:43 INFO - Running setup.py install for manifestparser 15:34:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:34:43 INFO - Running setup.py install for mozcrash 15:34:43 INFO - Running setup.py install for mozdebug 15:34:44 INFO - Running setup.py install for mozdevice 15:34:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Running setup.py install for mozfile 15:34:44 INFO - Running setup.py install for mozhttpd 15:34:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Running setup.py install for mozinfo 15:34:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Running setup.py install for mozInstall 15:34:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:34:44 INFO - Running setup.py install for mozleak 15:34:44 INFO - Running setup.py install for mozlog 15:34:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:34:45 INFO - Running setup.py install for moznetwork 15:34:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:34:45 INFO - Running setup.py install for mozprocess 15:34:45 INFO - Running setup.py install for mozprofile 15:34:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:34:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:34:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:34:45 INFO - Running setup.py install for mozrunner 15:34:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:34:45 INFO - Running setup.py install for mozscreenshot 15:34:45 INFO - Running setup.py install for moztest 15:34:46 INFO - Running setup.py install for mozversion 15:34:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:34:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:34:46 INFO - Cleaning up... 15:34:46 INFO - Return code: 0 15:34:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:46 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')]} 15:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:46 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')]} 15:34:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:46 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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:46 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 15:34:46 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 15:34:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:46 INFO - 'HOME': '/Users/cltbld', 15:34:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:46 INFO - 'LOGNAME': 'cltbld', 15:34:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:46 INFO - 'MOZ_NO_REMOTE': '1', 15:34:46 INFO - 'NO_EM_RESTART': '1', 15:34:46 INFO - 'PAGER': '/bin/cat', 15:34:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:46 INFO - 'PWD': '/builds/slave/test', 15:34:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:46 INFO - 'SHELL': '/bin/bash', 15:34:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:46 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:46 INFO - 'USER': 'cltbld', 15:34:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:46 INFO - 'XPC_FLAGS': '0x0', 15:34:46 INFO - 'XPC_SERVICE_NAME': '0', 15:34:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:46 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-gjCl7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:46 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)) 15:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:46 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-3y4VvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:46 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)) 15:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:46 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-qYz2PS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:46 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)) 15:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:46 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-QzXrNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:46 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)) 15:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:46 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Y5JXQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-rLFTD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-kWWU_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-FpVaNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-dThwV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Y_wTAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fdNJPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-eQbxLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-CvKDG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:47 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)) 15:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:47 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Q7XHg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:48 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-vqUih1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:48 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)) 15:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:48 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-agD9bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:48 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)) 15:34:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:48 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-l6_JTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:48 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)) 15:34:48 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)) 15:34:48 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)) 15:34:48 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)) 15:34:48 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)) 15:34:48 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)) 15:34:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:50 INFO - Downloading blessings-1.6.tar.gz 15:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:34:50 INFO - Installing collected packages: blessings 15:34:50 INFO - Running setup.py install for blessings 15:34:51 INFO - Successfully installed blessings 15:34:51 INFO - Cleaning up... 15:34:51 INFO - Return code: 0 15:34:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:34:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:51 INFO - Reading from file tmpfile_stdout 15:34:51 INFO - Current package versions: 15:34:51 INFO - blessings == 1.6 15:34:51 INFO - blobuploader == 1.2.4 15:34:51 INFO - docopt == 0.6.1 15:34:51 INFO - functools32 == 3.2.3-2 15:34:51 INFO - jsonschema == 2.5.1 15:34:51 INFO - manifestparser == 1.1 15:34:51 INFO - mozInstall == 1.12 15:34:51 INFO - mozcrash == 0.17 15:34:51 INFO - mozdebug == 0.1 15:34:51 INFO - mozdevice == 0.48 15:34:51 INFO - mozfile == 1.2 15:34:51 INFO - mozhttpd == 0.7 15:34:51 INFO - mozinfo == 0.9 15:34:51 INFO - mozleak == 0.1 15:34:51 INFO - mozlog == 3.3 15:34:51 INFO - moznetwork == 0.27 15:34:51 INFO - mozprocess == 0.23 15:34:51 INFO - mozprofile == 0.28 15:34:51 INFO - mozrunner == 6.12 15:34:51 INFO - mozscreenshot == 0.1 15:34:51 INFO - mozsystemmonitor == 0.3 15:34:51 INFO - moztest == 0.7 15:34:51 INFO - mozversion == 1.4 15:34:51 INFO - psutil == 3.1.1 15:34:51 INFO - requests == 1.2.3 15:34:51 INFO - wsgiref == 0.1.2 15:34:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:51 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 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:51 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 15:34:51 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 15:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:51 INFO - 'HOME': '/Users/cltbld', 15:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:51 INFO - 'LOGNAME': 'cltbld', 15:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:51 INFO - 'MOZ_NO_REMOTE': '1', 15:34:51 INFO - 'NO_EM_RESTART': '1', 15:34:51 INFO - 'PAGER': '/bin/cat', 15:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:51 INFO - 'PWD': '/builds/slave/test', 15:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:51 INFO - 'SHELL': '/bin/bash', 15:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:51 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:51 INFO - 'USER': 'cltbld', 15:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:51 INFO - 'XPC_FLAGS': '0x0', 15:34:51 INFO - 'XPC_SERVICE_NAME': '0', 15:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:34:52 INFO - Cleaning up... 15:34:52 INFO - Return code: 0 15:34:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106f0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f899b5741a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, '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.jU41PqdMkq/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.2MGnIM2cax/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 15:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:52 INFO - 'HOME': '/Users/cltbld', 15:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:52 INFO - 'LOGNAME': 'cltbld', 15:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:52 INFO - 'MOZ_NO_REMOTE': '1', 15:34:52 INFO - 'NO_EM_RESTART': '1', 15:34:52 INFO - 'PAGER': '/bin/cat', 15:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:52 INFO - 'PWD': '/builds/slave/test', 15:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:52 INFO - 'SHELL': '/bin/bash', 15:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:34:52 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:52 INFO - 'USER': 'cltbld', 15:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:52 INFO - 'XPC_FLAGS': '0x0', 15:34:52 INFO - 'XPC_SERVICE_NAME': '0', 15:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:34:52 INFO - Cleaning up... 15:34:52 INFO - Return code: 0 15:34:52 INFO - Running post-action listener: _resource_record_post_action 15:34:52 INFO - Running post-action listener: _start_resource_monitoring 15:34:52 INFO - Starting resource monitoring. 15:34:52 INFO - [mozharness: 2016-08-15 22:34:52.456583Z] Finished create-virtualenv step (success) 15:34:52 INFO - [mozharness: 2016-08-15 22:34:52.457582Z] Running install step. 15:34:52 INFO - Running pre-action listener: _resource_record_pre_action 15:34:52 INFO - Running main action method: install 15:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:52 INFO - Reading from file tmpfile_stdout 15:34:52 INFO - Detecting whether we're running mozinstall >=1.0... 15:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:34:52 INFO - Reading from file tmpfile_stdout 15:34:52 INFO - Output received: 15:34:52 INFO - Usage: mozinstall [options] installer 15:34:52 INFO - Options: 15:34:52 INFO - -h, --help show this help message and exit 15:34:52 INFO - -d DEST, --destination=DEST 15:34:52 INFO - Directory to install application into. [default: 15:34:52 INFO - "/builds/slave/test"] 15:34:52 INFO - --app=APP Application being installed. [default: firefox] 15:34:52 INFO - mkdir: /builds/slave/test/build/application 15:34:52 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'] 15:34:52 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 15:35:26 INFO - Reading from file tmpfile_stdout 15:35:26 INFO - Output received: 15:35:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:35:26 INFO - Running post-action listener: _resource_record_post_action 15:35:26 INFO - [mozharness: 2016-08-15 22:35:26.824357Z] Finished install step (success) 15:35:26 INFO - [mozharness: 2016-08-15 22:35:26.824544Z] Running run-tests step. 15:35:26 INFO - Running pre-action listener: _resource_record_pre_action 15:35:26 INFO - Running main action method: run_tests 15:35:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:35:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:35:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:35:26 INFO - 2016-08-15 15:35:26.839 screenresolution[2318:71774] starting screenresolution argv=screenresolution get 15:35:26 INFO - 2016-08-15 15:35:26.842 screenresolution[2318:71774] Display 0: 1600x1200x32@60 15:35:26 INFO - 2016-08-15 15:35:26.848 screenresolution[2319:71777] starting screenresolution argv=screenresolution list 15:35:26 INFO - Available Modes on Display 0 15:35:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:35:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:35:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:35:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:35:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:35:27 INFO - Intel Iris: 15:35:27 INFO - Chipset Model: Intel Iris 15:35:27 INFO - Type: GPU 15:35:27 INFO - Bus: Built-In 15:35:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:35:27 INFO - Vendor: Intel (0x8086) 15:35:27 INFO - Device ID: 0x0a2e 15:35:27 INFO - Revision ID: 0x0009 15:35:27 INFO - Displays: 15:35:27 INFO - Display: 15:35:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:35:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:35:27 INFO - Display Serial Number: 1600x1200 60 15:35:27 INFO - Main Display: Yes 15:35:27 INFO - Mirror: Off 15:35:27 INFO - Online: Yes 15:35:27 INFO - Rotation: Supported 15:35:27 INFO - Return code: 0 15:35:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:35:27 INFO - Python 2.7.3 15:35:27 INFO - Return code: 0 15:35:27 INFO - grabbing minidump binary from tooltool 15:35: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')]} 15:35:27 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 0x1105bfd30>, '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 0x110324db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:35:27 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 15:35:27 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 15:35:27 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 15:35:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:35:27 INFO - Return code: 0 15:35:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:35:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:35:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:35:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:35:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:35:27 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/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 15:35:27 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/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --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 15:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jU41PqdMkq/Render', 15:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:35:27 INFO - 'HOME': '/Users/cltbld', 15:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:35:27 INFO - 'LOGNAME': 'cltbld', 15:35:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:35:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:35:27 INFO - 'MOZ_NO_REMOTE': '1', 15:35:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:35:27 INFO - 'NO_EM_RESTART': '1', 15:35:27 INFO - 'PAGER': '/bin/cat', 15:35:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:35:27 INFO - 'PWD': '/builds/slave/test', 15:35:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:35:27 INFO - 'SHELL': '/bin/bash', 15:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MGnIM2cax/Listeners', 15:35:27 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 15:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:35:27 INFO - 'USER': 'cltbld', 15:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:35:27 INFO - 'XPC_FLAGS': '0x0', 15:35:27 INFO - 'XPC_SERVICE_NAME': '0', 15:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:35:27 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/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 15:35:27 INFO - mozversion application_buildid: 20160815124617 15:35:27 INFO - mozversion application_changeset: 90418dcd93dbcbe0166653878e04126df0712eba 15:35:27 INFO - mozversion application_display_name: Nightly 15:35:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:35:27 INFO - mozversion application_name: Firefox 15:35:27 INFO - mozversion application_remotingname: firefox 15:35:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:35:27 INFO - mozversion application_vendor: Mozilla 15:35:27 INFO - mozversion application_version: 51.0a1 15:35:27 INFO - mozversion platform_buildid: 20160815124617 15:35:27 INFO - mozversion platform_changeset: 90418dcd93dbcbe0166653878e04126df0712eba 15:35:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:35:27 INFO - mozversion platform_version: 51.0a1 15:35:27 INFO - using testdate: 1471300527 15:35:27 INFO - actual date: 1471300527 15:35:27 INFO - starting webserver on 'localhost:53109' 15:35:27 INFO - SUITE-START | Running 2 tests 15:35:27 INFO - TEST-START | dromaeo_css 15:35:27 INFO - operating with platform_type : mac_ 15:35:27 INFO - Initialising browser for dromaeo_css test... 15:35:27 INFO - TEST-INFO | started process 2329 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp0bzJ_c/profile http://localhost:53109/getInfo.html) 15:35:29 INFO - PROCESS | 2329 | __metrics Screen width/height:1600/1200 15:35:29 INFO - PROCESS | 2329 | colorDepth:24 15:35:29 INFO - PROCESS | 2329 | Browser inner width/height: 1024/689 15:35:29 INFO - PROCESS | 2329 | __metrics 15:35:29 INFO - PROCESS | 2329 | 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" 15:35:29 INFO - PROCESS | 2329 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:35:30 INFO - TEST-INFO | 2329: exit 0 15:35:30 INFO - Browser initialized. 15:35:30 INFO - Running cycle 1/1 for dromaeo_css test... 15:35:30 INFO - TEST-INFO | started process 2331 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp0bzJ_c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:42 INFO - PROCESS | 2331 | RSS: Main: 228438016 15:35:42 INFO - PROCESS | 2331 | 15:36:33 INFO - PROCESS | 2331 | 1471300593691 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] 15:38:45 INFO - PROCESS | 2331 | Cycle 1(1): loaded http://localhost:53109/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53109/tests/dromaeo/cssquery-ext.html) 15:38:47 INFO - PROCESS | 2331 | RSS: Main: 380702720 15:38:47 INFO - PROCESS | 2331 | 15:41:50 INFO - PROCESS | 2331 | Cycle 1(1): loaded http://localhost:53109/tests/dromaeo/cssquery-ext.html (next: http://localhost:53109/tests/dromaeo/cssquery-jquery.html) 15:41:51 INFO - PROCESS | 2331 | RSS: Main: 371990528 15:41:51 INFO - PROCESS | 2331 | 15:44:53 INFO - PROCESS | 2331 | Cycle 1(1): loaded http://localhost:53109/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53109/tests/dromaeo/cssquery-mootools.html) 15:44:53 INFO - PROCESS | 2331 | RSS: Main: 361549824 15:44:53 INFO - PROCESS | 2331 | 15:47:56 INFO - PROCESS | 2331 | Cycle 1(1): loaded http://localhost:53109/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53109/tests/dromaeo/cssquery-prototype.html) 15:47:57 INFO - PROCESS | 2331 | RSS: Main: 355381248 15:47:57 INFO - PROCESS | 2331 | 15:51:00 INFO - PROCESS | 2331 | Cycle 1(1): loaded http://localhost:53109/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53109/tests/dromaeo/cssquery-yui.html) 15:51:01 INFO - PROCESS | 2331 | RSS: Main: 339570688 15:51:01 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | Cycle 1(1): loaded http://localhost:53109/tests/dromaeo/cssquery-yui.html (next: http://localhost:53109/tests/dromaeo/cssquery-dojo.html) 15:54:04 INFO - PROCESS | 2331 | RSS: Main: 485167104 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | __start_tp_report 15:54:04 INFO - PROCESS | 2331 | _x_x_mozilla_page_load 15:54:04 INFO - PROCESS | 2331 | _x_x_mozilla_page_load_details 15:54:04 INFO - PROCESS | 2331 | |i|pagename|runs| 15:54:04 INFO - PROCESS | 2331 | |0;dojo.html;3254.593175853017;3263.2331402120503;3290.2596915693957;3305.3224089061746;3316.3035762489876;5513.338399392069;5591.524720398766;5656.745446454909;5679.772809087642;5740.540756739461;5647.29408823897;5671.943280567193;5689.061304884689;5689.431056894308;5700.144978253254;4310.956890431103;5152.8711782205555;5170.405403378603;5198.92201616976;5270.156774916014;5236.842894713146;5258.2638430619745;5261.895002049574;5264.157734762439;5267.051930652481;5162.5095615916425;5189.403218629849;5189.948100519003;5202.687838729688;5256.080185967459;5258.132408152669;5292.9735351323;5302.602304827154;5309.548688361455;5347.197920311945;5087.465816089294;5090.389153301628;5162.612804039674;5209.843704688866;5217.8173763918085;5569.8607534811545;5636.2954630671165;5637.971810140964;5664.54683625309;5713.971430142864;6157.3534778848025;6206.317305096436;6233.438990490878;6243.7814676486105;6246.625202487865;6124.9912799099075;6166.876662466726;6243.344448832898;6282.905756413657;6291.307956124822;5781.36404995446;5797.333306669787;5840.503557197686;5844.357120716718;5856.853578660568;6248.062790581449;6249.375062493714;6249.562530622813;6260.436560709467;6269.498440124779;5963.5527335450015;6052.12244224585;6143.139960405547;6144.754209831557;6149.354317796657;5275.419703832576;6244.219472565929;6266.248050234001;6266.811995640138;6277.466415354655;6187.071939209065;6210.037444196157;6212.06818977148;6219.626822390671;6227.003679411272;6103.816885493442;6112.07796728157;6117.583823141483;6141.723622437001;6221.657808820468;4925.756214286784;4954.529319714621;4955.108080545464;4962.5533701967;4963.255511673278;4381.992141807402;4433.069055498381;4455.376247325377;4470.240059189946;4476.395686582335;5748.109043098327;5758.107493338539;5788.363280039192;5799.3620701722775;5805.274340707411;6215;6245.406686364788;6248.4688801452385;6254.437100660963;6264;5604.243427137366;5656.8020119295625;5686.260786097863;5703.287089113861;5744.684042377709;5362.91955620666;5394.433584473652;5413.891722165535;5419.078756611386;5424.213489044067;6222.439980401786;6276.717547710364;6281.654509001866;6287.685615719287;6294.0558916162945;6228.781992630236;6233.781817636361;6246.750129994749;6264.248290205204;6275.560710750204;6146.139540464249;6166.290876549075;6275.6862156892885;6277.96861015692;6281.59169653971;6104.004449388181;6190.1952746142715;6190.907136392868;6201.937980620136;6203.348648391854;6178.938210617836;6224.06639004153;6235.625862448086;6241.095041219089;6257.687115644108;5945.494632956247;6144.1398204250545;6152.200213972155;6154.169187159676;6154.230721159855;5942.405759424023;6011.338752737108;6044.697765111639;6098.329183789869;6130.092410195535;6003.189569408249;6031.215941927697;6039.969800151147;6046.54650901193;6050.818475445743;6053.878922421439;6093.2078829751845;6094.11635312886;6097.359777223505;6097.5426842987845;5807.593468457337;5870.148828419769;5872.941270587239;5874.530037597067;5891.116332550152 15:54:04 INFO - PROCESS | 2331 | |1;ext.html;485.8809591650046;509.22851879402714;521.8330134357003;523.2413001148336;528.6378830501105;30959.535606965885;32539.83730081349;39693.00767480814;40937;40942.38586421206;42433.7878310608;42528.57471425285;42530.787346063225;42588.78705606476;43054.70835874921;9759.07288807564;9958.253131015166;9961.153301969342;9965.850512242323;9975.301728878982;8848.867266991001;9075.319351048665;10321.948390258076;11976.640700778991;12255.203411778206;12151.27092374456;12233.388330583479;12242.204256723284;12243.816342754866;12280.692982675415;8902.86645700315;12131.272123672563;12223.2666040037;12227.248869004447;12233.143679942408;11763.941180294129;12068.577599784054;12099.576514822027;12215.144939854214;12257.448414821265;40176.79911600423;40333.79833100845;40348;40351;40429;3416.538767266412;3482.1120614243205;3517.5954765202073;3874.5156855393075;4282.528921818597;26859.46281074387;30643.693563064306;32438.83780581082;32485;32604.836975814967;3218.6459489456142;3376.74674399421;3453.067671728644;3454.2055327274616;3455.1707590178426;518389.4080529611;522515;524664;524765;526489;60135.398646013855;60672.696636516535;60674;60717.69641151766;60818;63154;63205.68397157985;63276;63283;63365.68317158385;16395.344186232418;16604.003759774452;16651.917625354312;16657.91671041661;16666.166691665367;11711.648650540497;12024.857638524327;12039.578614748618;12052.698682532831;12703.93648031754;18702.81297187038;18769.155388007573;18804;18823.529411764815;18834.71747923782;5012.62152907946;7796.84406311882;7807.336376408069;7945.602719863984;8007.239312265326;867.1935100356603;869.7869022089502;977.5747549815853;1047.8009178256107;1110;28856.567151492745;30075.548866767014;30255.941042063423;30463.847680761453;30552.694473055428;1006.6146118267358;1094.0034437152228;1147.8852114788453;1156.3986726902133;1175.2360965372404;14745.557633271019;15754.606134846721;15811.367545298059;15870.761938570931;15878.52364429069;705457;711795;711830;712703;712958;58455.41544584585;58837.82324353574;58855;58937;58961.705191474626;1316.374722007039;1363.6863521390135;1377.889768818492;1378.5312993582231;1381.2265131526176;29687;29781.851090744407;29806;30539.54190687097;30643.693563064084;55266.171007435725;56759;56883.86232275414;58851.41148588459;58997;648173;653027;653570;654019;654666.9066618736;37200.81399592985;40822.79588602038;42907.35638965481;42938;43245.567544324156;47381.526184739094;47575.52424475711;48451.75774121107;48735;49348.75325623349;32257.35485290328;37641.81179094197;39004.80497597494;39978.80010599928;40597;32569.83715081504;38320.80839595784;38969.2206155881;39387.818365449086;40461.79769101136;38052.809735951145;38624.42063369108;38842.417363739;38900.832975010795;38962.80518597389;29577.85211073931;32882;33200.66799331976;34735.82632086823;34855.47716784199;21880.717892315643;22685.43286417853;25099.623505647796;25456.490870182122;25611.97552097895 15:54:04 INFO - PROCESS | 2331 | |2;jquery.html;320.93420848726004;356.5026787631253;377.05359547535676;380.87431147721253;406.7640768354355;12214.633560993174;15452.845471545295;16146.596335091614;20040.69938950917;20870.89564552179;20283.898580507113;21406.678899816514;21420.357389278306;21510.89244553775;21823;5819.621724587899;6086.2087928569445;6096.055111457731;6099.57302988791;6111.388861113897;5621.971890140564;6774.796756097325;6854.177498700163;6936.618485983269;6939.757108501203;6593.406593406592;6687.364700353459;6765.966170169142;6875.278095799944;6899.862002759942;6631.535792494527;6708.195016597991;6755.831104222385;6759.5268331216585;6774.627395493246;5065.898682026375;5791.971040144815;5848.853778655525;5912.201852749867;5917.822465326047;26218.86890565535;26368.340791480176;26424.339391515172;26426.86786566074;26532.336691582866;2241.2827895073583;2277.9886100569556;2382.5234953009467;2405.987970060156;2455.3247856839334;15078.472253471076;15490.845091549052;15713.450029248921;15836.920815395964;16605.750913736305;1801.5225965119255;1807.638472305531;1874.4657772534763;1911.3979096584671;1978;65809;88650.3404897854;93918.12163756754;94166;114036.42981785121;10505.216117728807;10811.297265677864;10890.237683362247;10982.725431864108;10991.670249892515;13301.135426197266;13396.26320552379;13398.799018014737;13400.128991615515;13400.865991339962;7959.243871832166;7960.641771120314;8012.158723334082;8168.183181681784;8210.712625058095;7852.921470785333;7879.724209652634;8016.238457346542;8031.678732850737;8097.825815256755;5387.326584176978;6344.746210151635;6450.548461607643;6473.514486413538;6487.481001519867;1807.692692242322;1841.8710690251553;1843.7186155621982;1846.3999200259968;1850.1859181959894;915.2129168914728;952.9952350238204;970.9853203402321;985.4233226837147;996.3324572536418;21945.341639750834;22391.21630742916;22480.775192248195;22491;22547.88726056359;957.5355952363099;968.2302569457298;970.2819913264134;1085.0234788690084;1088.450332582041;10973.23187376876;11760.176787624956;11870.703232419259;11876.76246475083;11961.641150765317;605595;609209;612144;616286;621275.723456594;72923;113585.4320728391;115286.4235678833;116478;117685;984.7735020945223;997.1026076531035;1035.1253191053547;1068.9946550267355;1069.8181309177457;19262.94053826997;19572.902135489232;20142.625543300022;20169.40866598163;20479.897600511904;49981;50347.74826125846;51919.70200745011;52058;52402.737986309825;60459.69770151121;70675;71885.6405717968;72175.91736124067;73298;6573.309802470863;6839.965800170968;7876.84246315082;8154.633041513028;8156.755297341089;13549.932250338685;13580.32098395096;13810;13873;13955.930220348833;10943.398113103856;11374.772504549697;11521.596744113916;11534.884651153381;11945.581904633296;9372.765680858032;11199.992000719649;11425.31448113116;11451;11783.941080294544;11591.188616796744;11748.765024699287;11759.824847291355;12085.637430877088;12197.02423806111;6786.219584747817;7970.123286438371;8095.190480951857;8288;8483.490990540588;8331.6667333306;10602.310849794738;11040.558377664804;11550.422478875855;11662.650120496399 15:54:04 INFO - PROCESS | 2331 | |3;mootools.html;1520.2626726037872;1557.7896983907174;1686.9156542172905;1735.652869426115;1905.8951757653333;8428.410011299211;8975.057618950013;8980.595873185714;8993.73018809435;9011.18899299063;7825.295723384894;8092.39307051972;8544.530050847201;8774.956125219382;8780.912190878089;2358.976410235897;2384.451576137481;2388.4148383645943;2390.5338459000413;2399.2682231919257;2026.7365242518451;2302.4128847143957;2327.755585663498;2348.823838212132;2352.517733864562;2359.2096647623066;2378.4291769975252;2383.809295256384;2392.3899405651537;2400.5918993771015;2325.453518423169;2338.2634470141934;2348.8825558722083;2352.800011998982;2384.391980045095;2340.8595484271;2343.4961483281177;2346.8122550195944;2350.4828937633692;2353.9293821185393;8637.568121593895;8797.29621630268;8817.735467935909;8828.955855220684;8837.204651581389;5262.973720124577;5308.814191503279;5329.333833270841;5405.216243644689;5410.5401040911565;3436.3127374525193;3568.892933212008;3577.5349204603235;3642.817859107034;3643.4534819777246;1062.9681109566648;1094.2945335059214;1112.7551938573502;1121.7139629394533;1123.5225029362489;11644.825327620092;11766.941165294204;11768.823467647991;11769.882301176964;11771.470283837249;4545.2954792007295;4651.604613607881;4850.320955066293;4861.465238823726;4867.099586576494;10061.798764024825;10082.647107351208;10085.394876307446;10093.69718908434;10098.545565449573;5429.511343979061;5431.967926094029;5434.239206511092;5436;5439.211314359475;4449.043455656984;4615.815367385334;4822.590079843182;4961.032598643302;4973.52751488608;3900.863469778544;3970.5235371754998;4037.555868854424;4050.736702114353;4165.375193720905;1275.3304515129446;1277.8274932883942;1280.769461496921;1289.5357671238346;1299.2399446323818;865.9567021648892;884.9115088491228;909.8908131024319;913.9360244782802;937.8265020971143;5971.940280597139;6084.756609735649;6100.69496525172;6172.506199504029;6206.348333425016;754.9245075492407;839.1231163434226;881.2377293641083;925.3430064654171;935.2050756856753;3851.480050193188;4032.6370626643743;4051.513818341818;4149.087200815816;4188.958110418917;10687.167900506372;10995.780084398268;11117.221794474472;11123.499442524944;11153.665390038312;11041.33751974884;11051.2816666918;11060;11064.502097405637;11075.224734268684;8305.46014509055;8402.159784021549;8415.70545030921;8418.368622353348;8421.536815475085;5742.196092546963;5953.315368732651;5964.165016897725;6022.6386416813375;6109.51123910095;8422.747317580483;9721.368111072758;10203.94898025505;10226.59093636276;10246.436445995541;10454.529546170288;10466.476676166008;10468.528916198637;10486.79026419452;10635.255532112988;6659.134312539339;6884.070650462124;6939.965300173467;7171.103612048494;7273.527220730635;8275.54484503334;8624.310055195694;8625.180607842245;8634.740957771277;8636.611352489028;6478.643674597943;7469.365103966224;7567.281108294703;7692.346150577264;7711.8843217352905;7224.566526008456;7372.815679607838;7401.000864883387;7411.66647500853;7470.4023678106705;5929.9110513341475;7048.118985126859;7131.037309963089;7132.108486439195;7137.85724285501;5050.595952323732;5104.285400044071;5109.156989096906;5134.460881607527;5218.921716174336;5053.216751403538;5075.416327122281;5131.153359695758;5179.19722443022;5350.839474815762 15:54:04 INFO - PROCESS | 2331 | |4;prototype.html;294.82605262894873;299.59704197853887;309.58361004449023;312.35031135238705;312.40642778720417;4762.833300834472;5453.454654534549;5474.37044739855;5502.5873059520545;5540.806071787489;4727.4452518290955;5165.793368265275;5280.3663560372715;5420.972895135519;5451.509364157225;4131.359639255905;4135.31767258402;4138.558250632126;4150.294449943509;4174.979125104385;4134.772587507686;4135.255653982282;4142.482189726285;4153.335466325389;4165.771097526237;3757.1170774867883;3839.9824046627664;3966.2860685076676;4110.588941105895;4142.544320124784;4097.959020409802;4101.159262351211;4109.157622687342;4138.234426631083;4146.66826653867;3270.3622793555264;3358.2947581008016;3563.6614521620663;3576.7853928764357;3596.82015899204;4791.401074865642;4938.604911607063;5253.527182553588;5274.841754747364;5372.838814835562;4078.9184216315803;4287.2711639021445;4334.544872788375;4602.217623004098;4697.953020469786;4868.805247790084;4922.187839006559;5220.373555173365;5747.339056008529;5772.826815195551;3058.9388212235854;3264.7714659973813;3509.4735789631504;3931.272714547818;3952.545457272393;5153.845384638467;5887.61730487517;5889;5936.447327649109;5973.49225315853;3354.7483938704695;3906.2187562487616;3931.9553832007196;3939.290927632996;3942.3889297158985;3489.685928266468;3937.4881265435884;3952;3991.341428664296;4004.819783109767;4347.304431290978;4648.163330600524;4671.416072990876;4693.812247510062;4705.411823522059;4986.329579590805;5728.169415434739;5748.080307150919;5761.251037365116;5772.422757724278;1068.636663534402;1082.355998181081;1331.2079312808862;1366.1666383506054;1366.7425968109362;836.9621669130235;856.5203486047833;871.5032431514103;896.2426749396749;909.8999110097884;4626.398568186114;5196.792128314901;5275.947240527622;5282.573705099679;5293.12663410533;5139.45816255122;5779.739911703983;5795.507381872504;5806.44838740319;5894.941050589439;4995.675281106789;5173.896522069537;5181.979028179013;5265.973670131625;5287.23335116406;2708.688500822431;2904.4481548505714;3050.8779648813797;3056.342886279461;3061.448939190967;5360.624756267024;5680.318361796611;5683.2895888014;5952.821415357546;6088.2694076710195;5663.745131469093;5889.9679610534295;5942.68235388199;5962.433568810956;5967.6121052131475;5184.963007398535;5440;5623.297087864017;5843.298804143445;6027.8794424111275;5512.614117011931;5802.402510443552;5902.734376952962;5972.402759724167;5989.431004054608;5717.085266357363;5870.91388093187;5876.706164691834;5889.352171261244;5890.2342695449315;5553.777848885999;5767.769289228551;5980.637648129052;6088.756449742138;6164.503376132673;3323.557185468727;3909.2213043064767;3915.3735402335487;3972.2849887020616;3978.701597380266;4986.47641997585;5911.374372047721;5930.318013428338;5937.732802023833;6008.939910601013;4820.71075735457;5631.943680563142;5706.914396283976;5814.331351894417;5900.881982360414;4966.950330496793;5329.973350133379;5698.660814708457;5744.195812586157;5777.6822274775295;5211.400688920823;5307.336582927134;5557.277553917965;5619.409961953936;5663.235463212578;5236.402897116416;5576.163575463551;5668.943310566841;5685.260916080883;5758.510526605118;4659.953400465952;5008.9248661269385;5307.9469205309;5570.136628822539;5579.246801681721 15:54:04 INFO - PROCESS | 2331 | |5;yui.html;606.3390903914733;629.8425393651587;699.4264702943589;718.1489934427703;819.0171793847383;15034.248287585619;16042.919785401056;16540.91729541352;16615.41846035389;17126.914365428187;15055.774163387558;15069.6986060279;15266.923665381686;15277.312520936557;15288.235588220572;848.9023762267326;869.5304535550827;869.5652173913044;871;872.8167084912176;688.7451642892112;690.620158912596;713.4007433755644;834.3783881008657;870.4646642314995;916.5234078279291;919.8666193401987;920.1534588178858;922.6032805893464;922.8338898998211;914.2731528434877;919.9834183227538;921.4195057114042;922.8292765838274;923.829091618046;528.0916823064323;651.1079820645698;671.92944740802;676.1851968378093;681.2540268406087;15282.541523754306;15324.463643772415;15331.310091045929;15353.309101090366;15379.692406151926;14106.576802695936;14340.56978290653;14610.780838287434;14611.196384198865;14826.25868706571;13011.284379359131;13237.801432978416;13343.66640833987;13450.596482105553;13712.17726936377;4538.137753826795;4567.543245675439;4582.518835522289;4583.931241031387;4819.6867203631655;116072.83927160705;117137;118037;119171.40414297959;119986;11446.198766086294;11498.48256828428;11573.338132229634;11609.941950290195;11956.641300760817;23314.417139571648;23408.765912340998;23414.06343746231;23415;23444.179453719036;7963.960180199062;8095.2714255717265;8110.060840404749;8243.917560824435;8345.41582089248;4435.401220835211;4552.339910712928;4596.402467679247;4624.768761561976;4699.271612900006;3572.4105522589;3696.611855755107;3699.9075023124637;3700.9444858327147;3703.7407381483035;630.5586089737202;645.1645119570074;650.9414152726278;655.2366493035655;656.474820143883;592.1473078766562;621.8787336469345;629.9244090709144;641.8491654461197;658.0524045374646;9156.771080723036;9278.396904201205;9357.625694972261;9453.149216570542;9578.712638620853;512.190738632959;537.7176982084359;588.5762251179144;608.5091525761901;619.6096459230656;4418.734875907456;4436.866893993185;4658.906821863611;4834.830780922651;4898.338724272249;114755.42622286835;138432;142081;142345;144392;132839;135357.3232133833;138193.3090334542;138927;141698;107183;111082.99999999838;114622.42688786503;117386;117693.82306176829;9040.547972601213;9150.862737058816;9229.400088994194;9249.30630202724;9353.812923741623;53841.46158538521;60532.69733651303;62646.6867665677;63646;64000;85629.57185214035;91741;98898.02203955737;101415;101792.47311290485;11877.406129693654;12081.335526545772;12222.144449888778;12232.266064036186;12365.567205147776;13537.1200871943;13599.65537900852;13761.38073786702;13839.861601383856;14229.64425889361;12612.7828898284;12782.488700451877;12856.55002074923;12924.741505170032;13046.152000119962;8854.822903541764;13617.591472255848;13701.725965480435;13932.651683707989;14462.927685361507;14032.859671403156;14138.78791818103;14199.438061813398;14433.711325773216;14799.92600036993;10692;11085.27945683528;11302.547898083985;11622.535098595961;11726.296422214695;11548.018418434527;11618.797011146342;11699.649010529698;11715.355655439032;12020.879791201976 15:54:04 INFO - PROCESS | 2331 | __end_tp_report 15:54:04 INFO - PROCESS | 2331 | __start_cc_report 15:54:04 INFO - PROCESS | 2331 | _x_x_mozilla_cycle_collect,1344 15:54:04 INFO - PROCESS | 2331 | __end_cc_report 15:54:04 INFO - PROCESS | 2331 | __startTimestamp1471301644567__endTimestamp 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | ------- Summary: start ------- 15:54:04 INFO - PROCESS | 2331 | Number of tests: 6 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | [#0] dojo.html Cycles:165 Average:5727.49 Median:5945.49 stddev:644.84 (10.8%) stddev-sans-first:616.95 15:54:04 INFO - PROCESS | 2331 | Values: 3254.6 3263.2 3290.3 3305.3 3316.3 5513.3 5591.5 5656.7 5679.8 5740.5 5647.3 5671.9 5689.1 5689.4 5700.1 4311.0 5152.9 5170.4 5198.9 5270.2 5236.8 5258.3 5261.9 5264.2 5267.1 5162.5 5189.4 5189.9 5202.7 5256.1 5258.1 5293.0 5302.6 5309.5 5347.2 5087.5 5090.4 5162.6 5209.8 5217.8 5569.9 5636.3 5638.0 5664.5 5714.0 6157.4 6206.3 6233.4 6243.8 6246.6 6125.0 6166.9 6243.3 6282.9 6291.3 5781.4 5797.3 5840.5 5844.4 5856.9 6248.1 6249.4 6249.6 6260.4 6269.5 5963.6 6052.1 6143.1 6144.8 6149.4 5275.4 6244.2 6266.2 6266.8 6277.5 6187.1 6210.0 6212.1 6219.6 6227.0 6103.8 6112.1 6117.6 6141.7 6221.7 4925.8 4954.5 4955.1 4962.6 4963.3 4382.0 4433.1 4455.4 4470.2 4476.4 5748.1 5758.1 5788.4 5799.4 5805.3 6215.0 6245.4 6248.5 6254.4 6264.0 5604.2 5656.8 5686.3 5703.3 5744.7 5362.9 5394.4 5413.9 5419.1 5424.2 6222.4 6276.7 6281.7 6287.7 6294.1 6228.8 6233.8 6246.8 6264.2 6275.6 6146.1 6166.3 6275.7 6278.0 6281.6 6104.0 6190.2 6190.9 6201.9 6203.3 6178.9 6224.1 6235.6 6241.1 6257.7 5945.5 6144.1 6152.2 6154.2 6154.2 5942.4 6011.3 6044.7 6098.3 6130.1 6003.2 6031.2 6040.0 6046.5 6050.8 6053.9 6093.2 6094.1 6097.4 6097.5 5807.6 5870.1 5872.9 5874.5 5891.1 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | [#1] ext.html Cycles:180 Average:76036.94 Median:21249.47 stddev:169714.42 (798.7%) stddev-sans-first:170095.72 15:54:04 INFO - PROCESS | 2331 | Values: 485.9 509.2 521.8 523.2 528.6 30959.5 32539.8 39693.0 40937.0 40942.4 42433.8 42528.6 42530.8 42588.8 43054.7 9759.1 9958.3 9961.2 9965.9 9975.3 8848.9 9075.3 10321.9 11976.6 12255.2 12151.3 12233.4 12242.2 12243.8 12280.7 8902.9 12131.3 12223.3 12227.2 12233.1 11763.9 12068.6 12099.6 12215.1 12257.4 40176.8 40333.8 40348.0 40351.0 40429.0 3416.5 3482.1 3517.6 3874.5 4282.5 26859.5 30643.7 32438.8 32485.0 32604.8 3218.6 3376.7 3453.1 3454.2 3455.2 518389.4 522515.0 524664.0 524765.0 526489.0 60135.4 60672.7 60674.0 60717.7 60818.0 63154.0 63205.7 63276.0 63283.0 63365.7 16395.3 16604.0 16651.9 16657.9 16666.2 11711.6 12024.9 12039.6 12052.7 12703.9 18702.8 18769.2 18804.0 18823.5 18834.7 5012.6 7796.8 7807.3 7945.6 8007.2 867.2 869.8 977.6 1047.8 1110.0 28856.6 30075.5 30255.9 30463.8 30552.7 1006.6 1094.0 1147.9 1156.4 1175.2 14745.6 15754.6 15811.4 15870.8 15878.5 705457.0 711795.0 711830.0 712703.0 712958.0 58455.4 58837.8 58855.0 58937.0 58961.7 1316.4 1363.7 1377.9 1378.5 1381.2 29687.0 29781.9 29806.0 30539.5 30643.7 55266.2 56759.0 56883.9 58851.4 58997.0 648173.0 653027.0 653570.0 654019.0 654666.9 37200.8 40822.8 42907.4 42938.0 43245.6 47381.5 47575.5 48451.8 48735.0 49348.8 32257.4 37641.8 39004.8 39978.8 40597.0 32569.8 38320.8 38969.2 39387.8 40461.8 38052.8 38624.4 38842.4 38900.8 38962.8 29577.9 32882.0 33200.7 34735.8 34855.5 21880.7 22685.4 25099.6 25456.5 25612.0 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | [#2] jquery.html Cycles:180 Average:34133.97 Median:26425.60 stddev:101089.90 (382.5%) stddev-sans-first:101341.60 15:54:04 INFO - PROCESS | 2331 | Values: 320.9 356.5 377.1 380.9 406.8 12214.6 15452.8 16146.6 20040.7 20870.9 20283.9 21406.7 21420.4 21510.9 21823.0 5819.6 6086.2 6096.1 6099.6 6111.4 5622.0 6774.8 6854.2 6936.6 6939.8 6593.4 6687.4 6766.0 6875.3 6899.9 6631.5 6708.2 6755.8 6759.5 6774.6 5065.9 5792.0 5848.9 5912.2 5917.8 26218.9 26368.3 26424.3 26426.9 26532.3 2241.3 2278.0 2382.5 2406.0 2455.3 15078.5 15490.8 15713.5 15836.9 16605.8 1801.5 1807.6 1874.5 1911.4 1978.0 65809.0 88650.3 93918.1 94166.0 114036.4 10505.2 10811.3 10890.2 10982.7 10991.7 13301.1 13396.3 13398.8 13400.1 13400.9 7959.2 7960.6 8012.2 8168.2 8210.7 7852.9 7879.7 8016.2 8031.7 8097.8 5387.3 6344.7 6450.5 6473.5 6487.5 1807.7 1841.9 1843.7 1846.4 1850.2 915.2 953.0 971.0 985.4 996.3 21945.3 22391.2 22480.8 22491.0 22547.9 957.5 968.2 970.3 1085.0 1088.5 10973.2 11760.2 11870.7 11876.8 11961.6 605595.0 609209.0 612144.0 616286.0 621275.7 72923.0 113585.4 115286.4 116478.0 117685.0 984.8 997.1 1035.1 1069.0 1069.8 19262.9 19572.9 20142.6 20169.4 20479.9 49981.0 50347.7 51919.7 52058.0 52402.7 60459.7 70675.0 71885.6 72175.9 73298.0 6573.3 6840.0 7876.8 8154.6 8156.8 13549.9 13580.3 13810.0 13873.0 13955.9 10943.4 11374.8 11521.6 11534.9 11945.6 9372.8 11200.0 11425.3 11451.0 11783.9 11591.2 11748.8 11759.8 12085.6 12197.0 6786.2 7970.1 8095.2 8288.0 8483.5 8331.7 10602.3 11040.6 11550.4 11662.7 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | [#3] mootools.html Cycles:180 Average:5768.89 Median:4914.07 stddev:3244.20 (66.0%) stddev-sans-first:3237.59 15:54:04 INFO - PROCESS | 2331 | Values: 1520.3 1557.8 1686.9 1735.7 1905.9 8428.4 8975.1 8980.6 8993.7 9011.2 7825.3 8092.4 8544.5 8775.0 8780.9 2359.0 2384.5 2388.4 2390.5 2399.3 2026.7 2302.4 2327.8 2348.8 2352.5 2359.2 2378.4 2383.8 2392.4 2400.6 2325.5 2338.3 2348.9 2352.8 2384.4 2340.9 2343.5 2346.8 2350.5 2353.9 8637.6 8797.3 8817.7 8829.0 8837.2 5263.0 5308.8 5329.3 5405.2 5410.5 3436.3 3568.9 3577.5 3642.8 3643.5 1063.0 1094.3 1112.8 1121.7 1123.5 11644.8 11766.9 11768.8 11769.9 11771.5 4545.3 4651.6 4850.3 4861.5 4867.1 10061.8 10082.6 10085.4 10093.7 10098.5 5429.5 5432.0 5434.2 5436.0 5439.2 4449.0 4615.8 4822.6 4961.0 4973.5 3900.9 3970.5 4037.6 4050.7 4165.4 1275.3 1277.8 1280.8 1289.5 1299.2 866.0 884.9 909.9 913.9 937.8 5971.9 6084.8 6100.7 6172.5 6206.3 754.9 839.1 881.2 925.3 935.2 3851.5 4032.6 4051.5 4149.1 4189.0 10687.2 10995.8 11117.2 11123.5 11153.7 11041.3 11051.3 11060.0 11064.5 11075.2 8305.5 8402.2 8415.7 8418.4 8421.5 5742.2 5953.3 5964.2 6022.6 6109.5 8422.7 9721.4 10203.9 10226.6 10246.4 10454.5 10466.5 10468.5 10486.8 10635.3 6659.1 6884.1 6940.0 7171.1 7273.5 8275.5 8624.3 8625.2 8634.7 8636.6 6478.6 7469.4 7567.3 7692.3 7711.9 7224.6 7372.8 7401.0 7411.7 7470.4 5929.9 7048.1 7131.0 7132.1 7137.9 5050.6 5104.3 5109.2 5134.5 5218.9 5053.2 5075.4 5131.2 5179.2 5350.8 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | [#4] prototype.html Cycles:180 Average:4600.61 Median:5204.10 stddev:1431.88 (27.5%) stddev-sans-first:1398.95 15:54:04 INFO - PROCESS | 2331 | Values: 294.8 299.6 309.6 312.4 312.4 4762.8 5453.5 5474.4 5502.6 5540.8 4727.4 5165.8 5280.4 5421.0 5451.5 4131.4 4135.3 4138.6 4150.3 4175.0 4134.8 4135.3 4142.5 4153.3 4165.8 3757.1 3840.0 3966.3 4110.6 4142.5 4098.0 4101.2 4109.2 4138.2 4146.7 3270.4 3358.3 3563.7 3576.8 3596.8 4791.4 4938.6 5253.5 5274.8 5372.8 4078.9 4287.3 4334.5 4602.2 4698.0 4868.8 4922.2 5220.4 5747.3 5772.8 3058.9 3264.8 3509.5 3931.3 3952.5 5153.8 5887.6 5889.0 5936.4 5973.5 3354.7 3906.2 3932.0 3939.3 3942.4 3489.7 3937.5 3952.0 3991.3 4004.8 4347.3 4648.2 4671.4 4693.8 4705.4 4986.3 5728.2 5748.1 5761.3 5772.4 1068.6 1082.4 1331.2 1366.2 1366.7 837.0 856.5 871.5 896.2 909.9 4626.4 5196.8 5275.9 5282.6 5293.1 5139.5 5779.7 5795.5 5806.4 5894.9 4995.7 5173.9 5182.0 5266.0 5287.2 2708.7 2904.4 3050.9 3056.3 3061.4 5360.6 5680.3 5683.3 5952.8 6088.3 5663.7 5890.0 5942.7 5962.4 5967.6 5185.0 5440.0 5623.3 5843.3 6027.9 5512.6 5802.4 5902.7 5972.4 5989.4 5717.1 5870.9 5876.7 5889.4 5890.2 5553.8 5767.8 5980.6 6088.8 6164.5 3323.6 3909.2 3915.4 3972.3 3978.7 4986.5 5911.4 5930.3 5937.7 6008.9 4820.7 5631.9 5706.9 5814.3 5900.9 4967.0 5330.0 5698.7 5744.2 5777.7 5211.4 5307.3 5557.3 5619.4 5663.2 5236.4 5576.2 5668.9 5685.3 5758.5 4660.0 5008.9 5307.9 5570.1 5579.2 15:54:04 INFO - PROCESS | 2331 | 15:54:04 INFO - PROCESS | 2331 | [#5] yui.html Cycles:180 Average:25330.69 Median:13508.30 stddev:40115.20 (297.0%) stddev-sans-first:40184.77 15:54:04 INFO - PROCESS | 2331 | Values: 606.3 629.8 699.4 718.1 819.0 15034.2 16042.9 16540.9 16615.4 17126.9 15055.8 15069.7 15266.9 15277.3 15288.2 848.9 869.5 869.6 871.0 872.8 688.7 690.6 713.4 834.4 870.5 916.5 919.9 920.2 922.6 922.8 914.3 920.0 921.4 922.8 923.8 528.1 651.1 671.9 676.2 681.3 15282.5 15324.5 15331.3 15353.3 15379.7 14106.6 14340.6 14610.8 14611.2 14826.3 13011.3 13237.8 13343.7 13450.6 13712.2 4538.1 4567.5 4582.5 4583.9 4819.7 116072.8 117137.0 118037.0 119171.4 119986.0 11446.2 11498.5 11573.3 11609.9 11956.6 23314.4 23408.8 23414.1 23415.0 23444.2 7964.0 8095.3 8110.1 8243.9 8345.4 4435.4 4552.3 4596.4 4624.8 4699.3 3572.4 3696.6 3699.9 3700.9 3703.7 630.6 645.2 650.9 655.2 656.5 592.1 621.9 629.9 641.8 658.1 9156.8 9278.4 9357.6 9453.1 9578.7 512.2 537.7 588.6 608.5 619.6 4418.7 4436.9 4658.9 4834.8 4898.3 114755.4 138432.0 142081.0 142345.0 144392.0 132839.0 135357.3 138193.3 138927.0 141698.0 107183.0 111083.0 114622.4 117386.0 117693.8 9040.5 9150.9 9229.4 9249.3 9353.8 53841.5 60532.7 62646.7 63646.0 64000.0 85629.6 91741.0 98898.0 101415.0 101792.5 11877.4 12081.3 12222.1 12232.3 12365.6 13537.1 13599.7 13761.4 13839.9 14229.6 12612.8 12782.5 12856.6 12924.7 13046.2 8854.8 13617.6 13701.7 13932.7 14462.9 14032.9 14138.8 14199.4 14433.7 14799.9 10692.0 11085.3 11302.5 11622.5 11726.3 11548.0 11618.8 11699.6 11715.4 12020.9 15:54:04 INFO - PROCESS | 2331 | -------- Summary: end -------- 15:54:04 INFO - PROCESS | 2331 | 15:54:05 INFO - TEST-INFO | 2331: exit 0 15:54:05 INFO - TEST-OK | dromaeo_css | took 1117783ms 15:54:05 INFO - TEST-START | kraken 15:54:05 INFO - operating with platform_type : mac_ 15:54:05 INFO - Initialising browser for kraken test... 15:54:05 INFO - TEST-INFO | started process 2333 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpOkkPYT/profile http://localhost:53109/getInfo.html) 15:54:07 INFO - PROCESS | 2333 | __metrics Screen width/height:1600/1200 15:54:07 INFO - PROCESS | 2333 | colorDepth:24 15:54:07 INFO - PROCESS | 2333 | Browser inner width/height: 1024/689 15:54:07 INFO - PROCESS | 2333 | __metrics 15:54:07 INFO - PROCESS | 2333 | 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" 15:54:07 INFO - PROCESS | 2333 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:54:08 INFO - TEST-INFO | 2333: exit 0 15:54:08 INFO - Browser initialized. 15:54:08 INFO - Running cycle 1/1 for kraken test... 15:54:08 INFO - TEST-INFO | started process 2334 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpOkkPYT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:54:20 INFO - PROCESS | 2334 | RSS: Main: 228425728 15:54:20 INFO - PROCESS | 2334 | 15:54:23 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53109/tests/kraken/driver.html?testName=audio-beat-detection) 15:54:23 INFO - PROCESS | 2334 | RSS: Main: 285331456 15:54:23 INFO - PROCESS | 2334 | 15:54:26 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53109/tests/kraken/driver.html?testName=audio-dft) 15:54:27 INFO - PROCESS | 2334 | RSS: Main: 294326272 15:54:27 INFO - PROCESS | 2334 | 15:54:30 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53109/tests/kraken/driver.html?testName=audio-fft) 15:54:31 INFO - PROCESS | 2334 | RSS: Main: 387366912 15:54:31 INFO - PROCESS | 2334 | 15:54:33 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53109/tests/kraken/driver.html?testName=audio-oscillator) 15:54:34 INFO - PROCESS | 2334 | RSS: Main: 289509376 15:54:34 INFO - PROCESS | 2334 | 15:54:36 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53109/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:54:36 INFO - PROCESS | 2334 | RSS: Main: 293904384 15:54:36 INFO - PROCESS | 2334 | 15:54:39 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53109/tests/kraken/driver.html?testName=imaging-darkroom) 15:54:40 INFO - PROCESS | 2334 | RSS: Main: 331145216 15:54:40 INFO - PROCESS | 2334 | 15:54:42 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53109/tests/kraken/driver.html?testName=imaging-desaturate) 15:54:43 INFO - PROCESS | 2334 | RSS: Main: 331956224 15:54:43 INFO - PROCESS | 2334 | 15:54:46 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53109/tests/kraken/driver.html?testName=json-parse-financial) 15:54:47 INFO - PROCESS | 2334 | RSS: Main: 332296192 15:54:47 INFO - PROCESS | 2334 | 15:54:48 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53109/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:54:49 INFO - PROCESS | 2334 | RSS: Main: 302059520 15:54:49 INFO - PROCESS | 2334 | 15:54:51 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:54:51 INFO - PROCESS | 2334 | RSS: Main: 308510720 15:54:51 INFO - PROCESS | 2334 | 15:54:53 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:54:54 INFO - PROCESS | 2334 | RSS: Main: 305856512 15:54:54 INFO - PROCESS | 2334 | 15:54:57 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:54:58 INFO - PROCESS | 2334 | RSS: Main: 313331712 15:54:58 INFO - PROCESS | 2334 | 15:55:01 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:55:01 INFO - PROCESS | 2334 | RSS: Main: 314560512 15:55:01 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | Cycle 1(1): loaded http://localhost:53109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53109/tests/kraken/driver.html?testName=ai-astar) 15:55:03 INFO - PROCESS | 2334 | RSS: Main: 357888000 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | __start_tp_report 15:55:03 INFO - PROCESS | 2334 | _x_x_mozilla_page_load 15:55:03 INFO - PROCESS | 2334 | _x_x_mozilla_page_load_details 15:55:03 INFO - PROCESS | 2334 | |i|pagename|runs| 15:55:03 INFO - PROCESS | 2334 | |0;ai-astar;103;113;112;113;108;113;111;112;112;113 15:55:03 INFO - PROCESS | 2334 | |1;audio-beat-detection;148;109;107;168;111;109;131;107;139;104 15:55:03 INFO - PROCESS | 2334 | |2;audio-dft;188;184;183;184;184;184;181;182;191;215 15:55:03 INFO - PROCESS | 2334 | |3;audio-fft;77;58;62;92;76;80;88;65;85;56 15:55:03 INFO - PROCESS | 2334 | |4;audio-oscillator;87;68;104;71;69;67;97;71;68;103 15:55:03 INFO - PROCESS | 2334 | |5;imaging-gaussian-blur;87;84;86;84;92;83;87;84;86;84 15:55:03 INFO - PROCESS | 2334 | |6;imaging-darkroom;93;92;93;93;93;92;93;93;93;93 15:55:03 INFO - PROCESS | 2334 | |7;imaging-desaturate;83;86;84;83;84;85;85;85;86;85 15:55:03 INFO - PROCESS | 2334 | |8;json-parse-financial;55;54;55;56;54;54;54;53;54;54 15:55:03 INFO - PROCESS | 2334 | |9;json-stringify-tinderbox;64;64;80;76;74;69;68;70;70;69 15:55:03 INFO - PROCESS | 2334 | |10;stanford-crypto-aes;88;85;87;81;86;84;73;100;75;103 15:55:03 INFO - PROCESS | 2334 | |11;stanford-crypto-ccm;139;139;138;133;138;148;155;139;167;165 15:55:03 INFO - PROCESS | 2334 | |12;stanford-crypto-pbkdf2;168;168;170;168;169;169;166;166;167;166 15:55:03 INFO - PROCESS | 2334 | |13;stanford-crypto-sha256-iterative;69;67;70;67;66;67;67;66;67;67 15:55:03 INFO - PROCESS | 2334 | __end_tp_report 15:55:03 INFO - PROCESS | 2334 | __start_cc_report 15:55:03 INFO - PROCESS | 2334 | _x_x_mozilla_cycle_collect,2088 15:55:03 INFO - PROCESS | 2334 | __end_cc_report 15:55:03 INFO - PROCESS | 2334 | __startTimestamp1471301703714__endTimestamp 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | ------- Summary: start ------- 15:55:03 INFO - PROCESS | 2334 | Number of tests: 14 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.50 stddev:3.20 (2.8%) stddev-sans-first:1.62 15:55:03 INFO - PROCESS | 2334 | Values: 103.0 113.0 112.0 113.0 108.0 113.0 111.0 112.0 112.0 113.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:121.00 stddev:22.06 (18.2%) stddev-sans-first:21.51 15:55:03 INFO - PROCESS | 2334 | Values: 148.0 109.0 107.0 168.0 111.0 109.0 131.0 107.0 139.0 104.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#2] audio-dft Cycles:10 Average:187.60 Median:184.00 stddev:10.06 (5.5%) stddev-sans-first:10.67 15:55:03 INFO - PROCESS | 2334 | Values: 188.0 184.0 183.0 184.0 184.0 184.0 181.0 182.0 191.0 215.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#3] audio-fft Cycles:10 Average:73.90 Median:78.50 stddev:12.89 (16.4%) stddev-sans-first:13.62 15:55:03 INFO - PROCESS | 2334 | Values: 77.0 58.0 62.0 92.0 76.0 80.0 88.0 65.0 85.0 56.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:70.00 stddev:15.57 (22.2%) stddev-sans-first:16.33 15:55:03 INFO - PROCESS | 2334 | Values: 87.0 68.0 104.0 71.0 69.0 67.0 97.0 71.0 68.0 103.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:2.63 (3.1%) stddev-sans-first:2.74 15:55:03 INFO - PROCESS | 2334 | Values: 87.0 84.0 86.0 84.0 92.0 83.0 87.0 84.0 86.0 84.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 15:55:03 INFO - PROCESS | 2334 | Values: 93.0 92.0 93.0 93.0 93.0 92.0 93.0 93.0 93.0 93.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.07 (1.3%) stddev-sans-first:0.97 15:55:03 INFO - PROCESS | 2334 | Values: 83.0 86.0 84.0 83.0 84.0 85.0 85.0 85.0 86.0 85.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 15:55:03 INFO - PROCESS | 2334 | Values: 55.0 54.0 55.0 56.0 54.0 54.0 54.0 53.0 54.0 54.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.00 stddev:5.04 (7.2%) stddev-sans-first:4.78 15:55:03 INFO - PROCESS | 2334 | Values: 64.0 64.0 80.0 76.0 74.0 69.0 68.0 70.0 70.0 69.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:84.50 stddev:9.48 (11.2%) stddev-sans-first:10.04 15:55:03 INFO - PROCESS | 2334 | Values: 88.0 85.0 87.0 81.0 86.0 84.0 73.0 100.0 75.0 103.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:143.50 stddev:12.16 (8.5%) stddev-sans-first:12.62 15:55:03 INFO - PROCESS | 2334 | Values: 139.0 139.0 138.0 133.0 138.0 148.0 155.0 139.0 167.0 165.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.00 stddev:1.42 (0.8%) stddev-sans-first:1.50 15:55:03 INFO - PROCESS | 2334 | Values: 168.0 168.0 170.0 168.0 169.0 169.0 166.0 166.0 167.0 166.0 15:55:03 INFO - PROCESS | 2334 | 15:55:03 INFO - PROCESS | 2334 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:1.25 (1.9%) stddev-sans-first:1.17 15:55:03 INFO - PROCESS | 2334 | Values: 69.0 67.0 70.0 67.0 66.0 67.0 67.0 66.0 67.0 67.0 15:55:03 INFO - PROCESS | 2334 | -------- Summary: end -------- 15:55:03 INFO - PROCESS | 2334 | 15:55:04 INFO - TEST-INFO | 2334: exit 0 15:55:04 INFO - TEST-OK | kraken | took 59344ms 15:55:04 INFO - SUITE-END | took 1177s 15:55:04 INFO - Completed test suite (00:19:37) 15:55:04 INFO - javascript benchmark 15:55:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.593175853017, 3263.2331402120503, 3290.2596915693957, 3305.3224089061746, 3316.3035762489876, 5513.338399392069, 5591.524720398766, 5656.745446454909, 5679.772809087642, 5740.540756739461, 5647.29408823897, 5671.943280567193, 5689.061304884689, 5689.431056894308, 5700.144978253254, 4310.956890431103, 5152.8711782205555, 5170.405403378603, 5198.92201616976, 5270.156774916014, 5236.842894713146, 5258.2638430619745, 5261.895002049574, 5264.157734762439, 5267.051930652481, 5162.5095615916425, 5189.403218629849, 5189.948100519003, 5202.687838729688, 5256.080185967459, 5258.132408152669, 5292.9735351323, 5302.602304827154, 5309.548688361455, 5347.197920311945, 5087.465816089294, 5090.389153301628, 5162.612804039674, 5209.843704688866, 5217.8173763918085, 5569.8607534811545, 5636.2954630671165, 5637.971810140964, 5664.54683625309, 5713.971430142864, 6157.3534778848025, 6206.317305096436, 6233.438990490878, 6243.7814676486105, 6246.625202487865, 6124.9912799099075, 6166.876662466726, 6243.344448832898, 6282.905756413657, 6291.307956124822, 5781.36404995446, 5797.333306669787, 5840.503557197686, 5844.357120716718, 5856.853578660568, 6248.062790581449, 6249.375062493714, 6249.562530622813, 6260.436560709467, 6269.498440124779, 5963.5527335450015, 6052.12244224585, 6143.139960405547, 6144.754209831557, 6149.354317796657, 5275.419703832576, 6244.219472565929, 6266.248050234001, 6266.811995640138, 6277.466415354655, 6187.071939209065, 6210.037444196157, 6212.06818977148, 6219.626822390671, 6227.003679411272, 6103.816885493442, 6112.07796728157, 6117.583823141483, 6141.723622437001, 6221.657808820468, 4925.756214286784, 4954.529319714621, 4955.108080545464, 4962.5533701967, 4963.255511673278, 4381.992141807402, 4433.069055498381, 4455.376247325377, 4470.240059189946, 4476.395686582335, 5748.109043098327, 5758.107493338539, 5788.363280039192, 5799.3620701722775, 5805.274340707411, 6215.0, 6245.406686364788, 6248.4688801452385, 6254.437100660963, 6264.0, 5604.243427137366, 5656.8020119295625, 5686.260786097863, 5703.287089113861, 5744.684042377709, 5362.91955620666, 5394.433584473652, 5413.891722165535, 5419.078756611386, 5424.213489044067, 6222.439980401786, 6276.717547710364, 6281.654509001866, 6287.685615719287, 6294.0558916162945, 6228.781992630236, 6233.781817636361, 6246.750129994749, 6264.248290205204, 6275.560710750204, 6146.139540464249, 6166.290876549075, 6275.6862156892885, 6277.96861015692, 6281.59169653971, 6104.004449388181, 6190.1952746142715, 6190.907136392868, 6201.937980620136, 6203.348648391854, 6178.938210617836, 6224.06639004153, 6235.625862448086, 6241.095041219089, 6257.687115644108, 5945.494632956247, 6144.1398204250545, 6152.200213972155, 6154.169187159676, 6154.230721159855, 5942.405759424023, 6011.338752737108, 6044.697765111639, 6098.329183789869, 6130.092410195535, 6003.189569408249, 6031.215941927697, 6039.969800151147, 6046.54650901193, 6050.818475445743, 6053.878922421439, 6093.2078829751845, 6094.11635312886, 6097.359777223505, 6097.5426842987845, 5807.593468457337, 5870.148828419769, 5872.941270587239, 5874.530037597067, 5891.116332550152], "value": 5685.007397934061, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.8809591650046, 509.22851879402714, 521.8330134357003, 523.2413001148336, 528.6378830501105, 30959.535606965885, 32539.83730081349, 39693.00767480814, 40937.0, 40942.38586421206, 42433.7878310608, 42528.57471425285, 42530.787346063225, 42588.78705606476, 43054.70835874921, 9759.07288807564, 9958.253131015166, 9961.153301969342, 9965.850512242323, 9975.301728878982, 8848.867266991001, 9075.319351048665, 10321.948390258076, 11976.640700778991, 12255.203411778206, 12151.27092374456, 12233.388330583479, 12242.204256723284, 12243.816342754866, 12280.692982675415, 8902.86645700315, 12131.272123672563, 12223.2666040037, 12227.248869004447, 12233.143679942408, 11763.941180294129, 12068.577599784054, 12099.576514822027, 12215.144939854214, 12257.448414821265, 40176.79911600423, 40333.79833100845, 40348.0, 40351.0, 40429.0, 3416.538767266412, 3482.1120614243205, 3517.5954765202073, 3874.5156855393075, 4282.528921818597, 26859.46281074387, 30643.693563064306, 32438.83780581082, 32485.0, 32604.836975814967, 3218.6459489456142, 3376.74674399421, 3453.067671728644, 3454.2055327274616, 3455.1707590178426, 518389.4080529611, 522515.0, 524664.0, 524765.0, 526489.0, 60135.398646013855, 60672.696636516535, 60674.0, 60717.69641151766, 60818.0, 63154.0, 63205.68397157985, 63276.0, 63283.0, 63365.68317158385, 16395.344186232418, 16604.003759774452, 16651.917625354312, 16657.91671041661, 16666.166691665367, 11711.648650540497, 12024.857638524327, 12039.578614748618, 12052.698682532831, 12703.93648031754, 18702.81297187038, 18769.155388007573, 18804.0, 18823.529411764815, 18834.71747923782, 5012.62152907946, 7796.84406311882, 7807.336376408069, 7945.602719863984, 8007.239312265326, 867.1935100356603, 869.7869022089502, 977.5747549815853, 1047.8009178256107, 1110.0, 28856.567151492745, 30075.548866767014, 30255.941042063423, 30463.847680761453, 30552.694473055428, 1006.6146118267358, 1094.0034437152228, 1147.8852114788453, 1156.3986726902133, 1175.2360965372404, 14745.557633271019, 15754.606134846721, 15811.367545298059, 15870.761938570931, 15878.52364429069, 705457.0, 711795.0, 711830.0, 712703.0, 712958.0, 58455.41544584585, 58837.82324353574, 58855.0, 58937.0, 58961.705191474626, 1316.374722007039, 1363.6863521390135, 1377.889768818492, 1378.5312993582231, 1381.2265131526176, 29687.0, 29781.851090744407, 29806.0, 30539.54190687097, 30643.693563064084, 55266.171007435725, 56759.0, 56883.86232275414, 58851.41148588459, 58997.0, 648173.0, 653027.0, 653570.0, 654019.0, 654666.9066618736, 37200.81399592985, 40822.79588602038, 42907.35638965481, 42938.0, 43245.567544324156, 47381.526184739094, 47575.52424475711, 48451.75774121107, 48735.0, 49348.75325623349, 32257.35485290328, 37641.81179094197, 39004.80497597494, 39978.80010599928, 40597.0, 32569.83715081504, 38320.80839595784, 38969.2206155881, 39387.818365449086, 40461.79769101136, 38052.809735951145, 38624.42063369108, 38842.417363739, 38900.832975010795, 38962.80518597389, 29577.85211073931, 32882.0, 33200.66799331976, 34735.82632086823, 34855.47716784199, 21880.717892315643, 22685.43286417853, 25099.623505647796, 25456.490870182122, 25611.97552097895], "value": 20953.014705058617, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.93420848726004, 356.5026787631253, 377.05359547535676, 380.87431147721253, 406.7640768354355, 12214.633560993174, 15452.845471545295, 16146.596335091614, 20040.69938950917, 20870.89564552179, 20283.898580507113, 21406.678899816514, 21420.357389278306, 21510.89244553775, 21823.0, 5819.621724587899, 6086.2087928569445, 6096.055111457731, 6099.57302988791, 6111.388861113897, 5621.971890140564, 6774.796756097325, 6854.177498700163, 6936.618485983269, 6939.757108501203, 6593.406593406592, 6687.364700353459, 6765.966170169142, 6875.278095799944, 6899.862002759942, 6631.535792494527, 6708.195016597991, 6755.831104222385, 6759.5268331216585, 6774.627395493246, 5065.898682026375, 5791.971040144815, 5848.853778655525, 5912.201852749867, 5917.822465326047, 26218.86890565535, 26368.340791480176, 26424.339391515172, 26426.86786566074, 26532.336691582866, 2241.2827895073583, 2277.9886100569556, 2382.5234953009467, 2405.987970060156, 2455.3247856839334, 15078.472253471076, 15490.845091549052, 15713.450029248921, 15836.920815395964, 16605.750913736305, 1801.5225965119255, 1807.638472305531, 1874.4657772534763, 1911.3979096584671, 1978.0, 65809.0, 88650.3404897854, 93918.12163756754, 94166.0, 114036.42981785121, 10505.216117728807, 10811.297265677864, 10890.237683362247, 10982.725431864108, 10991.670249892515, 13301.135426197266, 13396.26320552379, 13398.799018014737, 13400.128991615515, 13400.865991339962, 7959.243871832166, 7960.641771120314, 8012.158723334082, 8168.183181681784, 8210.712625058095, 7852.921470785333, 7879.724209652634, 8016.238457346542, 8031.678732850737, 8097.825815256755, 5387.326584176978, 6344.746210151635, 6450.548461607643, 6473.514486413538, 6487.481001519867, 1807.692692242322, 1841.8710690251553, 1843.7186155621982, 1846.3999200259968, 1850.1859181959894, 915.2129168914728, 952.9952350238204, 970.9853203402321, 985.4233226837147, 996.3324572536418, 21945.341639750834, 22391.21630742916, 22480.775192248195, 22491.0, 22547.88726056359, 957.5355952363099, 968.2302569457298, 970.2819913264134, 1085.0234788690084, 1088.450332582041, 10973.23187376876, 11760.176787624956, 11870.703232419259, 11876.76246475083, 11961.641150765317, 605595.0, 609209.0, 612144.0, 616286.0, 621275.723456594, 72923.0, 113585.4320728391, 115286.4235678833, 116478.0, 117685.0, 984.7735020945223, 997.1026076531035, 1035.1253191053547, 1068.9946550267355, 1069.8181309177457, 19262.94053826997, 19572.902135489232, 20142.625543300022, 20169.40866598163, 20479.897600511904, 49981.0, 50347.74826125846, 51919.70200745011, 52058.0, 52402.737986309825, 60459.69770151121, 70675.0, 71885.6405717968, 72175.91736124067, 73298.0, 6573.309802470863, 6839.965800170968, 7876.84246315082, 8154.633041513028, 8156.755297341089, 13549.932250338685, 13580.32098395096, 13810.0, 13873.0, 13955.930220348833, 10943.398113103856, 11374.772504549697, 11521.596744113916, 11534.884651153381, 11945.581904633296, 9372.765680858032, 11199.992000719649, 11425.31448113116, 11451.0, 11783.941080294544, 11591.188616796744, 11748.765024699287, 11759.824847291355, 12085.637430877088, 12197.02423806111, 6786.219584747817, 7970.123286438371, 8095.190480951857, 8288.0, 8483.490990540588, 8331.6667333306, 10602.310849794738, 11040.558377664804, 11550.422478875855, 11662.650120496399], "value": 9750.068256252329, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.2626726037872, 1557.7896983907174, 1686.9156542172905, 1735.652869426115, 1905.8951757653333, 8428.410011299211, 8975.057618950013, 8980.595873185714, 8993.73018809435, 9011.18899299063, 7825.295723384894, 8092.39307051972, 8544.530050847201, 8774.956125219382, 8780.912190878089, 2358.976410235897, 2384.451576137481, 2388.4148383645943, 2390.5338459000413, 2399.2682231919257, 2026.7365242518451, 2302.4128847143957, 2327.755585663498, 2348.823838212132, 2352.517733864562, 2359.2096647623066, 2378.4291769975252, 2383.809295256384, 2392.3899405651537, 2400.5918993771015, 2325.453518423169, 2338.2634470141934, 2348.8825558722083, 2352.800011998982, 2384.391980045095, 2340.8595484271, 2343.4961483281177, 2346.8122550195944, 2350.4828937633692, 2353.9293821185393, 8637.568121593895, 8797.29621630268, 8817.735467935909, 8828.955855220684, 8837.204651581389, 5262.973720124577, 5308.814191503279, 5329.333833270841, 5405.216243644689, 5410.5401040911565, 3436.3127374525193, 3568.892933212008, 3577.5349204603235, 3642.817859107034, 3643.4534819777246, 1062.9681109566648, 1094.2945335059214, 1112.7551938573502, 1121.7139629394533, 1123.5225029362489, 11644.825327620092, 11766.941165294204, 11768.823467647991, 11769.882301176964, 11771.470283837249, 4545.2954792007295, 4651.604613607881, 4850.320955066293, 4861.465238823726, 4867.099586576494, 10061.798764024825, 10082.647107351208, 10085.394876307446, 10093.69718908434, 10098.545565449573, 5429.511343979061, 5431.967926094029, 5434.239206511092, 5436.0, 5439.211314359475, 4449.043455656984, 4615.815367385334, 4822.590079843182, 4961.032598643302, 4973.52751488608, 3900.863469778544, 3970.5235371754998, 4037.555868854424, 4050.736702114353, 4165.375193720905, 1275.3304515129446, 1277.8274932883942, 1280.769461496921, 1289.5357671238346, 1299.2399446323818, 865.9567021648892, 884.9115088491228, 909.8908131024319, 913.9360244782802, 937.8265020971143, 5971.940280597139, 6084.756609735649, 6100.69496525172, 6172.506199504029, 6206.348333425016, 754.9245075492407, 839.1231163434226, 881.2377293641083, 925.3430064654171, 935.2050756856753, 3851.480050193188, 4032.6370626643743, 4051.513818341818, 4149.087200815816, 4188.958110418917, 10687.167900506372, 10995.780084398268, 11117.221794474472, 11123.499442524944, 11153.665390038312, 11041.33751974884, 11051.2816666918, 11060.0, 11064.502097405637, 11075.224734268684, 8305.46014509055, 8402.159784021549, 8415.70545030921, 8418.368622353348, 8421.536815475085, 5742.196092546963, 5953.315368732651, 5964.165016897725, 6022.6386416813375, 6109.51123910095, 8422.747317580483, 9721.368111072758, 10203.94898025505, 10226.59093636276, 10246.436445995541, 10454.529546170288, 10466.476676166008, 10468.528916198637, 10486.79026419452, 10635.255532112988, 6659.134312539339, 6884.070650462124, 6939.965300173467, 7171.103612048494, 7273.527220730635, 8275.54484503334, 8624.310055195694, 8625.180607842245, 8634.740957771277, 8636.611352489028, 6478.643674597943, 7469.365103966224, 7567.281108294703, 7692.346150577264, 7711.8843217352905, 7224.566526008456, 7372.815679607838, 7401.000864883387, 7411.66647500853, 7470.4023678106705, 5929.9110513341475, 7048.118985126859, 7131.037309963089, 7132.108486439195, 7137.85724285501, 5050.595952323732, 5104.285400044071, 5109.156989096906, 5134.460881607527, 5218.921716174336, 5053.216751403538, 5075.416327122281, 5131.153359695758, 5179.19722443022, 5350.839474815762], "value": 4627.27891747311, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.82605262894873, 299.59704197853887, 309.58361004449023, 312.35031135238705, 312.40642778720417, 4762.833300834472, 5453.454654534549, 5474.37044739855, 5502.5873059520545, 5540.806071787489, 4727.4452518290955, 5165.793368265275, 5280.3663560372715, 5420.972895135519, 5451.509364157225, 4131.359639255905, 4135.31767258402, 4138.558250632126, 4150.294449943509, 4174.979125104385, 4134.772587507686, 4135.255653982282, 4142.482189726285, 4153.335466325389, 4165.771097526237, 3757.1170774867883, 3839.9824046627664, 3966.2860685076676, 4110.588941105895, 4142.544320124784, 4097.959020409802, 4101.159262351211, 4109.157622687342, 4138.234426631083, 4146.66826653867, 3270.3622793555264, 3358.2947581008016, 3563.6614521620663, 3576.7853928764357, 3596.82015899204, 4791.401074865642, 4938.604911607063, 5253.527182553588, 5274.841754747364, 5372.838814835562, 4078.9184216315803, 4287.2711639021445, 4334.544872788375, 4602.217623004098, 4697.953020469786, 4868.805247790084, 4922.187839006559, 5220.373555173365, 5747.339056008529, 5772.826815195551, 3058.9388212235854, 3264.7714659973813, 3509.4735789631504, 3931.272714547818, 3952.545457272393, 5153.845384638467, 5887.61730487517, 5889.0, 5936.447327649109, 5973.49225315853, 3354.7483938704695, 3906.2187562487616, 3931.9553832007196, 3939.290927632996, 3942.3889297158985, 3489.685928266468, 3937.4881265435884, 3952.0, 3991.341428664296, 4004.819783109767, 4347.304431290978, 4648.163330600524, 4671.416072990876, 4693.812247510062, 4705.411823522059, 4986.329579590805, 5728.169415434739, 5748.080307150919, 5761.251037365116, 5772.422757724278, 1068.636663534402, 1082.355998181081, 1331.2079312808862, 1366.1666383506054, 1366.7425968109362, 836.9621669130235, 856.5203486047833, 871.5032431514103, 896.2426749396749, 909.8999110097884, 4626.398568186114, 5196.792128314901, 5275.947240527622, 5282.573705099679, 5293.12663410533, 5139.45816255122, 5779.739911703983, 5795.507381872504, 5806.44838740319, 5894.941050589439, 4995.675281106789, 5173.896522069537, 5181.979028179013, 5265.973670131625, 5287.23335116406, 2708.688500822431, 2904.4481548505714, 3050.8779648813797, 3056.342886279461, 3061.448939190967, 5360.624756267024, 5680.318361796611, 5683.2895888014, 5952.821415357546, 6088.2694076710195, 5663.745131469093, 5889.9679610534295, 5942.68235388199, 5962.433568810956, 5967.6121052131475, 5184.963007398535, 5440.0, 5623.297087864017, 5843.298804143445, 6027.8794424111275, 5512.614117011931, 5802.402510443552, 5902.734376952962, 5972.402759724167, 5989.431004054608, 5717.085266357363, 5870.91388093187, 5876.706164691834, 5889.352171261244, 5890.2342695449315, 5553.777848885999, 5767.769289228551, 5980.637648129052, 6088.756449742138, 6164.503376132673, 3323.557185468727, 3909.2213043064767, 3915.3735402335487, 3972.2849887020616, 3978.701597380266, 4986.47641997585, 5911.374372047721, 5930.318013428338, 5937.732802023833, 6008.939910601013, 4820.71075735457, 5631.943680563142, 5706.914396283976, 5814.331351894417, 5900.881982360414, 4966.950330496793, 5329.973350133379, 5698.660814708457, 5744.195812586157, 5777.6822274775295, 5211.400688920823, 5307.336582927134, 5557.277553917965, 5619.409961953936, 5663.235463212578, 5236.402897116416, 5576.163575463551, 5668.943310566841, 5685.260916080883, 5758.510526605118, 4659.953400465952, 5008.9248661269385, 5307.9469205309, 5570.136628822539, 5579.246801681721], "value": 4135.274047020902, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.3390903914733, 629.8425393651587, 699.4264702943589, 718.1489934427703, 819.0171793847383, 15034.248287585619, 16042.919785401056, 16540.91729541352, 16615.41846035389, 17126.914365428187, 15055.774163387558, 15069.6986060279, 15266.923665381686, 15277.312520936557, 15288.235588220572, 848.9023762267326, 869.5304535550827, 869.5652173913044, 871.0, 872.8167084912176, 688.7451642892112, 690.620158912596, 713.4007433755644, 834.3783881008657, 870.4646642314995, 916.5234078279291, 919.8666193401987, 920.1534588178858, 922.6032805893464, 922.8338898998211, 914.2731528434877, 919.9834183227538, 921.4195057114042, 922.8292765838274, 923.829091618046, 528.0916823064323, 651.1079820645698, 671.92944740802, 676.1851968378093, 681.2540268406087, 15282.541523754306, 15324.463643772415, 15331.310091045929, 15353.309101090366, 15379.692406151926, 14106.576802695936, 14340.56978290653, 14610.780838287434, 14611.196384198865, 14826.25868706571, 13011.284379359131, 13237.801432978416, 13343.66640833987, 13450.596482105553, 13712.17726936377, 4538.137753826795, 4567.543245675439, 4582.518835522289, 4583.931241031387, 4819.6867203631655, 116072.83927160705, 117137.0, 118037.0, 119171.40414297959, 119986.0, 11446.198766086294, 11498.48256828428, 11573.338132229634, 11609.941950290195, 11956.641300760817, 23314.417139571648, 23408.765912340998, 23414.06343746231, 23415.0, 23444.179453719036, 7963.960180199062, 8095.2714255717265, 8110.060840404749, 8243.917560824435, 8345.41582089248, 4435.401220835211, 4552.339910712928, 4596.402467679247, 4624.768761561976, 4699.271612900006, 3572.4105522589, 3696.611855755107, 3699.9075023124637, 3700.9444858327147, 3703.7407381483035, 630.5586089737202, 645.1645119570074, 650.9414152726278, 655.2366493035655, 656.474820143883, 592.1473078766562, 621.8787336469345, 629.9244090709144, 641.8491654461197, 658.0524045374646, 9156.771080723036, 9278.396904201205, 9357.625694972261, 9453.149216570542, 9578.712638620853, 512.190738632959, 537.7176982084359, 588.5762251179144, 608.5091525761901, 619.6096459230656, 4418.734875907456, 4436.866893993185, 4658.906821863611, 4834.830780922651, 4898.338724272249, 114755.42622286835, 138432.0, 142081.0, 142345.0, 144392.0, 132839.0, 135357.3232133833, 138193.3090334542, 138927.0, 141698.0, 107183.0, 111082.99999999838, 114622.42688786503, 117386.0, 117693.82306176829, 9040.547972601213, 9150.862737058816, 9229.400088994194, 9249.30630202724, 9353.812923741623, 53841.46158538521, 60532.69733651303, 62646.6867665677, 63646.0, 64000.0, 85629.57185214035, 91741.0, 98898.02203955737, 101415.0, 101792.47311290485, 11877.406129693654, 12081.335526545772, 12222.144449888778, 12232.266064036186, 12365.567205147776, 13537.1200871943, 13599.65537900852, 13761.38073786702, 13839.861601383856, 14229.64425889361, 12612.7828898284, 12782.488700451877, 12856.55002074923, 12924.741505170032, 13046.152000119962, 8854.822903541764, 13617.591472255848, 13701.725965480435, 13932.651683707989, 14462.927685361507, 14032.859671403156, 14138.78791818103, 14199.438061813398, 14433.711325773216, 14799.92600036993, 10692.0, 11085.27945683528, 11302.547898083985, 11622.535098595961, 11726.296422214695, 11548.018418434527, 11618.797011146342, 11699.649010529698, 11715.355655439032, 12020.879791201976], "value": 7923.719873531707, "unit": "score"}], "name": "dromaeo_css", "value": 7486.840787507819, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 113.0, 108.0, 113.0, 111.0, 112.0, 112.0, 113.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 107.0, 168.0, 111.0, 109.0, 131.0, 107.0, 139.0, 104.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 183.0, 184.0, 184.0, 184.0, 181.0, 182.0, 191.0, 215.0], "value": 187.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 62.0, 92.0, 76.0, 80.0, 88.0, 65.0, 85.0, 56.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 68.0, 104.0, 71.0, 69.0, 67.0, 97.0, 71.0, 68.0, 103.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 92.0, 83.0, 87.0, 84.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 83.0, 84.0, 85.0, 85.0, 85.0, 86.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 80.0, 76.0, 74.0, 69.0, 68.0, 70.0, 70.0, 69.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 81.0, 86.0, 84.0, 73.0, 100.0, 75.0, 103.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 138.0, 133.0, 138.0, 148.0, 155.0, 139.0, 167.0, 165.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 170.0, 168.0, 169.0, 169.0, 166.0, 166.0, 167.0, 166.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 67.0, 66.0, 67.0, 67.0, 66.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1431.3999999999999, "alertThreshold": 2.0}]} 15:55:04 INFO - Return code: 0 15:55:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:55:05 INFO - # TBPL SUCCESS # 15:55:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:55:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:55:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:55:05 INFO - 2016-08-15 15:55:05.051 screenresolution[2338:73808] starting screenresolution argv=screenresolution get 15:55:05 INFO - 2016-08-15 15:55:05.054 screenresolution[2338:73808] Display 0: 1600x1200x32@60 15:55:05 INFO - 2016-08-15 15:55:05.060 screenresolution[2339:73811] starting screenresolution argv=screenresolution list 15:55:05 INFO - Available Modes on Display 0 15:55:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:55:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:55:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:55:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:55:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:55:05 INFO - Intel Iris: 15:55:05 INFO - Chipset Model: Intel Iris 15:55:05 INFO - Type: GPU 15:55:05 INFO - Bus: Built-In 15:55:05 INFO - VRAM (Dynamic, Max): 1536 MB 15:55:05 INFO - Vendor: Intel (0x8086) 15:55:05 INFO - Device ID: 0x0a2e 15:55:05 INFO - Revision ID: 0x0009 15:55:05 INFO - Displays: 15:55:05 INFO - Display: 15:55:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:55:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:55:05 INFO - Display Serial Number: 1600x1200 60 15:55:05 INFO - Main Display: Yes 15:55:05 INFO - Mirror: Off 15:55:05 INFO - Online: Yes 15:55:05 INFO - Rotation: Supported 15:55:05 INFO - Return code: 0 15:55:05 INFO - Running post-action listener: _resource_record_post_action 15:55:05 INFO - [mozharness: 2016-08-15 22:55:05.304546Z] Finished run-tests step (success) 15:55:05 INFO - Running post-run listener: _resource_record_post_run 15:55:05 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 16384; Write bytes: 703448576; Read time: 0; Write time: 1970 15:55:05 INFO - TinderboxPrint: CPU usage
53.5% 15:55:05 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 0 15:55:05 INFO - TinderboxPrint: I/O write bytes / time
703,448,576 / 1,970 15:55:05 INFO - TinderboxPrint: CPU idle
2,256.6 (46.5%) 15:55:05 INFO - TinderboxPrint: CPU system
969.9 (20.0%) 15:55:05 INFO - TinderboxPrint: CPU user
1,623.2 (33.5%) 15:55:05 INFO - TinderboxPrint: Swap in / out
404,180,992 / 0 15:55:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400181760; Write bytes: 395060736; Read time: 29557; Write time: 812 15:55:05 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 16384; Write bytes: 294350848; Read time: 0; Write time: 1114 15:55:05 INFO - Running post-run listener: _upload_blobber_files 15:55:05 INFO - Blob upload gear active. 15:55:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:55:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:55:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:55:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:55:06 INFO - (blobuploader) - INFO - Open directory for files ... 15:55:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:55:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:55:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:55:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:55:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:55:07 INFO - (blobuploader) - INFO - Done attempting. 15:55:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:55:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:55:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:55:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:55:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:55:08 INFO - (blobuploader) - INFO - Done attempting. 15:55:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:55:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:55:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:55:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:55:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:55:10 INFO - (blobuploader) - INFO - Done attempting. 15:55:10 INFO - (blobuploader) - INFO - Iteration through files over. 15:55:10 INFO - Return code: 0 15:55:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:55:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:55:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/50138b08d89cd705b61580e3e5b4573e25fb15417a0669d70c8c76a4f33314afb6fc707e23ebad79d209a26eec4b915061d95a6a4f2e645d48f8e60d9a7c012e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5a711290997cb1024fbc7fca267872dd2dd7b9759061f2ddde04ad88b5d680b8a4e3ff05753c9e5e3a4a5a9b7d2880c3c849965074b4ebd7fbb005f15d4b072", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ab45ba57f3e759ab0a3a8c5a7a459037effb922e27f472e4c121ad9370dad2511a8058c7fababcba627f92b3b096b112a705324dbd0f72d1fcde11e908a6e1b"} 15:55:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:55:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:55:10 INFO - Contents: 15:55:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/50138b08d89cd705b61580e3e5b4573e25fb15417a0669d70c8c76a4f33314afb6fc707e23ebad79d209a26eec4b915061d95a6a4f2e645d48f8e60d9a7c012e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5a711290997cb1024fbc7fca267872dd2dd7b9759061f2ddde04ad88b5d680b8a4e3ff05753c9e5e3a4a5a9b7d2880c3c849965074b4ebd7fbb005f15d4b072", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ab45ba57f3e759ab0a3a8c5a7a459037effb922e27f472e4c121ad9370dad2511a8058c7fababcba627f92b3b096b112a705324dbd0f72d1fcde11e908a6e1b"} 15:55:10 INFO - Running post-run listener: copy_logs_to_upload_dir 15:55:10 INFO - Copying logs to upload dir... 15:55:10 INFO - mkdir: /builds/slave/test/build/upload/logs 15:55:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.055168 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-15 15:55:10.575352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 15:55:10.579103) ========= 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.jU41PqdMkq/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.2MGnIM2cax/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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/50138b08d89cd705b61580e3e5b4573e25fb15417a0669d70c8c76a4f33314afb6fc707e23ebad79d209a26eec4b915061d95a6a4f2e645d48f8e60d9a7c012e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5a711290997cb1024fbc7fca267872dd2dd7b9759061f2ddde04ad88b5d680b8a4e3ff05753c9e5e3a4a5a9b7d2880c3c849965074b4ebd7fbb005f15d4b072", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ab45ba57f3e759ab0a3a8c5a7a459037effb922e27f472e4c121ad9370dad2511a8058c7fababcba627f92b3b096b112a705324dbd0f72d1fcde11e908a6e1b"} build_url:https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/ITWmU3Y2S3a6O1pnnfpXvg/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/50138b08d89cd705b61580e3e5b4573e25fb15417a0669d70c8c76a4f33314afb6fc707e23ebad79d209a26eec4b915061d95a6a4f2e645d48f8e60d9a7c012e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5a711290997cb1024fbc7fca267872dd2dd7b9759061f2ddde04ad88b5d680b8a4e3ff05753c9e5e3a4a5a9b7d2880c3c849965074b4ebd7fbb005f15d4b072", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ab45ba57f3e759ab0a3a8c5a7a459037effb922e27f472e4c121ad9370dad2511a8058c7fababcba627f92b3b096b112a705324dbd0f72d1fcde11e908a6e1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 15:55:10.612406) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 15:55:10.612781) ========= 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.jU41PqdMkq/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.2MGnIM2cax/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.005183 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 15:55:10.661497) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 15:55:10.661824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 15:55:10.662176) ========= ========= Total master_lag: 0.18 =========