builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0357 starttime: 1470424010.18 results: success (0) buildid: 20160805104421 builduid: be27fefe9b63440f8b43d7b6d014e796 revision: cf64f3ec680ba27c5d158ccbbda6763dbe488634 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.177618) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.178221) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.178540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.204659) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.205077) ========= 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.CjQXEjqxkB/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.q8rPsVY07R/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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-05 12:06:50-- 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% 214M=0s 2016-08-05 12:06:50 (214 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105208 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.333275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.333688) ========= 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.CjQXEjqxkB/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.q8rPsVY07R/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.030261 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:50.386248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-05 12:06:50.386667) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cf64f3ec680ba27c5d158ccbbda6763dbe488634 --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 cf64f3ec680ba27c5d158ccbbda6763dbe488634 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CjQXEjqxkB/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.q8rPsVY07R/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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-05 12:06:50,469 truncating revision to first 12 chars 2016-08-05 12:06:50,469 Setting DEBUG logging. 2016-08-05 12:06:50,470 attempt 1/10 2016-08-05 12:06:50,470 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cf64f3ec680b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-05 12:06:51,517 unpacking tar archive at: autoland-cf64f3ec680b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.302731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-05 12:06:51.713243) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:51.713775) ========= script_repo_revision: cf64f3ec680ba27c5d158ccbbda6763dbe488634 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:51.714387) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:51.714837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 12:06:51.734428) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-08-05 12:06:51.735034) ========= /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.CjQXEjqxkB/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.q8rPsVY07R/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:06:51 INFO - MultiFileLogger online at 20160805 12:06:51 in /builds/slave/test 12:06:51 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 12:06:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:06:51 INFO - {'append_to_log': False, 12:06:51 INFO - 'base_work_dir': '/builds/slave/test', 12:06:51 INFO - 'blob_upload_branch': 'Autoland', 12:06:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:06:51 INFO - 'branch': 'Autoland', 12:06:51 INFO - 'buildbot_json_path': 'buildprops.json', 12:06:51 INFO - 'config_files': ('talos/mac_config.py',), 12:06:51 INFO - 'default_actions': ('clobber', 12:06:51 INFO - 'read-buildbot-config', 12:06:51 INFO - 'download-and-extract', 12:06:51 INFO - 'populate-webroot', 12:06:51 INFO - 'create-virtualenv', 12:06:51 INFO - 'install', 12:06:51 INFO - 'run-tests'), 12:06:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:06:51 INFO - 'download_minidump_stackwalk': True, 12:06:51 INFO - 'download_symbols': 'ondemand', 12:06:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:06:51 INFO - 'tooltool.py': '/tools/tooltool.py', 12:06:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:06:51 INFO - '/tools/misc-python/virtualenv.py')}, 12:06:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:06:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:06:51 INFO - 'installer_path': 'installer.exe', 12:06:51 INFO - 'log_level': 'info', 12:06:51 INFO - 'log_name': 'talos', 12:06:51 INFO - 'log_to_console': True, 12:06:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:06:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:06:51 INFO - 'opt_config_files': (), 12:06:51 INFO - 'pip_index': False, 12:06:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:06:51 INFO - 'cmd': ('bash', 12:06:51 INFO - '-c', 12:06:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:06:51 INFO - 'enabled': True, 12:06:51 INFO - 'halt_on_failure': False, 12:06:51 INFO - 'name': 'check_screen_resolution'},), 12:06:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:06:51 INFO - 'cmd': ('bash', 12:06:51 INFO - '-c', 12:06:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:06:51 INFO - 'enabled': True, 12:06:51 INFO - 'halt_on_failure': False, 12:06:51 INFO - 'name': 'check_screen_resolution'},), 12:06:51 INFO - 'run_cmd_checks_enabled': True, 12:06:51 INFO - 'sps_profile': False, 12:06:51 INFO - 'sps_profile_interval': 0, 12:06:51 INFO - 'suite': 'dromaeojs', 12:06:51 INFO - 'system_bits': '32', 12:06:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:06:51 INFO - 'title': 't-yosemite-r7-0357', 12:06:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:06:51 INFO - 'use_talos_json': True, 12:06:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:06:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:06:51 INFO - 'work_dir': 'build'} 12:06:51 INFO - [mozharness: 2016-08-05 19:06:51.931983Z] Running clobber step. 12:06:51 INFO - Running pre-action listener: _resource_record_pre_action 12:06:51 INFO - Running main action method: clobber 12:06:51 INFO - rmtree: /builds/slave/test/build 12:06:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:06:53 INFO - Running post-action listener: _resource_record_post_action 12:06:53 INFO - [mozharness: 2016-08-05 19:06:53.871073Z] Finished clobber step (success) 12:06:53 INFO - [mozharness: 2016-08-05 19:06:53.871214Z] Running read-buildbot-config step. 12:06:53 INFO - Running pre-action listener: _resource_record_pre_action 12:06:53 INFO - Running main action method: read_buildbot_config 12:06:53 INFO - Using buildbot properties: 12:06:53 INFO - { 12:06:53 INFO - "project": "", 12:06:53 INFO - "product": "firefox", 12:06:53 INFO - "script_repo_revision": "production", 12:06:53 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 12:06:53 INFO - "repository": "", 12:06:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 12:06:53 INFO - "buildid": "20160805104421", 12:06:53 INFO - "pgo_build": "False", 12:06:53 INFO - "basedir": "/builds/slave/test", 12:06:53 INFO - "buildnumber": 148, 12:06:53 INFO - "slavename": "t-yosemite-r7-0357", 12:06:53 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:06:53 INFO - "platform": "yosemite_r7", 12:06:53 INFO - "scheduler": "tests-autoland-macosx64-talos", 12:06:53 INFO - "branch": "autoland", 12:06:53 INFO - "repo_path": "integration/autoland", 12:06:53 INFO - "revision": "cf64f3ec680ba27c5d158ccbbda6763dbe488634", 12:06:53 INFO - "stage_platform": "macosx64", 12:06:53 INFO - "builduid": "be27fefe9b63440f8b43d7b6d014e796", 12:06:53 INFO - "slavebuilddir": "test" 12:06:53 INFO - } 12:06:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:06:53 INFO - Running post-action listener: _resource_record_post_action 12:06:53 INFO - [mozharness: 2016-08-05 19:06:53.874603Z] Finished read-buildbot-config step (success) 12:06:53 INFO - [mozharness: 2016-08-05 19:06:53.874731Z] Running download-and-extract step. 12:06:53 INFO - Running pre-action listener: _resource_record_pre_action 12:06:53 INFO - Running main action method: download_and_extract 12:06:53 INFO - mkdir: /builds/slave/test/build/tests 12:06:53 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')]} 12:06:53 INFO - trying https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:06:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:06:54 INFO - Downloaded 1199 bytes. 12:06:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:06:54 INFO - Using the following test package requirements: 12:06:54 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:06:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:06:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:06:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'jsshell-mac.zip'], 12:06:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:06:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:06:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:06:54 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:06:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:06:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:06:54 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:06:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:54 INFO - trying https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:06:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:06:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:06:56 INFO - Downloaded 16320640 bytes. 12:06:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:07:03 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 12:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:03 INFO - trying https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:07:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:07:04 INFO - Downloaded 16320640 bytes. 12:07:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:07:10 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')]} 12:07:10 INFO - trying https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:07:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:07:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:07:12 INFO - Downloaded 14413723 bytes. 12:07:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 12:07:12 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')]} 12:07:12 INFO - trying https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:07:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:07:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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 12:07:19 INFO - Downloaded 111436580 bytes. 12:07:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:07:19 INFO - mkdir: /builds/slave/test/properties 12:07:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:19 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:19 INFO - Contents: 12:07:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:07:20 INFO - Running post-action listener: _resource_record_post_action 12:07:20 INFO - Running post-action listener: set_extra_try_arguments 12:07:20 INFO - [mozharness: 2016-08-05 19:07:20.351355Z] Finished download-and-extract step (success) 12:07:20 INFO - [mozharness: 2016-08-05 19:07:20.351505Z] Running populate-webroot step. 12:07:20 INFO - Running pre-action listener: _resource_record_pre_action 12:07:20 INFO - Running main action method: populate_webroot 12:07:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:07:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:07:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:07:20 INFO - '--tppagecycles', 12:07:20 INFO - '7'], 12:07:20 INFO - 'tests': ['tsvgm']}}, 12:07:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:07:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:07:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['damp', 'tps']}, 12:07:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'tests': ['damp', 'tps']}, 12:07:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['dromaeo_dom']}, 12:07:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['basic_compositor_video']}, 12:07:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:07:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['a11yr', 12:07:20 INFO - 'ts_paint', 12:07:20 INFO - 'tpaint', 12:07:20 INFO - 'sessionrestore', 12:07:20 INFO - 'sessionrestore_no_auto_restore', 12:07:20 INFO - 'tabpaint']}, 12:07:20 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:20 INFO - 'ts_paint', 12:07:20 INFO - 'tpaint', 12:07:20 INFO - 'sessionrestore', 12:07:20 INFO - 'sessionrestore_no_auto_restore', 12:07:20 INFO - 'tabpaint']}, 12:07:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:07:20 INFO - 'ts_paint', 12:07:20 INFO - 'tpaint', 12:07:20 INFO - 'sessionrestore', 12:07:20 INFO - 'sessionrestore_no_auto_restore', 12:07:20 INFO - 'tabpaint']}, 12:07:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:07:20 INFO - 'ts_paint', 12:07:20 INFO - 'tpaint', 12:07:20 INFO - 'sessionrestore', 12:07:20 INFO - 'sessionrestore_no_auto_restore', 12:07:20 INFO - 'tabpaint']}, 12:07:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['a11yr', 12:07:20 INFO - 'ts_paint', 12:07:20 INFO - 'tpaint', 12:07:20 INFO - 'sessionrestore', 12:07:20 INFO - 'sessionrestore_no_auto_restore', 12:07:20 INFO - 'tabpaint']}, 12:07:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['a11yr', 12:07:20 INFO - 'ts_paint', 12:07:20 INFO - 'tpaint', 12:07:20 INFO - 'sessionrestore', 12:07:20 INFO - 'sessionrestore_no_auto_restore', 12:07:20 INFO - 'tabpaint']}, 12:07:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['tsvgx', 12:07:20 INFO - 'tsvgr_opacity', 12:07:20 INFO - 'tart', 12:07:20 INFO - 'tscrollx', 12:07:20 INFO - 'cart']}, 12:07:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:20 INFO - 'tsvgr_opacity', 12:07:20 INFO - 'tart', 12:07:20 INFO - 'tscrollx', 12:07:20 INFO - 'cart']}, 12:07:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'talos_options': ['--disable-e10s'], 12:07:20 INFO - 'tests': ['tp5o']}, 12:07:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'tests': ['tp5o']}, 12:07:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'talos_options': ['--disable-e10s', 12:07:20 INFO - '--xperf_path', 12:07:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:20 INFO - 'tests': ['tp5n']}, 12:07:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:20 INFO - 'talos_options': ['--xperf_path', 12:07:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:20 INFO - 'tests': ['tp5n']}}, 12:07:20 INFO - 'talos.zip': {'path': '', 12:07:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:07:20 INFO - Running post-action listener: _resource_record_post_action 12:07:20 INFO - [mozharness: 2016-08-05 19:07:20.370520Z] Finished populate-webroot step (success) 12:07:20 INFO - [mozharness: 2016-08-05 19:07:20.370654Z] Running create-virtualenv step. 12:07:20 INFO - Running pre-action listener: _resource_record_pre_action 12:07:20 INFO - Running main action method: create_virtualenv 12:07:20 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:20 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 12:07:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:07:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:20 INFO - Using real prefix '/tools/python27' 12:07:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:21 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:24 INFO - Installing pip.................done. 12:07:24 INFO - Return code: 0 12:07:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07:24 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')]} 12:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:24 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')]} 12:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:24 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:24 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 12:07:24 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 12:07:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:24 INFO - 'HOME': '/Users/cltbld', 12:07:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:24 INFO - 'LOGNAME': 'cltbld', 12:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:24 INFO - 'MOZ_NO_REMOTE': '1', 12:07:24 INFO - 'NO_EM_RESTART': '1', 12:07:24 INFO - 'PAGER': '/bin/cat', 12:07:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:24 INFO - 'PWD': '/builds/slave/test', 12:07:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:24 INFO - 'SHELL': '/bin/bash', 12:07:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:24 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:24 INFO - 'USER': 'cltbld', 12:07:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:24 INFO - 'XPC_FLAGS': '0x0', 12:07:24 INFO - 'XPC_SERVICE_NAME': '0', 12:07:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:24 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 12:07:24 INFO - Cleaning up... 12:07:24 INFO - Return code: 0 12:07:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:24 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')]} 12:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:24 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')]} 12:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:24 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:24 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 12:07:24 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 12:07:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:24 INFO - 'HOME': '/Users/cltbld', 12:07:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:24 INFO - 'LOGNAME': 'cltbld', 12:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:24 INFO - 'MOZ_NO_REMOTE': '1', 12:07:24 INFO - 'NO_EM_RESTART': '1', 12:07:24 INFO - 'PAGER': '/bin/cat', 12:07:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:24 INFO - 'PWD': '/builds/slave/test', 12:07:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:24 INFO - 'SHELL': '/bin/bash', 12:07:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:24 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:24 INFO - 'USER': 'cltbld', 12:07:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:24 INFO - 'XPC_FLAGS': '0x0', 12:07:24 INFO - 'XPC_SERVICE_NAME': '0', 12:07:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:24 INFO - Downloading/unpacking psutil>=3.1.1 12:07:24 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 12:07:24 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 12:07:24 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 12:07:24 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 12:07:24 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 12:07:24 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 12:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:27 INFO - Installing collected packages: psutil 12:07:27 INFO - Running setup.py install for psutil 12:07:27 INFO - building 'psutil._psutil_osx' extension 12:07:27 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 12:07:28 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 12:07:28 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 12:07:28 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 12:07:28 INFO - building 'psutil._psutil_posix' extension 12:07:28 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 12:07:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:07:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:07:28 INFO - ^ 12:07:28 INFO - 1 warning generated. 12:07:28 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 12:07:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:28 INFO - Successfully installed psutil 12:07:28 INFO - Cleaning up... 12:07:28 INFO - Return code: 0 12:07:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:28 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:28 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 12:07:28 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 12:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:28 INFO - 'HOME': '/Users/cltbld', 12:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:28 INFO - 'LOGNAME': 'cltbld', 12:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:28 INFO - 'MOZ_NO_REMOTE': '1', 12:07:28 INFO - 'NO_EM_RESTART': '1', 12:07:28 INFO - 'PAGER': '/bin/cat', 12:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:28 INFO - 'PWD': '/builds/slave/test', 12:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:28 INFO - 'SHELL': '/bin/bash', 12:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:28 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:28 INFO - 'USER': 'cltbld', 12:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:28 INFO - 'XPC_FLAGS': '0x0', 12:07:28 INFO - 'XPC_SERVICE_NAME': '0', 12:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07:28 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 12:07:28 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 12:07:28 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 12:07:28 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 12:07:28 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 12:07:28 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 12:07:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:31 INFO - Installing collected packages: mozsystemmonitor 12:07:31 INFO - Running setup.py install for mozsystemmonitor 12:07:31 INFO - Successfully installed mozsystemmonitor 12:07:31 INFO - Cleaning up... 12:07:31 INFO - Return code: 0 12:07:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:31 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')]} 12:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:31 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')]} 12:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:31 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:31 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 12:07:31 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 12:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:31 INFO - 'HOME': '/Users/cltbld', 12:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:31 INFO - 'LOGNAME': 'cltbld', 12:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:31 INFO - 'MOZ_NO_REMOTE': '1', 12:07:31 INFO - 'NO_EM_RESTART': '1', 12:07:31 INFO - 'PAGER': '/bin/cat', 12:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:31 INFO - 'PWD': '/builds/slave/test', 12:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:31 INFO - 'SHELL': '/bin/bash', 12:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:31 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:31 INFO - 'USER': 'cltbld', 12:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:31 INFO - 'XPC_FLAGS': '0x0', 12:07:31 INFO - 'XPC_SERVICE_NAME': '0', 12:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:31 INFO - Downloading/unpacking jsonschema==2.5.1 12:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:07:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:34 INFO - Downloading functools32-3.2.3-2.tar.gz 12:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:07:34 INFO - warning: no files found matching '*.txt' 12:07:34 INFO - no previously-included directories found matching 'build' 12:07:34 INFO - no previously-included directories found matching 'dist' 12:07:34 INFO - no previously-included directories found matching '.git*' 12:07:34 INFO - Installing collected packages: jsonschema, functools32 12:07:34 INFO - Running setup.py install for functools32 12:07:35 INFO - warning: no files found matching '*.txt' 12:07:35 INFO - no previously-included directories found matching 'build' 12:07:35 INFO - no previously-included directories found matching 'dist' 12:07:35 INFO - no previously-included directories found matching '.git*' 12:07:35 INFO - Successfully installed jsonschema functools32 12:07:35 INFO - Cleaning up... 12:07:35 INFO - Return code: 0 12:07:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:07:35 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')]} 12:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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')]} 12:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:35 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 12:07:35 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 12:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:35 INFO - 'HOME': '/Users/cltbld', 12:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:35 INFO - 'LOGNAME': 'cltbld', 12:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:35 INFO - 'MOZ_NO_REMOTE': '1', 12:07:35 INFO - 'NO_EM_RESTART': '1', 12:07:35 INFO - 'PAGER': '/bin/cat', 12:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:35 INFO - 'PWD': '/builds/slave/test', 12:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:35 INFO - 'SHELL': '/bin/bash', 12:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:35 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:35 INFO - 'USER': 'cltbld', 12:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:35 INFO - 'XPC_FLAGS': '0x0', 12:07:35 INFO - 'XPC_SERVICE_NAME': '0', 12:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:07:35 INFO - Cleaning up... 12:07:35 INFO - Return code: 0 12:07:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:07:35 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')]} 12:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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')]} 12:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:35 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 12:07:35 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 12:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:35 INFO - 'HOME': '/Users/cltbld', 12:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:35 INFO - 'LOGNAME': 'cltbld', 12:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:35 INFO - 'MOZ_NO_REMOTE': '1', 12:07:35 INFO - 'NO_EM_RESTART': '1', 12:07:35 INFO - 'PAGER': '/bin/cat', 12:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:35 INFO - 'PWD': '/builds/slave/test', 12:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:35 INFO - 'SHELL': '/bin/bash', 12:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:35 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:35 INFO - 'USER': 'cltbld', 12:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:35 INFO - 'XPC_FLAGS': '0x0', 12:07:35 INFO - 'XPC_SERVICE_NAME': '0', 12:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:35 INFO - Downloading/unpacking blobuploader==1.2.4 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:38 INFO - Downloading blobuploader-1.2.4.tar.gz 12:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:07:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:07:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:39 INFO - Downloading docopt-0.6.1.tar.gz 12:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:07:39 INFO - Installing collected packages: blobuploader, requests, docopt 12:07:39 INFO - Running setup.py install for blobuploader 12:07:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:07:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:07:39 INFO - Running setup.py install for requests 12:07:40 INFO - Running setup.py install for docopt 12:07:40 INFO - Successfully installed blobuploader requests docopt 12:07:40 INFO - Cleaning up... 12:07:40 INFO - Return code: 0 12:07:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:40 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')]} 12:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:40 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')]} 12:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:40 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:40 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 12:07:40 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 12:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:40 INFO - 'HOME': '/Users/cltbld', 12:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:40 INFO - 'LOGNAME': 'cltbld', 12:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:40 INFO - 'MOZ_NO_REMOTE': '1', 12:07:40 INFO - 'NO_EM_RESTART': '1', 12:07:40 INFO - 'PAGER': '/bin/cat', 12:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:40 INFO - 'PWD': '/builds/slave/test', 12:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:40 INFO - 'SHELL': '/bin/bash', 12:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:40 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:40 INFO - 'USER': 'cltbld', 12:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:40 INFO - 'XPC_FLAGS': '0x0', 12:07:40 INFO - 'XPC_SERVICE_NAME': '0', 12:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:40 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-AGTYg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:40 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-CjUjKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:40 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-DK2kIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:40 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-s4AiJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-LVsa9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-T4arwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-meZCjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Yfa5QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-ua5yIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-O8zCHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-rZqpIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-T255QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-yWHDjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:41 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-asDH2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:42 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-pCjCpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:42 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Yg8vMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:42 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-9kKofs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:07:42 INFO - Running setup.py install for manifestparser 12:07:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:07:42 INFO - Running setup.py install for mozcrash 12:07:42 INFO - Running setup.py install for mozdebug 12:07:42 INFO - Running setup.py install for mozdevice 12:07:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:07:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:07:42 INFO - Running setup.py install for mozfile 12:07:43 INFO - Running setup.py install for mozhttpd 12:07:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozinfo 12:07:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozInstall 12:07:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozleak 12:07:43 INFO - Running setup.py install for mozlog 12:07:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for moznetwork 12:07:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozprocess 12:07:44 INFO - Running setup.py install for mozprofile 12:07:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for mozrunner 12:07:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for mozscreenshot 12:07:44 INFO - Running setup.py install for moztest 12:07:44 INFO - Running setup.py install for mozversion 12:07:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:07:44 INFO - Cleaning up... 12:07:44 INFO - Return code: 0 12:07:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:44 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:44 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 12:07:44 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 12:07:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:44 INFO - 'HOME': '/Users/cltbld', 12:07:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:44 INFO - 'LOGNAME': 'cltbld', 12:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:44 INFO - 'MOZ_NO_REMOTE': '1', 12:07:44 INFO - 'NO_EM_RESTART': '1', 12:07:44 INFO - 'PAGER': '/bin/cat', 12:07:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:44 INFO - 'PWD': '/builds/slave/test', 12:07:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:44 INFO - 'SHELL': '/bin/bash', 12:07:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:44 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:44 INFO - 'USER': 'cltbld', 12:07:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:44 INFO - 'XPC_FLAGS': '0x0', 12:07:44 INFO - 'XPC_SERVICE_NAME': '0', 12:07:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-YXUlRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-EylM7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-la8Dur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-KnCt1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-On9q_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-AcKW3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-7GuQca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:45 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-9IQmMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-s7xohx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-StDLBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-wu_fOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-EoAApX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-2LAjx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Cvv_k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-BgYX28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-rdV7sI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:46 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-SJeHT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:46 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)) 12:07:46 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)) 12:07:46 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)) 12:07:46 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)) 12:07:46 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)) 12:07:46 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)) 12:07:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:07:46 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 12:07:46 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 12:07:46 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 12:07:46 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 12:07:46 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 12:07:46 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 12:07:49 INFO - Downloading blessings-1.6.tar.gz 12:07:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:07:49 INFO - Installing collected packages: blessings 12:07:49 INFO - Running setup.py install for blessings 12:07:50 INFO - Successfully installed blessings 12:07:50 INFO - Cleaning up... 12:07:50 INFO - Return code: 0 12:07:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:07:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:50 INFO - Reading from file tmpfile_stdout 12:07:50 INFO - Current package versions: 12:07:50 INFO - blessings == 1.6 12:07:50 INFO - blobuploader == 1.2.4 12:07:50 INFO - docopt == 0.6.1 12:07:50 INFO - functools32 == 3.2.3-2 12:07:50 INFO - jsonschema == 2.5.1 12:07:50 INFO - manifestparser == 1.1 12:07:50 INFO - mozInstall == 1.12 12:07:50 INFO - mozcrash == 0.17 12:07:50 INFO - mozdebug == 0.1 12:07:50 INFO - mozdevice == 0.48 12:07:50 INFO - mozfile == 1.2 12:07:50 INFO - mozhttpd == 0.7 12:07:50 INFO - mozinfo == 0.9 12:07:50 INFO - mozleak == 0.1 12:07:50 INFO - mozlog == 3.3 12:07:50 INFO - moznetwork == 0.27 12:07:50 INFO - mozprocess == 0.23 12:07:50 INFO - mozprofile == 0.28 12:07:50 INFO - mozrunner == 6.12 12:07:50 INFO - mozscreenshot == 0.1 12:07:50 INFO - mozsystemmonitor == 0.3 12:07:50 INFO - moztest == 0.7 12:07:50 INFO - mozversion == 1.4 12:07:50 INFO - psutil == 3.1.1 12:07:50 INFO - requests == 1.2.3 12:07:50 INFO - wsgiref == 0.1.2 12:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:50 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 12:07:50 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 12:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:50 INFO - 'HOME': '/Users/cltbld', 12:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:50 INFO - 'LOGNAME': 'cltbld', 12:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:50 INFO - 'MOZ_NO_REMOTE': '1', 12:07:50 INFO - 'NO_EM_RESTART': '1', 12:07:50 INFO - 'PAGER': '/bin/cat', 12:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:50 INFO - 'PWD': '/builds/slave/test', 12:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:50 INFO - 'SHELL': '/bin/bash', 12:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:50 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:50 INFO - 'USER': 'cltbld', 12:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:50 INFO - 'XPC_FLAGS': '0x0', 12:07:50 INFO - 'XPC_SERVICE_NAME': '0', 12:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 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 0x101851a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa351c6e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, '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.CjQXEjqxkB/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.q8rPsVY07R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 12:07:51 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 12:07:51 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 12:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:51 INFO - 'HOME': '/Users/cltbld', 12:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:51 INFO - 'LOGNAME': 'cltbld', 12:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:51 INFO - 'MOZ_NO_REMOTE': '1', 12:07:51 INFO - 'NO_EM_RESTART': '1', 12:07:51 INFO - 'PAGER': '/bin/cat', 12:07:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:51 INFO - 'PWD': '/builds/slave/test', 12:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:51 INFO - 'SHELL': '/bin/bash', 12:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:07:51 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:51 INFO - 'USER': 'cltbld', 12:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:51 INFO - 'XPC_FLAGS': '0x0', 12:07:51 INFO - 'XPC_SERVICE_NAME': '0', 12:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Running post-action listener: _resource_record_post_action 12:07:51 INFO - Running post-action listener: _start_resource_monitoring 12:07:51 INFO - Starting resource monitoring. 12:07:51 INFO - [mozharness: 2016-08-05 19:07:51.466003Z] Finished create-virtualenv step (success) 12:07:51 INFO - [mozharness: 2016-08-05 19:07:51.466919Z] Running install step. 12:07:51 INFO - Running pre-action listener: _resource_record_pre_action 12:07:51 INFO - Running main action method: install 12:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:51 INFO - Reading from file tmpfile_stdout 12:07:51 INFO - Detecting whether we're running mozinstall >=1.0... 12:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:07:51 INFO - Reading from file tmpfile_stdout 12:07:51 INFO - Output received: 12:07:51 INFO - Usage: mozinstall [options] installer 12:07:51 INFO - Options: 12:07:51 INFO - -h, --help show this help message and exit 12:07:51 INFO - -d DEST, --destination=DEST 12:07:51 INFO - Directory to install application into. [default: 12:07:51 INFO - "/builds/slave/test"] 12:07:51 INFO - --app=APP Application being installed. [default: firefox] 12:07:51 INFO - mkdir: /builds/slave/test/build/application 12:07:51 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'] 12:07:51 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 12:08:26 INFO - Reading from file tmpfile_stdout 12:08:26 INFO - Output received: 12:08:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:08:26 INFO - Running post-action listener: _resource_record_post_action 12:08:26 INFO - [mozharness: 2016-08-05 19:08:26.406220Z] Finished install step (success) 12:08:26 INFO - [mozharness: 2016-08-05 19:08:26.406338Z] Running run-tests step. 12:08:26 INFO - Running pre-action listener: _resource_record_pre_action 12:08:26 INFO - Running main action method: run_tests 12:08:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:08:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:08:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:08:26 INFO - 2016-08-05 12:08:26.420 screenresolution[1657:7756] starting screenresolution argv=screenresolution get 12:08:26 INFO - 2016-08-05 12:08:26.423 screenresolution[1657:7756] Display 0: 1600x1200x32@60 12:08:26 INFO - 2016-08-05 12:08:26.429 screenresolution[1658:7758] starting screenresolution argv=screenresolution list 12:08:26 INFO - Available Modes on Display 0 12:08:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:08:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:08:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:08:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:08:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:08:26 INFO - Intel Iris: 12:08:26 INFO - Chipset Model: Intel Iris 12:08:26 INFO - Type: GPU 12:08:26 INFO - Bus: Built-In 12:08:26 INFO - VRAM (Dynamic, Max): 1536 MB 12:08:26 INFO - Vendor: Intel (0x8086) 12:08:26 INFO - Device ID: 0x0a2e 12:08:26 INFO - Revision ID: 0x0009 12:08:26 INFO - Displays: 12:08:26 INFO - Display: 12:08:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:08:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:08:26 INFO - Display Serial Number: 1600x1200 60 12:08:26 INFO - Main Display: Yes 12:08:26 INFO - Mirror: Off 12:08:26 INFO - Online: Yes 12:08:26 INFO - Rotation: Supported 12:08:26 INFO - Return code: 0 12:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:08:26 INFO - Python 2.7.3 12:08:26 INFO - Return code: 0 12:08:26 INFO - grabbing minidump binary from tooltool 12:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:26 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 0x1017b5d30>, '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 0x10151adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017adb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:08:26 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 12:08:26 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 12:08:26 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 12:08:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:08:26 INFO - Return code: 0 12:08:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:08:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:08:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:08:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:08:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:08:26 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/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--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 12:08:26 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/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0357 --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 12:08:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CjQXEjqxkB/Render', 12:08:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:26 INFO - 'HOME': '/Users/cltbld', 12:08:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:26 INFO - 'LOGNAME': 'cltbld', 12:08:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:26 INFO - 'MOZ_NO_REMOTE': '1', 12:08:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:26 INFO - 'NO_EM_RESTART': '1', 12:08:26 INFO - 'PAGER': '/bin/cat', 12:08:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:26 INFO - 'PWD': '/builds/slave/test', 12:08:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:26 INFO - 'SHELL': '/bin/bash', 12:08:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8rPsVY07R/Listeners', 12:08:26 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 12:08:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:26 INFO - 'USER': 'cltbld', 12:08:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:26 INFO - 'XPC_FLAGS': '0x0', 12:08:26 INFO - 'XPC_SERVICE_NAME': '0', 12:08:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:26 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/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--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 12:08:27 INFO - mozversion application_buildid: 20160805104421 12:08:27 INFO - mozversion application_changeset: cf64f3ec680ba27c5d158ccbbda6763dbe488634 12:08:27 INFO - mozversion application_display_name: Nightly 12:08:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:08:27 INFO - mozversion application_name: Firefox 12:08:27 INFO - mozversion application_remotingname: firefox 12:08:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:08:27 INFO - mozversion application_vendor: Mozilla 12:08:27 INFO - mozversion application_version: 51.0a1 12:08:27 INFO - mozversion platform_buildid: 20160805104421 12:08:27 INFO - mozversion platform_changeset: cf64f3ec680ba27c5d158ccbbda6763dbe488634 12:08:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:08:27 INFO - mozversion platform_version: 51.0a1 12:08:27 INFO - using testdate: 1470424107 12:08:27 INFO - actual date: 1470424107 12:08:27 INFO - starting webserver on 'localhost:49271' 12:08:27 INFO - SUITE-START | Running 2 tests 12:08:27 INFO - TEST-START | dromaeo_css 12:08:27 INFO - operating with platform_type : mac_ 12:08:27 INFO - Initialising browser for dromaeo_css test... 12:08:27 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpnqWW7R/profile http://localhost:49271/getInfo.html) 12:08:29 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 12:08:29 INFO - PROCESS | 1668 | colorDepth:24 12:08:29 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 12:08:29 INFO - PROCESS | 1668 | __metrics 12:08:29 INFO - PROCESS | 1668 | 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" 12:08:29 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:08:30 INFO - TEST-INFO | 1668: exit 0 12:08:30 INFO - Browser initialized. 12:08:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:08:30 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpnqWW7R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:08:42 INFO - PROCESS | 1670 | RSS: Main: 243322880 12:08:42 INFO - PROCESS | 1670 | 12:09:33 INFO - PROCESS | 1670 | 1470424173802 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] 12:11:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 12:11:47 INFO - PROCESS | 1670 | RSS: Main: 360849408 12:11:47 INFO - PROCESS | 1670 | 12:14:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 12:14:50 INFO - PROCESS | 1670 | RSS: Main: 387870720 12:14:50 INFO - PROCESS | 1670 | 12:17:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 12:17:53 INFO - PROCESS | 1670 | RSS: Main: 359682048 12:17:53 INFO - PROCESS | 1670 | 12:20:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 12:20:57 INFO - PROCESS | 1670 | RSS: Main: 369238016 12:20:57 INFO - PROCESS | 1670 | 12:24:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 12:24:01 INFO - PROCESS | 1670 | RSS: Main: 340783104 12:24:01 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 12:27:04 INFO - PROCESS | 1670 | RSS: Main: 613875712 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | __start_tp_report 12:27:04 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 12:27:04 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 12:27:04 INFO - PROCESS | 1670 | |i|pagename|runs| 12:27:04 INFO - PROCESS | 1670 | |0;dojo.html;3364.7981121132734;3375.848086836092;3379.442392005319;3403.285310084882;3435.6220815710285;5591.888162236753;5625.156226566012;5722.54219662427;5763.682997435145;5791.507721843643;5680.091185410335;5703.857403564913;5768.048272035119;5802.854928626786;5843.532517398608;5142.10013247682;5158.200478925773;5217.243499692542;5233.345831771028;5324.76038578263;5311.442298558653;5319.0957537218765;5322.494363035506;5323.1749078892835;5368.194770784394;5129.512696293846;5129.717865517395;5130.769115389817;5203.739813009353;5248.291480650101;5185.611079169077;5196.090684130262;5205.583553315726;5206;5209.583233341324;5089.6691715038405;5157.149070403379;5157.226416037587;5159.071367153911;5192.948070519322;5617.887642247132;5619.80330688424;5700.914486282709;5722.599418040739;5790.478856902857;6149.108379285023;6199.628022318675;6204.069389591552;6280.371962803729;6285.182926219608;6196.132541444202;6318.589291696025;6336.334684858116;6338.207724034496;6353.30113687494;5677.290338707661;5741.799037033684;5742.799002034993;5821.2141360916585;5850.268716410449;6189.164462797465;6236.407541283571;6249.437550620375;6252.562320637512;6255.937440625627;5469.823987842633;6073.453389194994;6172.969135154385;6206.906896396557;6218.440340369389;6096.345267793883;6175.320714721377;6194.256689197326;6285.779997700059;6303.023031430135;6205.22434695663;6254.718537665816;6271.96864015677;6273.843153921097;6285.057241413733;6278.30938596754;6289.308176100625;6290.4653104485715;6310.305866354697;6314.052892066226;4833.347498087714;4861.416630004422;4869.878253043702;4929.088118698052;4958.107540642647;4391.253486907233;4403.9559604404185;4410.1400226956075;4426.424564806554;4466.798994045275;5682.772689092472;5683.460071293221;5687.886242275132;5706.885862282814;5745.1669507921115;6149.538784591174;6206.844828879224;6303.180586523723;6309.053641953652;6314.368563143649;5719.628224165416;5734.7132643368495;5760.308762948473;5782.566307526952;5784.595078344475;5424.647397919122;5435.755391007414;5442.673439593637;5444.101723215705;5455.481729235717;6184.288806787277;6198.876022479435;6290.685465726787;6292.46514046302;6311.589746666452;6203.131561581473;6228;6256.874862502816;6289.371062893857;6306.054091886071;6322.336154703874;6325.778597749057;6335.461485773761;6341.017142342944;6341.682915854281;6056.939430605637;6109.586862300737;6234.844128896814;6245.5628106032145;6253.8749225014335;6236.189295391751;6276.560640755103;6294.244690637152;6295.842603934755;6312.242530896322;6090.725917333642;6132.509399248139;6143.877122457615;6144.047672610753;6161.630302181883;5982.521398288213;6036.486898613667;6136.6011209271255;6159.507239420746;6164.630122192682;5764.452377024176;6059.190418209483;6088.13903791261;6089.391060894052;6104.328523862283;6182.319944806158;6189.257289125334;6190.783322583688;6194.752209911733;6204.441600255998;5745.339285981999;5829.912551311649;6018.157457955977;6021.157845909854;6113.2969708482315 12:27:04 INFO - PROCESS | 1670 | |1;ext.html;469.83012299374553;481.51607634327496;510.0487590643451;523.2151772341489;541.320642593545;31105.688943110563;34064;42093.368599470996;42173.78913105438;42175;36383.63616363836;36573.90278291649;43755.68732938009;43875;43882.56117438825;8680;9713.46575938323;9897.50512474377;9898.406095634286;9900.50497475127;8637.827243455127;8898.644054237822;8906.376553641248;8906.59920303588;11774;11837.526498940031;12034.81947770784;12045.09661775366;12065.577704780335;12089.697757556043;11894.82157767634;11977.041836653047;11983.04135669153;11994.520219191221;11995.64013079609;11835.408229588573;11916.582919597773;11920.88079119215;11922.403879805976;11926.403679815974;40068.59931400678;40512.594874051174;40527;40568.594314056776;40617;3388.7119594834467;3424.7260219182403;3580.4092324766384;3958.9999999999714;4353.1293741251875;28454.28864278389;31309.217269568217;31707.890223842056;32578.511322330185;33071.50392744111;3181.1569933967517;3219.4205043092234;3283.688049635281;3354.0608629583758;3367.8316084195694;498477;500335;502515;503145;512207.43896280654;61553;61569.38430615637;61759;61832.38167618266;61938.69030654818;66121;66142.33857661362;66216.33783662198;66301;66366.66816665983;16525.6694866102;16727.16364181786;16736.83263167377;16739;16754.4135955241;12871.678208044686;12950.222986620833;12955.157914735511;12957.676058098623;12957.740845183233;15755.44855930037;16082;16219.026858388534;17784.02187879679;18152.364667236583;5994;6327.778527751599;6418.775342862978;6460.483161347081;6468.870622587522;911.8267529169436;951.871497347863;988.9208863290919;1032.6592224565875;1039.8336266197373;29455.11634650964;29560.704392956224;30332.393352132833;30351.544726828673;30444.69555304463;1019.9541020654088;1061.946902654864;1071.314358810362;1073.683263437288;1106.7675788084432;15865.365385384686;16469;16920.661586768194;17208.569785755204;17667.469975900487;551198;559819.4063207768;563462;701829;704364;52885.7355713219;54740.726296368266;62135;62695.37304626896;63061.68469157625;1411.8235220597426;1417.4613646814144;1452.9491467798787;1459.4672944375438;1463.317185236986;31320.843395782875;31913.20216994501;32006.51990220195;32655;32893.67106328906;54188.72905635447;54955.175672365745;55003;55142.44857551373;55181.724091379285;663763;671745;672037;887836;889464;45170.77414612906;45482;45969.54030459653;46113.76943115263;46175.76912115418;48841.75579122224;49091.01764231762;49106;49878.50121498739;50036;39807.601923980386;40897.79551102345;41335.586644133175;41521.79239103785;42311.576884230766;39918.60081399265;40572.3914141294;40641.01667482577;41372.793136034124;41787.37318940279;39420.60579394284;39833.80498585047;40229.1954160921;40277.39583906185;40322.1935561293;31318;31518.684813151576;32906;32961.83519082389;33091;25190.24429267125;25789.742102578733;25924.74075259299;26230.606540902285;26432 12:27:04 INFO - PROCESS | 1670 | |2;jquery.html;328.27615108685353;355.2397868561281;372.9813509324533;382.6556099510441;403.8081911092232;14018.883107665692;15084.77372839407;20514.28200013;20534.794652053475;21415.57168856626;18382.54043648906;18671.906640466812;20638.277660281892;20655.380338589865;20908;5828.300603927534;6070.514358851281;6105.725242364104;6138.14066030756;6147.354527774586;3560.786352818839;4517.796699148546;5964.552658550604;6087.512998960095;6698.832529186785;6711.664416779166;6734.191896972346;6735.292794256606;6736.124303840518;6736.821056315125;6375.10748495211;6544.083828264047;6590.769323073718;6605.801825945229;6737.865242695168;5552.91670624941;5712.8000519982015;5713.400092990218;5719.14212868069;5727.083666613322;20357.694634580494;26224.73775262242;26300.605490917653;26505.602415963775;26664.86667566669;2327.8486898351553;2792.6509186351705;2989.491786396318;3114.2681469854565;3247.25313177968;5099.33708617881;5518.420565840609;14245.789107925839;14679.339429725582;15587.376504939788;1804.3955274982766;1834.28462899468;1849.9075046247633;1937.8255956963942;1940.7865134835156;123690;126946;128432;130388.6961130386;131241;10429.322094063862;10631.574737010587;11542.59600913964;11572.305661660328;11744.647660570197;12897.419616117297;13514.32428378577;13553.254570998493;13595.184288942695;13598.728025439435;6323.336049714806;6733.831654208684;7711.190325015905;7801.180876008051;8084.1107478177355;7921.326687231534;8000.87987681725;8713.390062695677;8754.037055923844;8782;4340;4463.152001119842;4485;4559.635229181657;4561.7262964222255;1654.346533119411;1773.343862770783;1867.3837633580886;1912.1490936533387;1924.0278714317656;776.5884081436848;779.3803925878826;819.700809204648;873.5151990645253;874.3486102853415;20516.692249616266;21320;21445.57108857814;21528.246511372028;21626.675599866016;910.1626503616689;921.6313474610075;929.5073610986188;996.9003099690118;1048.16670746755;10379.948100259602;10701.357918524913;10977.83533247002;11324.207305488537;11335.319880807177;552362;575189;581032;594316;594940;81027.18972810314;83728.16271837325;128597;132163;139691;972.7568107973007;994.418265314799;1012.731626119079;1015.5785349080049;1018.6027449294781;18907.338243161423;19505.804941950966;19638.60722785508;19673.704894426883;19683.31108411199;39981.804185038185;49910.25314113989;54921.626959326335;55822.720886395306;59119.70440147772;86661.56669216866;87045;87893.56053219693;88607.55696221477;88657;8125.431219814556;8139.83720325602;8190.467619604707;8241.50550966944;8363.121872203574;12178;12984.091113621957;13026.739465210832;13232.272225027717;13384;11138.721531961766;11212.943935280271;11369.31784092957;11377.829332559853;11558.479868405773;6331.5251356147555;11139.554417823196;11158.107351411705;11218.382988935688;11524.884751152382;11434.256773309708;11453.083753299883;11575.942120289345;11692.94153529227;11693.298402095901;7907.8813817793925;8145.592720364077;8210.83578328442;8365.372597055124;8365.581720913808;9982.301238913205;10973.396463194282;11166.441677916235;11318.434078296217;11796.52813887435 12:27:04 INFO - PROCESS | 1670 | |3;mootools.html;1522.1552038618563;1554.1685198418845;1756.9560760980983;1870.298638010746;1906.2660875562913;8335.499870007803;8916.063813299608;8916.687915922945;8917.777055573606;8935.285177185817;7831.608419579013;8083.636236369362;8444.873326900102;8676.52279124648;8731.25784308333;2328.5459335429555;2359.516299158677;2359.8702071386065;2361.8819059046978;2364.645303204516;2311.491471876193;2319.396956791238;2330.8718020508863;2355.740868504463;2355.9411014724596;2029.6853987631866;2320.640300753386;2340.9648855267183;2349.6240601503764;2356.1282325539573;2057.8887400803546;2254.7068881045525;2311.179531266401;2319.211468100837;2319.860808351496;2238.552289542098;2267.2744721689064;2270.7842754938256;2274.3404412720292;2274.8066414354635;8687.695930642398;8898.37711360205;8901.910980890172;8922.821543569156;8938.776530586721;5175.741212939338;5339.946600533984;5341.786328546854;5344.4922732340365;5349.7057661828585;3419.5212670226188;3507;3652.6712595866234;3663.4687970244167;3664.13892735207;1075.4138994248153;1088.8475613414128;1092.9508172132194;1109.389835590422;1114.849495318138;11488.310701357861;11649.883501164964;11657.067434605297;11672.35802030888;11688.41557922109;4475.955240447619;4571.702839315434;4711.539422778949;4747.192977193886;4749.453812811502;9866.901330986599;9877.308588398886;9883.505824708736;9894.356866803635;9895.901040989642;5308.708021058802;5310.123829568078;5310.548603368757;5311.867203319871;5324.600654950894;4148.377743338523;4384.430024096847;4518.774061296922;4862.708237505761;4878.658493905464;3328.7836290641517;3418.8119653418803;3911.3155197840265;4000.8399664013696;4025.677945764332;1259.2759163481035;1274.6877015131167;1278.181963543333;1289.013904363163;1294.7410517896458;824.8432797768405;898.1063841477641;904.2585080234277;937.2595649436869;968.6173961285393;5784.797532086357;5941.405859414111;6023.66869822164;6048.364921683248;6145.517282408184;419.8866306097337;433.83947939262674;434.54372908446004;434.93041113422333;439.26861775144664;3557.7153827693724;3924.234774219057;3957.248122856648;4069.369247766601;4080.3063479208604;9958.651447199314;10612.363258204532;10700.678979630624;10702.357858528514;10723.83914241287;10552;10568.207384446197;10617.946910265398;10700;10757.569697212024;8035.116137224901;8144.226298501633;8216.589170541449;8228.753137405887;8231.382646301552;4979.502049794992;5105.463926287686;5698;5804.448577385142;5899.410058994066;9922.801543969224;9953.153981911619;10006.199504039805;10020.24848136378;10028.247881408777;8434.70478533273;9218.21645160169;9563.330566860532;10058.698239052841;10195.490225488844;6874.6562671867205;7085.256048114874;7487.6630551626395;7518.473706840468;7561.168271490022;8229.382796290422;8285.544295063588;8301.086880443027;8304.169583041648;8321.95839020801;7325.926740732738;7371.299726525972;7508.58702771331;7650.885236721565;7664.961675191588;7266.200717920919;7435.033445652247;7447.031885854804;7468.365188958782;7484.850302994018;6929.480288978246;7085.326893945067;7089.149302083577;7092.929070709227;7103.360697537247;4902.975485122552;5065.39215294167;5084.435472299309;5097.847064588068;5103.234514822806;4101.548829628799;4421.226285399978;5043.193089105872;5247.317848679647;5271.868203294948 12:27:04 INFO - PROCESS | 1670 | |4;prototype.html;252.9228585281489;289.2883506573828;306.2008158705778;309.49706726569326;312.5842629303027;4589.931151032729;5215.86960325992;5385.3268341457315;5430.429804870486;5558.805441809543;4338.717983331081;5187.910538786859;5420.159875219348;5422.864428389292;5443.346798384189;4133.689973252003;4195.832166713328;4197.3913782501595;4199.5511548515715;4200.222958752641;3848.3644451108307;4040.898977525571;4193.287141186006;4205.915881682365;4214.831406743727;3421.384150852858;4063.634272915437;4083.305838007546;4084.4690190275223;4111.876643700693;3423.623401425841;3897.3179693553516;4014.7353583621357;4051.0757390239696;4077.7359018704296;3542.6280240574624;3564.483149943244;3572.464130380438;3575.8212089395424;3595.7842529448053;4971.701697898102;5259.3951695555115;5311.114554333233;5375.489328513785;5393.056215162331;4610.608098311647;4638.5593368629925;4644.907101857978;4662.696924699883;4663.440387153563;5665.801696940587;5668.31980162379;5673.517750991171;5697.658140511582;5714.942850571482;3015.894443694482;3805.040404191843;3852.749571277858;3856.245408339199;3862.7682339059465;4951.801927922878;5439.374471935738;5496.928099020693;6002.879942401171;6024.463761740772;3320.833958302075;3928.607139286106;3935.685145188378;3937.4881265435884;3948.565657777642;3818.045488627843;3985.242803867256;3987.322155233617;4050.2911990401562;4073.3889916512167;3715.6437900166784;4148.522919864255;4217.451731274915;4687.7656117194;4725.196716558194;4575.163398692765;5470.890582188334;5636.774529018793;5761.942380576224;5790.449907258803;1196.36592605919;1210.6186551236333;1232.8643849176583;1293.3727142335958;1358.7825947848296;905.2712566384124;928.2944961829058;970.8495183246608;978.554757585297;980.6959842448864;5196.246544251062;5235.2776556190565;5248.6588371755715;5304.416514183436;5310.734463276821;5786.508146807484;5794.391588883191;5797.391273916262;5800.245968024215;5814.476697097281;3906.8632597858937;4296.572436573326;5210.869728256824;5240.6069544784295;5302.761375738101;2461.163204510475;2806.936901377486;3044.2845931206148;3049.35963447674;3059.2409364615437;5474.686075341969;5739.799107031234;6108;6124.479419249325;6126.540509461808;5457.2905522282645;5779.393163717749;5918.615290006136;5964.284285885635;5981.820545383645;5349.304590403222;5525.530329921921;5530.806421775219;5952.987992041363;6045.3652366501765;5111.412187598325;5531.280933478622;5593.692346920959;5851.327097383855;5857;5704.885902282014;5766.481016708516;5790.449907258803;5790.768369265183;5791.424774428155;5022.899542009212;5299.986005317955;5717.571182161271;6066.514678825595;6108.786192483241;3772.226693527875;3882.6699730523223;3896.6298201670797;3905.1882644495918;3907.47249121365;4687.742174180453;5799.652020878761;5852.502537284208;5863;5895.970520147372;5536.7231638417115;5576.912502062059;5666.716664166857;5669.262995810518;5693.829185124453;4646.049758403371;5663.5469162467725;5675.921574900755;5678.006348889011;5700.059490184073;4503.166914120898;5280.287161233185;5535.4187810280955;5545.972270138624;5619.80330688424;5669.489745922887;5678.574106941914;5690.687012214368;5691.146328050825;5699.0311647020935;5510.586705997146;5517.78569252053;5577.386487486292;5581.6371935824045;5598.35618903815 12:27:04 INFO - PROCESS | 1670 | |5;yui.html;685.9657017149142;771.506236008954;780.3055280800089;799.668137722845;816.3306089007015;14593.416263349467;15418.30617622207;16117.597060073489;16124.596885077879;16368.83631163688;14379.424823007093;14773.113613183217;14780.408783648641;14795.704085918274;14796.482123125692;700.2822107339966;849.4987957105291;873.1792115411492;875.2297977779546;875.6059773102098;710.783211120608;834.0533494483774;852.1861622150863;859.3340161374922;863.3352318714586;918.3892711346946;920.4523308631353;921.6451666108529;922.1055576091181;922.4419226368018;722.4509372876652;916.1434059154645;916.1479823763897;916.3448134583738;921.0237148622482;550.7521615273102;555.5111501878321;555.6082961512125;556.0103016630404;652.774792696519;14573.927130364385;14693.485727999469;14695.632609184855;14707.852921470861;14713.117212967256;12172;12764.87235127646;13540.729185416336;13772.517961871286;14041.859581404158;12146.878531214663;12281.140320177592;12412.75174496514;12688.873111268862;12741.490340386374;4476.798544065525;4479.193745125876;4512.932306015412;4663.860084197479;4674.929876051862;120099;122091;123157.68948086775;123524.38237808843;123907;8346.374021948379;10687.946560267148;10788.46057697112;10856.2400631955;11360.829587556193;20928;21267.78732212689;21286.680699789515;21294.935253237276;21300.147994080373;7571.3564347030015;7607.35337496319;7618.542887426772;7734.574598397142;7758.650860711282;3766.2844059628583;4288.592583704543;4385.320275357325;4426.557344265612;4522.389477420506;3539.2036791721657;3669.119411341312;3678.9448158277646;3690.630936906288;3691.077230692327;573.7131434282859;668.5520701130255;671.1241829412565;687.6183718036538;689.407109885498;567.9545636349083;570.9914351284734;613.923259592551;621.9346968568326;629.3297638015499;8656.697015604424;8989.146031127033;9197.126273004054;9242.72271831846;9499.715008549754;601.978930737422;603.9486643635253;624.1979056911847;626.8213559135625;631.8041407163794;4270.48754149504;4422.226110430662;4436.134953684001;4451.5103338632725;4473.821047158077;102400;126573;156998.21500892422;162274.3772562283;162955;135032.32483837716;137841.68946665313;138144.7834847434;138444;144508.83236751458;120782.396088019;122330;122364;123406;124217.9999999982;8597.312215022908;8606.741797746077;8644.394892357475;8656.48061116335;8824.117588241124;63174.68412658091;65773.67113164596;65922;67408.66295668687;67930;73144;73769.6311518439;82653.17346826455;82792;100005;13606.183628982293;13654.658633533843;13781.173129612256;14207.360668769723;14242.145471271757;15587.688246235239;15783.605409864846;16025.038497690175;16262.349506019627;16264.186790660657;13131.75248351405;13255.734885302432;14317.928410358298;14503.1298122113;14978.251087445802;12206.206596571194;13055.869441305465;13435.261060641367;14127.788083178966;14309.35607897668;13723.656908577366;14060.367283472473;14469.49356772508;14480.927595361956;14602.707945840812;9526.952365238129;11444.542218311508;11452.198346116025;11458.82811757841;11847.280988497021;8739.56302184901;9673.081057299545;11163.832542512031;11711.414429278331;11741.17811753169 12:27:04 INFO - PROCESS | 1670 | __end_tp_report 12:27:04 INFO - PROCESS | 1670 | __start_cc_report 12:27:04 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1390 12:27:04 INFO - PROCESS | 1670 | __end_cc_report 12:27:04 INFO - PROCESS | 1670 | __startTimestamp1470425224832__endTimestamp 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | ------- Summary: start ------- 12:27:04 INFO - PROCESS | 1670 | Number of tests: 6 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5760.53 Median:6036.49 stddev:643.64 (10.7%) stddev-sans-first:617.56 12:27:04 INFO - PROCESS | 1670 | Values: 3364.8 3375.8 3379.4 3403.3 3435.6 5591.9 5625.2 5722.5 5763.7 5791.5 5680.1 5703.9 5768.0 5802.9 5843.5 5142.1 5158.2 5217.2 5233.3 5324.8 5311.4 5319.1 5322.5 5323.2 5368.2 5129.5 5129.7 5130.8 5203.7 5248.3 5185.6 5196.1 5205.6 5206.0 5209.6 5089.7 5157.1 5157.2 5159.1 5192.9 5617.9 5619.8 5700.9 5722.6 5790.5 6149.1 6199.6 6204.1 6280.4 6285.2 6196.1 6318.6 6336.3 6338.2 6353.3 5677.3 5741.8 5742.8 5821.2 5850.3 6189.2 6236.4 6249.4 6252.6 6255.9 5469.8 6073.5 6173.0 6206.9 6218.4 6096.3 6175.3 6194.3 6285.8 6303.0 6205.2 6254.7 6272.0 6273.8 6285.1 6278.3 6289.3 6290.5 6310.3 6314.1 4833.3 4861.4 4869.9 4929.1 4958.1 4391.3 4404.0 4410.1 4426.4 4466.8 5682.8 5683.5 5687.9 5706.9 5745.2 6149.5 6206.8 6303.2 6309.1 6314.4 5719.6 5734.7 5760.3 5782.6 5784.6 5424.6 5435.8 5442.7 5444.1 5455.5 6184.3 6198.9 6290.7 6292.5 6311.6 6203.1 6228.0 6256.9 6289.4 6306.1 6322.3 6325.8 6335.5 6341.0 6341.7 6056.9 6109.6 6234.8 6245.6 6253.9 6236.2 6276.6 6294.2 6295.8 6312.2 6090.7 6132.5 6143.9 6144.0 6161.6 5982.5 6036.5 6136.6 6159.5 6164.6 5764.5 6059.2 6088.1 6089.4 6104.3 6182.3 6189.3 6190.8 6194.8 6204.4 5745.3 5829.9 6018.2 6021.2 6113.3 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:76186.78 Median:40148.90 stddev:171180.69 (426.4%) stddev-sans-first:171566.49 12:27:04 INFO - PROCESS | 1670 | Values: 469.8 481.5 510.0 523.2 541.3 31105.7 34064.0 42093.4 42173.8 42175.0 36383.6 36573.9 43755.7 43875.0 43882.6 8680.0 9713.5 9897.5 9898.4 9900.5 8637.8 8898.6 8906.4 8906.6 11774.0 11837.5 12034.8 12045.1 12065.6 12089.7 11894.8 11977.0 11983.0 11994.5 11995.6 11835.4 11916.6 11920.9 11922.4 11926.4 40068.6 40512.6 40527.0 40568.6 40617.0 3388.7 3424.7 3580.4 3959.0 4353.1 28454.3 31309.2 31707.9 32578.5 33071.5 3181.2 3219.4 3283.7 3354.1 3367.8 498477.0 500335.0 502515.0 503145.0 512207.4 61553.0 61569.4 61759.0 61832.4 61938.7 66121.0 66142.3 66216.3 66301.0 66366.7 16525.7 16727.2 16736.8 16739.0 16754.4 12871.7 12950.2 12955.2 12957.7 12957.7 15755.4 16082.0 16219.0 17784.0 18152.4 5994.0 6327.8 6418.8 6460.5 6468.9 911.8 951.9 988.9 1032.7 1039.8 29455.1 29560.7 30332.4 30351.5 30444.7 1020.0 1061.9 1071.3 1073.7 1106.8 15865.4 16469.0 16920.7 17208.6 17667.5 551198.0 559819.4 563462.0 701829.0 704364.0 52885.7 54740.7 62135.0 62695.4 63061.7 1411.8 1417.5 1452.9 1459.5 1463.3 31320.8 31913.2 32006.5 32655.0 32893.7 54188.7 54955.2 55003.0 55142.4 55181.7 663763.0 671745.0 672037.0 887836.0 889464.0 45170.8 45482.0 45969.5 46113.8 46175.8 48841.8 49091.0 49106.0 49878.5 50036.0 39807.6 40897.8 41335.6 41521.8 42311.6 39918.6 40572.4 40641.0 41372.8 41787.4 39420.6 39833.8 40229.2 40277.4 40322.2 31318.0 31518.7 32906.0 32961.8 33091.0 25190.2 25789.7 25924.7 26230.6 26432.0 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34589.10 Median:3051.88 stddev:97065.54 (3180.5%) stddev-sans-first:97303.75 12:27:04 INFO - PROCESS | 1670 | Values: 328.3 355.2 373.0 382.7 403.8 14018.9 15084.8 20514.3 20534.8 21415.6 18382.5 18671.9 20638.3 20655.4 20908.0 5828.3 6070.5 6105.7 6138.1 6147.4 3560.8 4517.8 5964.6 6087.5 6698.8 6711.7 6734.2 6735.3 6736.1 6736.8 6375.1 6544.1 6590.8 6605.8 6737.9 5552.9 5712.8 5713.4 5719.1 5727.1 20357.7 26224.7 26300.6 26505.6 26664.9 2327.8 2792.7 2989.5 3114.3 3247.3 5099.3 5518.4 14245.8 14679.3 15587.4 1804.4 1834.3 1849.9 1937.8 1940.8 123690.0 126946.0 128432.0 130388.7 131241.0 10429.3 10631.6 11542.6 11572.3 11744.6 12897.4 13514.3 13553.3 13595.2 13598.7 6323.3 6733.8 7711.2 7801.2 8084.1 7921.3 8000.9 8713.4 8754.0 8782.0 4340.0 4463.2 4485.0 4559.6 4561.7 1654.3 1773.3 1867.4 1912.1 1924.0 776.6 779.4 819.7 873.5 874.3 20516.7 21320.0 21445.6 21528.2 21626.7 910.2 921.6 929.5 996.9 1048.2 10379.9 10701.4 10977.8 11324.2 11335.3 552362.0 575189.0 581032.0 594316.0 594940.0 81027.2 83728.2 128597.0 132163.0 139691.0 972.8 994.4 1012.7 1015.6 1018.6 18907.3 19505.8 19638.6 19673.7 19683.3 39981.8 49910.3 54921.6 55822.7 59119.7 86661.6 87045.0 87893.6 88607.6 88657.0 8125.4 8139.8 8190.5 8241.5 8363.1 12178.0 12984.1 13026.7 13232.3 13384.0 11138.7 11212.9 11369.3 11377.8 11558.5 6331.5 11139.6 11158.1 11218.4 11524.9 11434.3 11453.1 11575.9 11692.9 11693.3 7907.9 8145.6 8210.8 8365.4 8365.6 9982.3 10973.4 11166.4 11318.4 11796.5 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5647.16 Median:4890.82 stddev:3195.41 (65.3%) stddev-sans-first:3189.34 12:27:04 INFO - PROCESS | 1670 | Values: 1522.2 1554.2 1757.0 1870.3 1906.3 8335.5 8916.1 8916.7 8917.8 8935.3 7831.6 8083.6 8444.9 8676.5 8731.3 2328.5 2359.5 2359.9 2361.9 2364.6 2311.5 2319.4 2330.9 2355.7 2355.9 2029.7 2320.6 2341.0 2349.6 2356.1 2057.9 2254.7 2311.2 2319.2 2319.9 2238.6 2267.3 2270.8 2274.3 2274.8 8687.7 8898.4 8901.9 8922.8 8938.8 5175.7 5339.9 5341.8 5344.5 5349.7 3419.5 3507.0 3652.7 3663.5 3664.1 1075.4 1088.8 1093.0 1109.4 1114.8 11488.3 11649.9 11657.1 11672.4 11688.4 4476.0 4571.7 4711.5 4747.2 4749.5 9866.9 9877.3 9883.5 9894.4 9895.9 5308.7 5310.1 5310.5 5311.9 5324.6 4148.4 4384.4 4518.8 4862.7 4878.7 3328.8 3418.8 3911.3 4000.8 4025.7 1259.3 1274.7 1278.2 1289.0 1294.7 824.8 898.1 904.3 937.3 968.6 5784.8 5941.4 6023.7 6048.4 6145.5 419.9 433.8 434.5 434.9 439.3 3557.7 3924.2 3957.2 4069.4 4080.3 9958.7 10612.4 10700.7 10702.4 10723.8 10552.0 10568.2 10617.9 10700.0 10757.6 8035.1 8144.2 8216.6 8228.8 8231.4 4979.5 5105.5 5698.0 5804.4 5899.4 9922.8 9953.2 10006.2 10020.2 10028.2 8434.7 9218.2 9563.3 10058.7 10195.5 6874.7 7085.3 7487.7 7518.5 7561.2 8229.4 8285.5 8301.1 8304.2 8322.0 7325.9 7371.3 7508.6 7650.9 7665.0 7266.2 7435.0 7447.0 7468.4 7484.9 6929.5 7085.3 7089.1 7092.9 7103.4 4903.0 5065.4 5084.4 5097.8 5103.2 4101.5 4421.2 5043.2 5247.3 5271.9 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4591.25 Median:5290.14 stddev:1423.61 (26.9%) stddev-sans-first:1389.87 12:27:04 INFO - PROCESS | 1670 | Values: 252.9 289.3 306.2 309.5 312.6 4589.9 5215.9 5385.3 5430.4 5558.8 4338.7 5187.9 5420.2 5422.9 5443.3 4133.7 4195.8 4197.4 4199.6 4200.2 3848.4 4040.9 4193.3 4205.9 4214.8 3421.4 4063.6 4083.3 4084.5 4111.9 3423.6 3897.3 4014.7 4051.1 4077.7 3542.6 3564.5 3572.5 3575.8 3595.8 4971.7 5259.4 5311.1 5375.5 5393.1 4610.6 4638.6 4644.9 4662.7 4663.4 5665.8 5668.3 5673.5 5697.7 5714.9 3015.9 3805.0 3852.7 3856.2 3862.8 4951.8 5439.4 5496.9 6002.9 6024.5 3320.8 3928.6 3935.7 3937.5 3948.6 3818.0 3985.2 3987.3 4050.3 4073.4 3715.6 4148.5 4217.5 4687.8 4725.2 4575.2 5470.9 5636.8 5761.9 5790.4 1196.4 1210.6 1232.9 1293.4 1358.8 905.3 928.3 970.8 978.6 980.7 5196.2 5235.3 5248.7 5304.4 5310.7 5786.5 5794.4 5797.4 5800.2 5814.5 3906.9 4296.6 5210.9 5240.6 5302.8 2461.2 2806.9 3044.3 3049.4 3059.2 5474.7 5739.8 6108.0 6124.5 6126.5 5457.3 5779.4 5918.6 5964.3 5981.8 5349.3 5525.5 5530.8 5953.0 6045.4 5111.4 5531.3 5593.7 5851.3 5857.0 5704.9 5766.5 5790.4 5790.8 5791.4 5022.9 5300.0 5717.6 6066.5 6108.8 3772.2 3882.7 3896.6 3905.2 3907.5 4687.7 5799.7 5852.5 5863.0 5896.0 5536.7 5576.9 5666.7 5669.3 5693.8 4646.0 5663.5 5675.9 5678.0 5700.1 4503.2 5280.3 5535.4 5546.0 5619.8 5669.5 5678.6 5690.7 5691.1 5699.0 5510.6 5517.8 5577.4 5581.6 5598.4 12:27:04 INFO - PROCESS | 1670 | 12:27:04 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:25543.29 Median:3690.85 stddev:41294.18 (1118.8%) stddev-sans-first:41367.84 12:27:04 INFO - PROCESS | 1670 | Values: 686.0 771.5 780.3 799.7 816.3 14593.4 15418.3 16117.6 16124.6 16368.8 14379.4 14773.1 14780.4 14795.7 14796.5 700.3 849.5 873.2 875.2 875.6 710.8 834.1 852.2 859.3 863.3 918.4 920.5 921.6 922.1 922.4 722.5 916.1 916.1 916.3 921.0 550.8 555.5 555.6 556.0 652.8 14573.9 14693.5 14695.6 14707.9 14713.1 12172.0 12764.9 13540.7 13772.5 14041.9 12146.9 12281.1 12412.8 12688.9 12741.5 4476.8 4479.2 4512.9 4663.9 4674.9 120099.0 122091.0 123157.7 123524.4 123907.0 8346.4 10687.9 10788.5 10856.2 11360.8 20928.0 21267.8 21286.7 21294.9 21300.1 7571.4 7607.4 7618.5 7734.6 7758.7 3766.3 4288.6 4385.3 4426.6 4522.4 3539.2 3669.1 3678.9 3690.6 3691.1 573.7 668.6 671.1 687.6 689.4 568.0 571.0 613.9 621.9 629.3 8656.7 8989.1 9197.1 9242.7 9499.7 602.0 603.9 624.2 626.8 631.8 4270.5 4422.2 4436.1 4451.5 4473.8 102400.0 126573.0 156998.2 162274.4 162955.0 135032.3 137841.7 138144.8 138444.0 144508.8 120782.4 122330.0 122364.0 123406.0 124218.0 8597.3 8606.7 8644.4 8656.5 8824.1 63174.7 65773.7 65922.0 67408.7 67930.0 73144.0 73769.6 82653.2 82792.0 100005.0 13606.2 13654.7 13781.2 14207.4 14242.1 15587.7 15783.6 16025.0 16262.3 16264.2 13131.8 13255.7 14317.9 14503.1 14978.3 12206.2 13055.9 13435.3 14127.8 14309.4 13723.7 14060.4 14469.5 14480.9 14602.7 9527.0 11444.5 11452.2 11458.8 11847.3 8739.6 9673.1 11163.8 11711.4 11741.2 12:27:04 INFO - PROCESS | 1670 | -------- Summary: end -------- 12:27:04 INFO - PROCESS | 1670 | 12:27:05 INFO - TEST-INFO | 1670: exit 0 12:27:05 INFO - TEST-OK | dromaeo_css | took 1118501ms 12:27:05 INFO - TEST-START | kraken 12:27:05 INFO - operating with platform_type : mac_ 12:27:05 INFO - Initialising browser for kraken test... 12:27:05 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpOvRdry/profile http://localhost:49271/getInfo.html) 12:27:07 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 12:27:07 INFO - PROCESS | 1677 | colorDepth:24 12:27:07 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 12:27:07 INFO - PROCESS | 1677 | __metrics 12:27:07 INFO - PROCESS | 1677 | 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" 12:27:07 INFO - PROCESS | 1677 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:27:08 INFO - TEST-INFO | 1677: exit 0 12:27:08 INFO - Browser initialized. 12:27:08 INFO - Running cycle 1/1 for kraken test... 12:27:08 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpOvRdry/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:27:20 INFO - PROCESS | 1678 | RSS: Main: 243224576 12:27:20 INFO - PROCESS | 1678 | 12:27:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 12:27:24 INFO - PROCESS | 1678 | RSS: Main: 294215680 12:27:24 INFO - PROCESS | 1678 | 12:27:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 12:27:27 INFO - PROCESS | 1678 | RSS: Main: 302022656 12:27:27 INFO - PROCESS | 1678 | 12:27:31 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 12:27:31 INFO - PROCESS | 1678 | RSS: Main: 396345344 12:27:31 INFO - PROCESS | 1678 | 12:27:33 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 12:27:34 INFO - PROCESS | 1678 | RSS: Main: 299200512 12:27:34 INFO - PROCESS | 1678 | 12:27:36 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:27:36 INFO - PROCESS | 1678 | RSS: Main: 302923776 12:27:36 INFO - PROCESS | 1678 | 12:27:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 12:27:40 INFO - PROCESS | 1678 | RSS: Main: 340582400 12:27:40 INFO - PROCESS | 1678 | 12:27:43 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 12:27:43 INFO - PROCESS | 1678 | RSS: Main: 342614016 12:27:43 INFO - PROCESS | 1678 | 12:27:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 12:27:47 INFO - PROCESS | 1678 | RSS: Main: 340398080 12:27:47 INFO - PROCESS | 1678 | 12:27:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:27:49 INFO - PROCESS | 1678 | RSS: Main: 314269696 12:27:49 INFO - PROCESS | 1678 | 12:27:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:27:51 INFO - PROCESS | 1678 | RSS: Main: 319143936 12:27:51 INFO - PROCESS | 1678 | 12:27:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:27:54 INFO - PROCESS | 1678 | RSS: Main: 318685184 12:27:54 INFO - PROCESS | 1678 | 12:27:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:27:58 INFO - PROCESS | 1678 | RSS: Main: 327663616 12:27:58 INFO - PROCESS | 1678 | 12:28:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:28:01 INFO - PROCESS | 1678 | RSS: Main: 329048064 12:28:01 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 12:28:03 INFO - PROCESS | 1678 | RSS: Main: 372338688 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | __start_tp_report 12:28:03 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 12:28:03 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 12:28:03 INFO - PROCESS | 1678 | |i|pagename|runs| 12:28:03 INFO - PROCESS | 1678 | |0;ai-astar;136;166;150;114;113;113;112;113;112;112 12:28:03 INFO - PROCESS | 1678 | |1;audio-beat-detection;143;114;109;160;109;112;156;121;141;108 12:28:03 INFO - PROCESS | 1678 | |2;audio-dft;192;184;186;184;184;185;183;187;191;255 12:28:03 INFO - PROCESS | 1678 | |3;audio-fft;79;57;58;109;57;57;62;65;83;57 12:28:03 INFO - PROCESS | 1678 | |4;audio-oscillator;84;67;92;69;78;68;95;70;68;97 12:28:03 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;89;87;88;87;89;86;88;86;89;86 12:28:03 INFO - PROCESS | 1678 | |6;imaging-darkroom;94;94;94;96;95;94;94;95;95;94 12:28:03 INFO - PROCESS | 1678 | |7;imaging-desaturate;86;84;86;86;80;83;85;87;81;83 12:28:03 INFO - PROCESS | 1678 | |8;json-parse-financial;53;54;54;54;53;53;53;52;52;54 12:28:03 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;68;66;69;77;79;73;70;71;70;72 12:28:03 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;84;84;85;82;81;82;93;98;79;83 12:28:03 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;138;136;141;149;150;134;136;138;138;137 12:28:03 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;165;166;163;213;186;163;165;163;164;159 12:28:03 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;67;66;67;63;59;70;76;83;81;81 12:28:03 INFO - PROCESS | 1678 | __end_tp_report 12:28:03 INFO - PROCESS | 1678 | __start_cc_report 12:28:03 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,2044 12:28:03 INFO - PROCESS | 1678 | __end_cc_report 12:28:03 INFO - PROCESS | 1678 | __startTimestamp1470425283813__endTimestamp 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | ------- Summary: start ------- 12:28:03 INFO - PROCESS | 1678 | Number of tests: 14 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:124.10 Median:113.50 stddev:19.66 (17.3%) stddev-sans-first:20.38 12:28:03 INFO - PROCESS | 1678 | Values: 136.0 166.0 150.0 114.0 113.0 113.0 112.0 113.0 112.0 112.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:131.00 stddev:20.60 (15.7%) stddev-sans-first:21.05 12:28:03 INFO - PROCESS | 1678 | Values: 143.0 114.0 109.0 160.0 109.0 112.0 156.0 121.0 141.0 108.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:193.10 Median:186.50 stddev:21.96 (11.8%) stddev-sans-first:23.29 12:28:03 INFO - PROCESS | 1678 | Values: 192.0 184.0 186.0 184.0 184.0 185.0 183.0 187.0 191.0 255.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:68.40 Median:60.00 stddev:17.17 (28.6%) stddev-sans-first:17.78 12:28:03 INFO - PROCESS | 1678 | Values: 79.0 57.0 58.0 109.0 57.0 57.0 62.0 65.0 83.0 57.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:81.00 stddev:12.21 (15.1%) stddev-sans-first:12.80 12:28:03 INFO - PROCESS | 1678 | Values: 84.0 67.0 92.0 69.0 78.0 68.0 95.0 70.0 68.0 97.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:1.27 (1.4%) stddev-sans-first:1.22 12:28:03 INFO - PROCESS | 1678 | Values: 89.0 87.0 88.0 87.0 89.0 86.0 88.0 86.0 89.0 86.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 12:28:03 INFO - PROCESS | 1678 | Values: 94.0 94.0 94.0 96.0 95.0 94.0 94.0 95.0 95.0 94.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:85.50 stddev:2.33 (2.7%) stddev-sans-first:2.37 12:28:03 INFO - PROCESS | 1678 | Values: 86.0 84.0 86.0 86.0 80.0 83.0 85.0 87.0 81.0 83.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:0.79 (1.5%) stddev-sans-first:0.83 12:28:03 INFO - PROCESS | 1678 | Values: 53.0 54.0 54.0 54.0 53.0 53.0 53.0 52.0 52.0 54.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:3.98 (5.6%) stddev-sans-first:4.01 12:28:03 INFO - PROCESS | 1678 | Values: 68.0 66.0 69.0 77.0 79.0 73.0 70.0 71.0 70.0 72.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.00 stddev:5.86 (7.0%) stddev-sans-first:6.20 12:28:03 INFO - PROCESS | 1678 | Values: 84.0 84.0 85.0 82.0 81.0 82.0 93.0 98.0 79.0 83.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:138.00 stddev:5.48 (4.0%) stddev-sans-first:5.78 12:28:03 INFO - PROCESS | 1678 | Values: 138.0 136.0 141.0 149.0 150.0 134.0 136.0 138.0 138.0 137.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:165.00 stddev:16.57 (10.0%) stddev-sans-first:17.44 12:28:03 INFO - PROCESS | 1678 | Values: 165.0 166.0 163.0 213.0 186.0 163.0 165.0 163.0 164.0 159.0 12:28:03 INFO - PROCESS | 1678 | 12:28:03 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:73.00 stddev:8.39 (11.5%) stddev-sans-first:8.76 12:28:03 INFO - PROCESS | 1678 | Values: 67.0 66.0 67.0 63.0 59.0 70.0 76.0 83.0 81.0 81.0 12:28:03 INFO - PROCESS | 1678 | -------- Summary: end -------- 12:28:03 INFO - PROCESS | 1678 | 12:28:04 INFO - TEST-INFO | 1678: exit 0 12:28:04 INFO - TEST-OK | kraken | took 59140ms 12:28:04 INFO - SUITE-END | took 1177s 12:28:04 INFO - Completed test suite (00:19:37) 12:28:04 INFO - javascript benchmark 12:28:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3364.7981121132734, 3375.848086836092, 3379.442392005319, 3403.285310084882, 3435.6220815710285, 5591.888162236753, 5625.156226566012, 5722.54219662427, 5763.682997435145, 5791.507721843643, 5680.091185410335, 5703.857403564913, 5768.048272035119, 5802.854928626786, 5843.532517398608, 5142.10013247682, 5158.200478925773, 5217.243499692542, 5233.345831771028, 5324.76038578263, 5311.442298558653, 5319.0957537218765, 5322.494363035506, 5323.1749078892835, 5368.194770784394, 5129.512696293846, 5129.717865517395, 5130.769115389817, 5203.739813009353, 5248.291480650101, 5185.611079169077, 5196.090684130262, 5205.583553315726, 5206.0, 5209.583233341324, 5089.6691715038405, 5157.149070403379, 5157.226416037587, 5159.071367153911, 5192.948070519322, 5617.887642247132, 5619.80330688424, 5700.914486282709, 5722.599418040739, 5790.478856902857, 6149.108379285023, 6199.628022318675, 6204.069389591552, 6280.371962803729, 6285.182926219608, 6196.132541444202, 6318.589291696025, 6336.334684858116, 6338.207724034496, 6353.30113687494, 5677.290338707661, 5741.799037033684, 5742.799002034993, 5821.2141360916585, 5850.268716410449, 6189.164462797465, 6236.407541283571, 6249.437550620375, 6252.562320637512, 6255.937440625627, 5469.823987842633, 6073.453389194994, 6172.969135154385, 6206.906896396557, 6218.440340369389, 6096.345267793883, 6175.320714721377, 6194.256689197326, 6285.779997700059, 6303.023031430135, 6205.22434695663, 6254.718537665816, 6271.96864015677, 6273.843153921097, 6285.057241413733, 6278.30938596754, 6289.308176100625, 6290.4653104485715, 6310.305866354697, 6314.052892066226, 4833.347498087714, 4861.416630004422, 4869.878253043702, 4929.088118698052, 4958.107540642647, 4391.253486907233, 4403.9559604404185, 4410.1400226956075, 4426.424564806554, 4466.798994045275, 5682.772689092472, 5683.460071293221, 5687.886242275132, 5706.885862282814, 5745.1669507921115, 6149.538784591174, 6206.844828879224, 6303.180586523723, 6309.053641953652, 6314.368563143649, 5719.628224165416, 5734.7132643368495, 5760.308762948473, 5782.566307526952, 5784.595078344475, 5424.647397919122, 5435.755391007414, 5442.673439593637, 5444.101723215705, 5455.481729235717, 6184.288806787277, 6198.876022479435, 6290.685465726787, 6292.46514046302, 6311.589746666452, 6203.131561581473, 6228.0, 6256.874862502816, 6289.371062893857, 6306.054091886071, 6322.336154703874, 6325.778597749057, 6335.461485773761, 6341.017142342944, 6341.682915854281, 6056.939430605637, 6109.586862300737, 6234.844128896814, 6245.5628106032145, 6253.8749225014335, 6236.189295391751, 6276.560640755103, 6294.244690637152, 6295.842603934755, 6312.242530896322, 6090.725917333642, 6132.509399248139, 6143.877122457615, 6144.047672610753, 6161.630302181883, 5982.521398288213, 6036.486898613667, 6136.6011209271255, 6159.507239420746, 6164.630122192682, 5764.452377024176, 6059.190418209483, 6088.13903791261, 6089.391060894052, 6104.328523862283, 6182.319944806158, 6189.257289125334, 6190.783322583688, 6194.752209911733, 6204.441600255998, 5745.339285981999, 5829.912551311649, 6018.157457955977, 6021.157845909854, 6113.2969708482315], "value": 5718.592489484871, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.83012299374553, 481.51607634327496, 510.0487590643451, 523.2151772341489, 541.320642593545, 31105.688943110563, 34064.0, 42093.368599470996, 42173.78913105438, 42175.0, 36383.63616363836, 36573.90278291649, 43755.68732938009, 43875.0, 43882.56117438825, 8680.0, 9713.46575938323, 9897.50512474377, 9898.406095634286, 9900.50497475127, 8637.827243455127, 8898.644054237822, 8906.376553641248, 8906.59920303588, 11774.0, 11837.526498940031, 12034.81947770784, 12045.09661775366, 12065.577704780335, 12089.697757556043, 11894.82157767634, 11977.041836653047, 11983.04135669153, 11994.520219191221, 11995.64013079609, 11835.408229588573, 11916.582919597773, 11920.88079119215, 11922.403879805976, 11926.403679815974, 40068.59931400678, 40512.594874051174, 40527.0, 40568.594314056776, 40617.0, 3388.7119594834467, 3424.7260219182403, 3580.4092324766384, 3958.9999999999714, 4353.1293741251875, 28454.28864278389, 31309.217269568217, 31707.890223842056, 32578.511322330185, 33071.50392744111, 3181.1569933967517, 3219.4205043092234, 3283.688049635281, 3354.0608629583758, 3367.8316084195694, 498477.0, 500335.0, 502515.0, 503145.0, 512207.43896280654, 61553.0, 61569.38430615637, 61759.0, 61832.38167618266, 61938.69030654818, 66121.0, 66142.33857661362, 66216.33783662198, 66301.0, 66366.66816665983, 16525.6694866102, 16727.16364181786, 16736.83263167377, 16739.0, 16754.4135955241, 12871.678208044686, 12950.222986620833, 12955.157914735511, 12957.676058098623, 12957.740845183233, 15755.44855930037, 16082.0, 16219.026858388534, 17784.02187879679, 18152.364667236583, 5994.0, 6327.778527751599, 6418.775342862978, 6460.483161347081, 6468.870622587522, 911.8267529169436, 951.871497347863, 988.9208863290919, 1032.6592224565875, 1039.8336266197373, 29455.11634650964, 29560.704392956224, 30332.393352132833, 30351.544726828673, 30444.69555304463, 1019.9541020654088, 1061.946902654864, 1071.314358810362, 1073.683263437288, 1106.7675788084432, 15865.365385384686, 16469.0, 16920.661586768194, 17208.569785755204, 17667.469975900487, 551198.0, 559819.4063207768, 563462.0, 701829.0, 704364.0, 52885.7355713219, 54740.726296368266, 62135.0, 62695.37304626896, 63061.68469157625, 1411.8235220597426, 1417.4613646814144, 1452.9491467798787, 1459.4672944375438, 1463.317185236986, 31320.843395782875, 31913.20216994501, 32006.51990220195, 32655.0, 32893.67106328906, 54188.72905635447, 54955.175672365745, 55003.0, 55142.44857551373, 55181.724091379285, 663763.0, 671745.0, 672037.0, 887836.0, 889464.0, 45170.77414612906, 45482.0, 45969.54030459653, 46113.76943115263, 46175.76912115418, 48841.75579122224, 49091.01764231762, 49106.0, 49878.50121498739, 50036.0, 39807.601923980386, 40897.79551102345, 41335.586644133175, 41521.79239103785, 42311.576884230766, 39918.60081399265, 40572.3914141294, 40641.01667482577, 41372.793136034124, 41787.37318940279, 39420.60579394284, 39833.80498585047, 40229.1954160921, 40277.39583906185, 40322.1935561293, 31318.0, 31518.684813151576, 32906.0, 32961.83519082389, 33091.0, 25190.24429267125, 25789.742102578733, 25924.74075259299, 26230.606540902285, 26432.0], "value": 21049.2998398842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.27615108685353, 355.2397868561281, 372.9813509324533, 382.6556099510441, 403.8081911092232, 14018.883107665692, 15084.77372839407, 20514.28200013, 20534.794652053475, 21415.57168856626, 18382.54043648906, 18671.906640466812, 20638.277660281892, 20655.380338589865, 20908.0, 5828.300603927534, 6070.514358851281, 6105.725242364104, 6138.14066030756, 6147.354527774586, 3560.786352818839, 4517.796699148546, 5964.552658550604, 6087.512998960095, 6698.832529186785, 6711.664416779166, 6734.191896972346, 6735.292794256606, 6736.124303840518, 6736.821056315125, 6375.10748495211, 6544.083828264047, 6590.769323073718, 6605.801825945229, 6737.865242695168, 5552.91670624941, 5712.8000519982015, 5713.400092990218, 5719.14212868069, 5727.083666613322, 20357.694634580494, 26224.73775262242, 26300.605490917653, 26505.602415963775, 26664.86667566669, 2327.8486898351553, 2792.6509186351705, 2989.491786396318, 3114.2681469854565, 3247.25313177968, 5099.33708617881, 5518.420565840609, 14245.789107925839, 14679.339429725582, 15587.376504939788, 1804.3955274982766, 1834.28462899468, 1849.9075046247633, 1937.8255956963942, 1940.7865134835156, 123690.0, 126946.0, 128432.0, 130388.6961130386, 131241.0, 10429.322094063862, 10631.574737010587, 11542.59600913964, 11572.305661660328, 11744.647660570197, 12897.419616117297, 13514.32428378577, 13553.254570998493, 13595.184288942695, 13598.728025439435, 6323.336049714806, 6733.831654208684, 7711.190325015905, 7801.180876008051, 8084.1107478177355, 7921.326687231534, 8000.87987681725, 8713.390062695677, 8754.037055923844, 8782.0, 4340.0, 4463.152001119842, 4485.0, 4559.635229181657, 4561.7262964222255, 1654.346533119411, 1773.343862770783, 1867.3837633580886, 1912.1490936533387, 1924.0278714317656, 776.5884081436848, 779.3803925878826, 819.700809204648, 873.5151990645253, 874.3486102853415, 20516.692249616266, 21320.0, 21445.57108857814, 21528.246511372028, 21626.675599866016, 910.1626503616689, 921.6313474610075, 929.5073610986188, 996.9003099690118, 1048.16670746755, 10379.948100259602, 10701.357918524913, 10977.83533247002, 11324.207305488537, 11335.319880807177, 552362.0, 575189.0, 581032.0, 594316.0, 594940.0, 81027.18972810314, 83728.16271837325, 128597.0, 132163.0, 139691.0, 972.7568107973007, 994.418265314799, 1012.731626119079, 1015.5785349080049, 1018.6027449294781, 18907.338243161423, 19505.804941950966, 19638.60722785508, 19673.704894426883, 19683.31108411199, 39981.804185038185, 49910.25314113989, 54921.626959326335, 55822.720886395306, 59119.70440147772, 86661.56669216866, 87045.0, 87893.56053219693, 88607.55696221477, 88657.0, 8125.431219814556, 8139.83720325602, 8190.467619604707, 8241.50550966944, 8363.121872203574, 12178.0, 12984.091113621957, 13026.739465210832, 13232.272225027717, 13384.0, 11138.721531961766, 11212.943935280271, 11369.31784092957, 11377.829332559853, 11558.479868405773, 6331.5251356147555, 11139.554417823196, 11158.107351411705, 11218.382988935688, 11524.884751152382, 11434.256773309708, 11453.083753299883, 11575.942120289345, 11692.94153529227, 11693.298402095901, 7907.8813817793925, 8145.592720364077, 8210.83578328442, 8365.372597055124, 8365.581720913808, 9982.301238913205, 10973.396463194282, 11166.441677916235, 11318.434078296217, 11796.52813887435], "value": 9595.920001399056, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.1552038618563, 1554.1685198418845, 1756.9560760980983, 1870.298638010746, 1906.2660875562913, 8335.499870007803, 8916.063813299608, 8916.687915922945, 8917.777055573606, 8935.285177185817, 7831.608419579013, 8083.636236369362, 8444.873326900102, 8676.52279124648, 8731.25784308333, 2328.5459335429555, 2359.516299158677, 2359.8702071386065, 2361.8819059046978, 2364.645303204516, 2311.491471876193, 2319.396956791238, 2330.8718020508863, 2355.740868504463, 2355.9411014724596, 2029.6853987631866, 2320.640300753386, 2340.9648855267183, 2349.6240601503764, 2356.1282325539573, 2057.8887400803546, 2254.7068881045525, 2311.179531266401, 2319.211468100837, 2319.860808351496, 2238.552289542098, 2267.2744721689064, 2270.7842754938256, 2274.3404412720292, 2274.8066414354635, 8687.695930642398, 8898.37711360205, 8901.910980890172, 8922.821543569156, 8938.776530586721, 5175.741212939338, 5339.946600533984, 5341.786328546854, 5344.4922732340365, 5349.7057661828585, 3419.5212670226188, 3507.0, 3652.6712595866234, 3663.4687970244167, 3664.13892735207, 1075.4138994248153, 1088.8475613414128, 1092.9508172132194, 1109.389835590422, 1114.849495318138, 11488.310701357861, 11649.883501164964, 11657.067434605297, 11672.35802030888, 11688.41557922109, 4475.955240447619, 4571.702839315434, 4711.539422778949, 4747.192977193886, 4749.453812811502, 9866.901330986599, 9877.308588398886, 9883.505824708736, 9894.356866803635, 9895.901040989642, 5308.708021058802, 5310.123829568078, 5310.548603368757, 5311.867203319871, 5324.600654950894, 4148.377743338523, 4384.430024096847, 4518.774061296922, 4862.708237505761, 4878.658493905464, 3328.7836290641517, 3418.8119653418803, 3911.3155197840265, 4000.8399664013696, 4025.677945764332, 1259.2759163481035, 1274.6877015131167, 1278.181963543333, 1289.013904363163, 1294.7410517896458, 824.8432797768405, 898.1063841477641, 904.2585080234277, 937.2595649436869, 968.6173961285393, 5784.797532086357, 5941.405859414111, 6023.66869822164, 6048.364921683248, 6145.517282408184, 419.8866306097337, 433.83947939262674, 434.54372908446004, 434.93041113422333, 439.26861775144664, 3557.7153827693724, 3924.234774219057, 3957.248122856648, 4069.369247766601, 4080.3063479208604, 9958.651447199314, 10612.363258204532, 10700.678979630624, 10702.357858528514, 10723.83914241287, 10552.0, 10568.207384446197, 10617.946910265398, 10700.0, 10757.569697212024, 8035.116137224901, 8144.226298501633, 8216.589170541449, 8228.753137405887, 8231.382646301552, 4979.502049794992, 5105.463926287686, 5698.0, 5804.448577385142, 5899.410058994066, 9922.801543969224, 9953.153981911619, 10006.199504039805, 10020.24848136378, 10028.247881408777, 8434.70478533273, 9218.21645160169, 9563.330566860532, 10058.698239052841, 10195.490225488844, 6874.6562671867205, 7085.256048114874, 7487.6630551626395, 7518.473706840468, 7561.168271490022, 8229.382796290422, 8285.544295063588, 8301.086880443027, 8304.169583041648, 8321.95839020801, 7325.926740732738, 7371.299726525972, 7508.58702771331, 7650.885236721565, 7664.961675191588, 7266.200717920919, 7435.033445652247, 7447.031885854804, 7468.365188958782, 7484.850302994018, 6929.480288978246, 7085.326893945067, 7089.149302083577, 7092.929070709227, 7103.360697537247, 4902.975485122552, 5065.39215294167, 5084.435472299309, 5097.847064588068, 5103.234514822806, 4101.548829628799, 4421.226285399978, 5043.193089105872, 5247.317848679647, 5271.868203294948], "value": 4459.538041881255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.9228585281489, 289.2883506573828, 306.2008158705778, 309.49706726569326, 312.5842629303027, 4589.931151032729, 5215.86960325992, 5385.3268341457315, 5430.429804870486, 5558.805441809543, 4338.717983331081, 5187.910538786859, 5420.159875219348, 5422.864428389292, 5443.346798384189, 4133.689973252003, 4195.832166713328, 4197.3913782501595, 4199.5511548515715, 4200.222958752641, 3848.3644451108307, 4040.898977525571, 4193.287141186006, 4205.915881682365, 4214.831406743727, 3421.384150852858, 4063.634272915437, 4083.305838007546, 4084.4690190275223, 4111.876643700693, 3423.623401425841, 3897.3179693553516, 4014.7353583621357, 4051.0757390239696, 4077.7359018704296, 3542.6280240574624, 3564.483149943244, 3572.464130380438, 3575.8212089395424, 3595.7842529448053, 4971.701697898102, 5259.3951695555115, 5311.114554333233, 5375.489328513785, 5393.056215162331, 4610.608098311647, 4638.5593368629925, 4644.907101857978, 4662.696924699883, 4663.440387153563, 5665.801696940587, 5668.31980162379, 5673.517750991171, 5697.658140511582, 5714.942850571482, 3015.894443694482, 3805.040404191843, 3852.749571277858, 3856.245408339199, 3862.7682339059465, 4951.801927922878, 5439.374471935738, 5496.928099020693, 6002.879942401171, 6024.463761740772, 3320.833958302075, 3928.607139286106, 3935.685145188378, 3937.4881265435884, 3948.565657777642, 3818.045488627843, 3985.242803867256, 3987.322155233617, 4050.2911990401562, 4073.3889916512167, 3715.6437900166784, 4148.522919864255, 4217.451731274915, 4687.7656117194, 4725.196716558194, 4575.163398692765, 5470.890582188334, 5636.774529018793, 5761.942380576224, 5790.449907258803, 1196.36592605919, 1210.6186551236333, 1232.8643849176583, 1293.3727142335958, 1358.7825947848296, 905.2712566384124, 928.2944961829058, 970.8495183246608, 978.554757585297, 980.6959842448864, 5196.246544251062, 5235.2776556190565, 5248.6588371755715, 5304.416514183436, 5310.734463276821, 5786.508146807484, 5794.391588883191, 5797.391273916262, 5800.245968024215, 5814.476697097281, 3906.8632597858937, 4296.572436573326, 5210.869728256824, 5240.6069544784295, 5302.761375738101, 2461.163204510475, 2806.936901377486, 3044.2845931206148, 3049.35963447674, 3059.2409364615437, 5474.686075341969, 5739.799107031234, 6108.0, 6124.479419249325, 6126.540509461808, 5457.2905522282645, 5779.393163717749, 5918.615290006136, 5964.284285885635, 5981.820545383645, 5349.304590403222, 5525.530329921921, 5530.806421775219, 5952.987992041363, 6045.3652366501765, 5111.412187598325, 5531.280933478622, 5593.692346920959, 5851.327097383855, 5857.0, 5704.885902282014, 5766.481016708516, 5790.449907258803, 5790.768369265183, 5791.424774428155, 5022.899542009212, 5299.986005317955, 5717.571182161271, 6066.514678825595, 6108.786192483241, 3772.226693527875, 3882.6699730523223, 3896.6298201670797, 3905.1882644495918, 3907.47249121365, 4687.742174180453, 5799.652020878761, 5852.502537284208, 5863.0, 5895.970520147372, 5536.7231638417115, 5576.912502062059, 5666.716664166857, 5669.262995810518, 5693.829185124453, 4646.049758403371, 5663.5469162467725, 5675.921574900755, 5678.006348889011, 5700.059490184073, 4503.166914120898, 5280.287161233185, 5535.4187810280955, 5545.972270138624, 5619.80330688424, 5669.489745922887, 5678.574106941914, 5690.687012214368, 5691.146328050825, 5699.0311647020935, 5510.586705997146, 5517.78569252053, 5577.386487486292, 5581.6371935824045, 5598.35618903815], "value": 4133.514435060117, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.9657017149142, 771.506236008954, 780.3055280800089, 799.668137722845, 816.3306089007015, 14593.416263349467, 15418.30617622207, 16117.597060073489, 16124.596885077879, 16368.83631163688, 14379.424823007093, 14773.113613183217, 14780.408783648641, 14795.704085918274, 14796.482123125692, 700.2822107339966, 849.4987957105291, 873.1792115411492, 875.2297977779546, 875.6059773102098, 710.783211120608, 834.0533494483774, 852.1861622150863, 859.3340161374922, 863.3352318714586, 918.3892711346946, 920.4523308631353, 921.6451666108529, 922.1055576091181, 922.4419226368018, 722.4509372876652, 916.1434059154645, 916.1479823763897, 916.3448134583738, 921.0237148622482, 550.7521615273102, 555.5111501878321, 555.6082961512125, 556.0103016630404, 652.774792696519, 14573.927130364385, 14693.485727999469, 14695.632609184855, 14707.852921470861, 14713.117212967256, 12172.0, 12764.87235127646, 13540.729185416336, 13772.517961871286, 14041.859581404158, 12146.878531214663, 12281.140320177592, 12412.75174496514, 12688.873111268862, 12741.490340386374, 4476.798544065525, 4479.193745125876, 4512.932306015412, 4663.860084197479, 4674.929876051862, 120099.0, 122091.0, 123157.68948086775, 123524.38237808843, 123907.0, 8346.374021948379, 10687.946560267148, 10788.46057697112, 10856.2400631955, 11360.829587556193, 20928.0, 21267.78732212689, 21286.680699789515, 21294.935253237276, 21300.147994080373, 7571.3564347030015, 7607.35337496319, 7618.542887426772, 7734.574598397142, 7758.650860711282, 3766.2844059628583, 4288.592583704543, 4385.320275357325, 4426.557344265612, 4522.389477420506, 3539.2036791721657, 3669.119411341312, 3678.9448158277646, 3690.630936906288, 3691.077230692327, 573.7131434282859, 668.5520701130255, 671.1241829412565, 687.6183718036538, 689.407109885498, 567.9545636349083, 570.9914351284734, 613.923259592551, 621.9346968568326, 629.3297638015499, 8656.697015604424, 8989.146031127033, 9197.126273004054, 9242.72271831846, 9499.715008549754, 601.978930737422, 603.9486643635253, 624.1979056911847, 626.8213559135625, 631.8041407163794, 4270.48754149504, 4422.226110430662, 4436.134953684001, 4451.5103338632725, 4473.821047158077, 102400.0, 126573.0, 156998.21500892422, 162274.3772562283, 162955.0, 135032.32483837716, 137841.68946665313, 138144.7834847434, 138444.0, 144508.83236751458, 120782.396088019, 122330.0, 122364.0, 123406.0, 124217.9999999982, 8597.312215022908, 8606.741797746077, 8644.394892357475, 8656.48061116335, 8824.117588241124, 63174.68412658091, 65773.67113164596, 65922.0, 67408.66295668687, 67930.0, 73144.0, 73769.6311518439, 82653.17346826455, 82792.0, 100005.0, 13606.183628982293, 13654.658633533843, 13781.173129612256, 14207.360668769723, 14242.145471271757, 15587.688246235239, 15783.605409864846, 16025.038497690175, 16262.349506019627, 16264.186790660657, 13131.75248351405, 13255.734885302432, 14317.928410358298, 14503.1298122113, 14978.251087445802, 12206.206596571194, 13055.869441305465, 13435.261060641367, 14127.788083178966, 14309.35607897668, 13723.656908577366, 14060.367283472473, 14469.49356772508, 14480.927595361956, 14602.707945840812, 9526.952365238129, 11444.542218311508, 11452.198346116025, 11458.82811757841, 11847.280988497021, 8739.56302184901, 9673.081057299545, 11163.832542512031, 11711.414429278331, 11741.17811753169], "value": 7848.78968991987, "unit": "score"}], "name": "dromaeo_css", "value": 7421.8526286697315, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 166.0, 150.0, 114.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 109.0, 160.0, 109.0, 112.0, 156.0, 121.0, 141.0, 108.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 184.0, 186.0, 184.0, 184.0, 185.0, 183.0, 187.0, 191.0, 255.0], "value": 193.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 58.0, 109.0, 57.0, 57.0, 62.0, 65.0, 83.0, 57.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 67.0, 92.0, 69.0, 78.0, 68.0, 95.0, 70.0, 68.0, 97.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 87.0, 89.0, 86.0, 88.0, 86.0, 89.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 86.0, 80.0, 83.0, 85.0, 87.0, 81.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 53.0, 53.0, 53.0, 52.0, 52.0, 54.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 77.0, 79.0, 73.0, 70.0, 71.0, 70.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 82.0, 81.0, 82.0, 93.0, 98.0, 79.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 141.0, 149.0, 150.0, 134.0, 136.0, 138.0, 138.0, 137.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 163.0, 213.0, 186.0, 163.0, 165.0, 163.0, 164.0, 159.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 63.0, 59.0, 70.0, 76.0, 83.0, 81.0, 81.0], "value": 71.3, "unit": "score"}], "name": "kraken", "value": 1449.3, "alertThreshold": 2.0}]} 12:28:05 INFO - Return code: 0 12:28:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:28:05 INFO - # TBPL SUCCESS # 12:28:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:28:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:28:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:28:05 INFO - 2016-08-05 12:28:05.098 screenresolution[1682:9965] starting screenresolution argv=screenresolution get 12:28:05 INFO - 2016-08-05 12:28:05.101 screenresolution[1682:9965] Display 0: 1600x1200x32@60 12:28:05 INFO - 2016-08-05 12:28:05.107 screenresolution[1683:9968] starting screenresolution argv=screenresolution list 12:28:05 INFO - Available Modes on Display 0 12:28:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:28:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:28:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:28:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:28:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:28:05 INFO - Intel Iris: 12:28:05 INFO - Chipset Model: Intel Iris 12:28:05 INFO - Type: GPU 12:28:05 INFO - Bus: Built-In 12:28:05 INFO - VRAM (Dynamic, Max): 1536 MB 12:28:05 INFO - Vendor: Intel (0x8086) 12:28:05 INFO - Device ID: 0x0a2e 12:28:05 INFO - Revision ID: 0x0009 12:28:05 INFO - Displays: 12:28:05 INFO - Display: 12:28:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:28:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:28:05 INFO - Display Serial Number: 1600x1200 60 12:28:05 INFO - Main Display: Yes 12:28:05 INFO - Mirror: Off 12:28:05 INFO - Online: Yes 12:28:05 INFO - Rotation: Supported 12:28:05 INFO - Return code: 0 12:28:05 INFO - Running post-action listener: _resource_record_post_action 12:28:05 INFO - [mozharness: 2016-08-05 19:28:05.346665Z] Finished run-tests step (success) 12:28:05 INFO - Running post-run listener: _resource_record_post_run 12:28:05 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 36992000; Write bytes: 705656832; Read time: 245; Write time: 2014 12:28:05 INFO - TinderboxPrint: CPU usage
53.5% 12:28:05 INFO - TinderboxPrint: I/O read bytes / time
36,992,000 / 245 12:28:05 INFO - TinderboxPrint: I/O write bytes / time
705,656,832 / 2,014 12:28:05 INFO - TinderboxPrint: CPU idle
2,258.2 (46.5%) 12:28:05 INFO - TinderboxPrint: CPU system
969.4 (20.0%) 12:28:05 INFO - TinderboxPrint: CPU user
1,626.0 (33.5%) 12:28:05 INFO - TinderboxPrint: Swap in / out
440,770,560 / 0 12:28:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388592128; Write bytes: 378097664; Read time: 29050; Write time: 834 12:28:05 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30813184; Write bytes: 298096640; Read time: 181; Write time: 1113 12:28:05 INFO - Running post-run listener: _upload_blobber_files 12:28:05 INFO - Blob upload gear active. 12:28:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:28: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 12:28: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'] 12:28: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 12:28:06 INFO - (blobuploader) - INFO - Open directory for files ... 12:28:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:28:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:28:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:06 INFO - (blobuploader) - INFO - Done attempting. 12:28:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:28:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:28:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:07 INFO - (blobuploader) - INFO - Done attempting. 12:28:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:28:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:28:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:11 INFO - (blobuploader) - INFO - Done attempting. 12:28:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:28:11 INFO - Return code: 0 12:28:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:28:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:28:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b7d55f33ed2ddb84ba0a6b2636e32cacef573a8d42c97f8d9c4147b139ae8bbea25b8b7c2c0f2fe678aa34d83b90a5b4b5dbf4c7c4e8758f268d14d886855711", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/db8a35fb26e9b58192ae4b9aa338d9121321b152e4ac7941e9bb660a743b640f3641f18599aa5b476ae51b6a279d7ddfe0bca5e77ff6585ffde1e13a171be7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/423a7d6135631d03f9d2308363fdfd31f2afed6a66dc1b518ce63934ecf76897dd3a398a6884d49cf03593e6b891a078f588910bb6809391aef0272a1e0cb46e"} 12:28:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:28:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:28:11 INFO - Contents: 12:28:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b7d55f33ed2ddb84ba0a6b2636e32cacef573a8d42c97f8d9c4147b139ae8bbea25b8b7c2c0f2fe678aa34d83b90a5b4b5dbf4c7c4e8758f268d14d886855711", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/db8a35fb26e9b58192ae4b9aa338d9121321b152e4ac7941e9bb660a743b640f3641f18599aa5b476ae51b6a279d7ddfe0bca5e77ff6585ffde1e13a171be7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/423a7d6135631d03f9d2308363fdfd31f2afed6a66dc1b518ce63934ecf76897dd3a398a6884d49cf03593e6b891a078f588910bb6809391aef0272a1e0cb46e"} 12:28:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:28:11 INFO - Copying logs to upload dir... 12:28:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:28:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.030227 ========= master_lag: 2.57 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-08-05 12:28:14.337831) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 12:28:14.345085) ========= 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.CjQXEjqxkB/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.q8rPsVY07R/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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/b7d55f33ed2ddb84ba0a6b2636e32cacef573a8d42c97f8d9c4147b139ae8bbea25b8b7c2c0f2fe678aa34d83b90a5b4b5dbf4c7c4e8758f268d14d886855711", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/db8a35fb26e9b58192ae4b9aa338d9121321b152e4ac7941e9bb660a743b640f3641f18599aa5b476ae51b6a279d7ddfe0bca5e77ff6585ffde1e13a171be7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/423a7d6135631d03f9d2308363fdfd31f2afed6a66dc1b518ce63934ecf76897dd3a398a6884d49cf03593e6b891a078f588910bb6809391aef0272a1e0cb46e"} build_url:https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/Qf4JRiSuSh6wiO_Iyo8CvA/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/b7d55f33ed2ddb84ba0a6b2636e32cacef573a8d42c97f8d9c4147b139ae8bbea25b8b7c2c0f2fe678aa34d83b90a5b4b5dbf4c7c4e8758f268d14d886855711", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/db8a35fb26e9b58192ae4b9aa338d9121321b152e4ac7941e9bb660a743b640f3641f18599aa5b476ae51b6a279d7ddfe0bca5e77ff6585ffde1e13a171be7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/423a7d6135631d03f9d2308363fdfd31f2afed6a66dc1b518ce63934ecf76897dd3a398a6884d49cf03593e6b891a078f588910bb6809391aef0272a1e0cb46e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 12:28:14.381031) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 12:28:14.381370) ========= 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.CjQXEjqxkB/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.q8rPsVY07R/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.005182 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 12:28:14.429392) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 12:28:14.429715) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 12:28:14.430048) ========= ========= Total master_lag: 2.71 =========