builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0285 starttime: 1471547539.39 results: success (0) buildid: 20160818093455 builduid: 339251aafcd84ee4b7deb657e0189b9c revision: 8a70c7469c5025ec9d644c3eb82f60f5982c1edb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.387559) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.388041) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.388318) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.412544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.412956) ========= 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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-18 12:12:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.12M=0.001s 2016-08-18 12:12:19 (8.12 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.565094) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.565669) ========= 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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.020055 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.615622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:19.615982) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8a70c7469c5025ec9d644c3eb82f60f5982c1edb --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 8a70c7469c5025ec9d644c3eb82f60f5982c1edb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-18 12:12:19,698 truncating revision to first 12 chars 2016-08-18 12:12:19,698 Setting DEBUG logging. 2016-08-18 12:12:19,698 attempt 1/10 2016-08-18 12:12:19,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8a70c7469c50?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 12:12:20,239 unpacking tar archive at: autoland-8a70c7469c50/testing/mozharness/ program finished with exit code 0 elapsedTime=0.836362 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:20.475127) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:20.475507) ========= script_repo_revision: 8a70c7469c5025ec9d644c3eb82f60f5982c1edb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:20.475947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:20.476208) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 12:12:20.495080) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-18 12:12:20.495509) ========= /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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12:20 INFO - MultiFileLogger online at 20160818 12:12:20 in /builds/slave/test 12:12:20 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:12:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:12:20 INFO - {'append_to_log': False, 12:12:20 INFO - 'base_work_dir': '/builds/slave/test', 12:12:20 INFO - 'blob_upload_branch': 'Autoland', 12:12:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:12:20 INFO - 'branch': 'Autoland', 12:12:20 INFO - 'buildbot_json_path': 'buildprops.json', 12:12:20 INFO - 'config_files': ('talos/mac_config.py',), 12:12:20 INFO - 'default_actions': ('clobber', 12:12:20 INFO - 'read-buildbot-config', 12:12:20 INFO - 'download-and-extract', 12:12:20 INFO - 'populate-webroot', 12:12:20 INFO - 'create-virtualenv', 12:12:20 INFO - 'install', 12:12:20 INFO - 'run-tests'), 12:12:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:12:20 INFO - 'download_minidump_stackwalk': True, 12:12:20 INFO - 'download_symbols': 'ondemand', 12:12:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:12:20 INFO - 'tooltool.py': '/tools/tooltool.py', 12:12:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:12:20 INFO - '/tools/misc-python/virtualenv.py')}, 12:12:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:12:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:12:20 INFO - 'installer_path': 'installer.exe', 12:12:20 INFO - 'log_level': 'info', 12:12:20 INFO - 'log_name': 'talos', 12:12:20 INFO - 'log_to_console': True, 12:12:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:12:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:12:20 INFO - 'opt_config_files': (), 12:12:20 INFO - 'pip_index': False, 12:12:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:20 INFO - 'cmd': ('bash', 12:12:20 INFO - '-c', 12:12:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:20 INFO - 'enabled': True, 12:12:20 INFO - 'halt_on_failure': False, 12:12:20 INFO - 'name': 'check_screen_resolution'},), 12:12:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:20 INFO - 'cmd': ('bash', 12:12:20 INFO - '-c', 12:12:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:20 INFO - 'enabled': True, 12:12:20 INFO - 'halt_on_failure': False, 12:12:20 INFO - 'name': 'check_screen_resolution'},), 12:12:20 INFO - 'run_cmd_checks_enabled': True, 12:12:20 INFO - 'sps_profile': False, 12:12:20 INFO - 'sps_profile_interval': 0, 12:12:20 INFO - 'suite': 'dromaeojs', 12:12:20 INFO - 'system_bits': '32', 12:12:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:12:20 INFO - 'title': 't-yosemite-r7-0285', 12:12:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:12:20 INFO - 'use_talos_json': True, 12:12:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:12:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:12:20 INFO - 'work_dir': 'build'} 12:12:20 INFO - [mozharness: 2016-08-18 19:12:20.687960Z] Running clobber step. 12:12:20 INFO - Running pre-action listener: _resource_record_pre_action 12:12:20 INFO - Running main action method: clobber 12:12:20 INFO - rmtree: /builds/slave/test/build 12:12:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:12:22 INFO - Running post-action listener: _resource_record_post_action 12:12:22 INFO - [mozharness: 2016-08-18 19:12:22.174585Z] Finished clobber step (success) 12:12:22 INFO - [mozharness: 2016-08-18 19:12:22.174825Z] Running read-buildbot-config step. 12:12:22 INFO - Running pre-action listener: _resource_record_pre_action 12:12:22 INFO - Running main action method: read_buildbot_config 12:12:22 INFO - Using buildbot properties: 12:12:22 INFO - { 12:12:22 INFO - "project": "", 12:12:22 INFO - "product": "firefox", 12:12:22 INFO - "script_repo_revision": "production", 12:12:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 12:12:22 INFO - "repository": "", 12:12:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 12:12:22 INFO - "buildid": "20160818093455", 12:12:22 INFO - "pgo_build": "False", 12:12:22 INFO - "basedir": "/builds/slave/test", 12:12:22 INFO - "buildnumber": 175, 12:12:22 INFO - "slavename": "t-yosemite-r7-0285", 12:12:22 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:12:22 INFO - "platform": "yosemite_r7", 12:12:22 INFO - "scheduler": "tests-autoland-macosx64-talos", 12:12:22 INFO - "branch": "autoland", 12:12:22 INFO - "repo_path": "integration/autoland", 12:12:22 INFO - "revision": "8a70c7469c5025ec9d644c3eb82f60f5982c1edb", 12:12:22 INFO - "stage_platform": "macosx64", 12:12:22 INFO - "builduid": "339251aafcd84ee4b7deb657e0189b9c", 12:12:22 INFO - "slavebuilddir": "test" 12:12:22 INFO - } 12:12:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:12:22 INFO - Running post-action listener: _resource_record_post_action 12:12:22 INFO - [mozharness: 2016-08-18 19:12:22.180162Z] Finished read-buildbot-config step (success) 12:12:22 INFO - [mozharness: 2016-08-18 19:12:22.180369Z] Running download-and-extract step. 12:12:22 INFO - Running pre-action listener: _resource_record_pre_action 12:12:22 INFO - Running main action method: download_and_extract 12:12:22 INFO - mkdir: /builds/slave/test/build/tests 12:12:22 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:12:22 INFO - trying https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:12:22 INFO - Downloading https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:23 INFO - Downloaded 1199 bytes. 12:12:23 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:12:23 INFO - Using the following test package requirements: 12:12:23 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:12:23 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:12:23 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:12:23 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'jsshell-mac.zip'], 12:12:23 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:12:23 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:12:23 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:12:23 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:12:23 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:12:23 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:12:23 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:12:23 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:23 INFO - trying https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:12:23 INFO - Downloading https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:24 INFO - Downloaded 16339515 bytes. 12:12:24 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:12:30 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:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:30 INFO - trying https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:12:30 INFO - Downloading https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:31 INFO - Downloaded 16339515 bytes. 12:12:31 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:12:38 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:12:38 INFO - trying https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:12:38 INFO - Downloading https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:39 INFO - Downloaded 14413981 bytes. 12:12:39 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:12:39 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:12:39 INFO - trying https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:12:39 INFO - Downloading https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:12:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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:12:42 INFO - Downloaded 111273765 bytes. 12:12:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:12:42 INFO - mkdir: /builds/slave/test/properties 12:12:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:12:42 INFO - Writing to file /builds/slave/test/properties/build_url 12:12:42 INFO - Contents: 12:12:42 INFO - build_url:https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:12:43 INFO - Running post-action listener: _resource_record_post_action 12:12:43 INFO - Running post-action listener: set_extra_try_arguments 12:12:43 INFO - [mozharness: 2016-08-18 19:12:43.439014Z] Finished download-and-extract step (success) 12:12:43 INFO - [mozharness: 2016-08-18 19:12:43.439156Z] Running populate-webroot step. 12:12:43 INFO - Running pre-action listener: _resource_record_pre_action 12:12:43 INFO - Running main action method: populate_webroot 12:12:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:12:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:12:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:12:43 INFO - '--tppagecycles', 12:12:43 INFO - '7'], 12:12:43 INFO - 'tests': ['tsvgm']}}, 12:12:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:12:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:12:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:12:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:12:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:12:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['damp', 'tps']}, 12:12:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'tests': ['damp', 'tps']}, 12:12:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['dromaeo_dom']}, 12:12:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:12:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['basic_compositor_video']}, 12:12:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:12:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['a11yr', 12:12:43 INFO - 'ts_paint', 12:12:43 INFO - 'tpaint', 12:12:43 INFO - 'sessionrestore', 12:12:43 INFO - 'sessionrestore_no_auto_restore', 12:12:43 INFO - 'tabpaint']}, 12:12:43 INFO - 'other-e10s': {'tests': ['a11yr', 12:12:43 INFO - 'ts_paint', 12:12:43 INFO - 'tpaint', 12:12:43 INFO - 'sessionrestore', 12:12:43 INFO - 'sessionrestore_no_auto_restore', 12:12:43 INFO - 'tabpaint']}, 12:12:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:12:43 INFO - 'ts_paint', 12:12:43 INFO - 'tpaint', 12:12:43 INFO - 'sessionrestore', 12:12:43 INFO - 'sessionrestore_no_auto_restore', 12:12:43 INFO - 'tabpaint']}, 12:12:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:12:43 INFO - 'ts_paint', 12:12:43 INFO - 'tpaint', 12:12:43 INFO - 'sessionrestore', 12:12:43 INFO - 'sessionrestore_no_auto_restore', 12:12:43 INFO - 'tabpaint']}, 12:12:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['a11yr', 12:12:43 INFO - 'ts_paint', 12:12:43 INFO - 'tpaint', 12:12:43 INFO - 'sessionrestore', 12:12:43 INFO - 'sessionrestore_no_auto_restore', 12:12:43 INFO - 'tabpaint']}, 12:12:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['a11yr', 12:12:43 INFO - 'ts_paint', 12:12:43 INFO - 'tpaint', 12:12:43 INFO - 'sessionrestore', 12:12:43 INFO - 'sessionrestore_no_auto_restore', 12:12:43 INFO - 'tabpaint']}, 12:12:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['tsvgx', 12:12:43 INFO - 'tsvgr_opacity', 12:12:43 INFO - 'tart', 12:12:43 INFO - 'tscrollx', 12:12:43 INFO - 'cart']}, 12:12:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:12:43 INFO - 'tsvgr_opacity', 12:12:43 INFO - 'tart', 12:12:43 INFO - 'tscrollx', 12:12:43 INFO - 'cart']}, 12:12:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'talos_options': ['--disable-e10s'], 12:12:43 INFO - 'tests': ['tp5o']}, 12:12:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'tests': ['tp5o']}, 12:12:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'talos_options': ['--disable-e10s', 12:12:43 INFO - '--xperf_path', 12:12:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:43 INFO - 'tests': ['tp5n']}, 12:12:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:12:43 INFO - 'talos_options': ['--xperf_path', 12:12:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:12:43 INFO - 'tests': ['tp5n']}}, 12:12:43 INFO - 'talos.zip': {'path': '', 12:12:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:12:43 INFO - Running post-action listener: _resource_record_post_action 12:12:43 INFO - [mozharness: 2016-08-18 19:12:43.457922Z] Finished populate-webroot step (success) 12:12:43 INFO - [mozharness: 2016-08-18 19:12:43.458054Z] Running create-virtualenv step. 12:12:43 INFO - Running pre-action listener: _resource_record_pre_action 12:12:43 INFO - Running main action method: create_virtualenv 12:12:43 INFO - Creating virtualenv /builds/slave/test/build/venv 12:12:43 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:12:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:12:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:12:43 INFO - Using real prefix '/tools/python27' 12:12:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:12:44 INFO - Installing distribute.............................................................................................................................................................................................done. 12:12:47 INFO - Installing pip.................done. 12:12:47 INFO - Return code: 0 12:12:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:47 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12:47 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:12:47 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:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:47 INFO - 'HOME': '/Users/cltbld', 12:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:47 INFO - 'LOGNAME': 'cltbld', 12:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:47 INFO - 'MOZ_NO_REMOTE': '1', 12:12:47 INFO - 'NO_EM_RESTART': '1', 12:12:47 INFO - 'PAGER': '/bin/cat', 12:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:47 INFO - 'PWD': '/builds/slave/test', 12:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:47 INFO - 'SHELL': '/bin/bash', 12:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:12:47 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:47 INFO - 'USER': 'cltbld', 12:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:47 INFO - 'XPC_FLAGS': '0x0', 12:12:47 INFO - 'XPC_SERVICE_NAME': '0', 12:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:47 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:12:47 INFO - Cleaning up... 12:12:47 INFO - Return code: 0 12:12:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:47 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12:47 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:12:47 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:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:47 INFO - 'HOME': '/Users/cltbld', 12:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:47 INFO - 'LOGNAME': 'cltbld', 12:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:47 INFO - 'MOZ_NO_REMOTE': '1', 12:12:47 INFO - 'NO_EM_RESTART': '1', 12:12:47 INFO - 'PAGER': '/bin/cat', 12:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:47 INFO - 'PWD': '/builds/slave/test', 12:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:47 INFO - 'SHELL': '/bin/bash', 12:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:12:47 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:47 INFO - 'USER': 'cltbld', 12:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:47 INFO - 'XPC_FLAGS': '0x0', 12:12:47 INFO - 'XPC_SERVICE_NAME': '0', 12:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:47 INFO - Downloading/unpacking psutil>=3.1.1 12:12:47 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:12:47 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:12:47 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:12:47 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:12:47 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:12:47 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:12:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:12:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:50 INFO - Installing collected packages: psutil 12:12:50 INFO - Running setup.py install for psutil 12:12:50 INFO - building 'psutil._psutil_osx' extension 12:12:50 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:12:50 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:12:50 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:12:50 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:12:50 INFO - building 'psutil._psutil_posix' extension 12:12:50 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:12:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:12:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:12:50 INFO - ^ 12:12:50 INFO - 1 warning generated. 12:12:50 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:12:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:51 INFO - Successfully installed psutil 12:12:51 INFO - Cleaning up... 12:12:51 INFO - Return code: 0 12:12:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:12: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:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:12: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 mozsystemmonitor==0.3 12:12:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:12:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:51 INFO - 'HOME': '/Users/cltbld', 12:12:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:51 INFO - 'LOGNAME': 'cltbld', 12:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:51 INFO - 'MOZ_NO_REMOTE': '1', 12:12:51 INFO - 'NO_EM_RESTART': '1', 12:12:51 INFO - 'PAGER': '/bin/cat', 12:12:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:51 INFO - 'PWD': '/builds/slave/test', 12:12:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:51 INFO - 'SHELL': '/bin/bash', 12:12:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:12:51 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:12:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:51 INFO - 'USER': 'cltbld', 12:12:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:51 INFO - 'XPC_FLAGS': '0x0', 12:12:51 INFO - 'XPC_SERVICE_NAME': '0', 12:12:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:12:51 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:12:51 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:12:51 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:12:51 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:12:51 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:12:51 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:12:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:12:54 INFO - Installing collected packages: mozsystemmonitor 12:12:54 INFO - Running setup.py install for mozsystemmonitor 12:12:54 INFO - Successfully installed mozsystemmonitor 12:12:54 INFO - Cleaning up... 12:12:54 INFO - Return code: 0 12:12:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:12: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:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:54 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12:54 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:12:54 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:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:54 INFO - 'HOME': '/Users/cltbld', 12:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:54 INFO - 'LOGNAME': 'cltbld', 12:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:54 INFO - 'MOZ_NO_REMOTE': '1', 12:12:54 INFO - 'NO_EM_RESTART': '1', 12:12:54 INFO - 'PAGER': '/bin/cat', 12:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:54 INFO - 'PWD': '/builds/slave/test', 12:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:54 INFO - 'SHELL': '/bin/bash', 12:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:12:54 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:54 INFO - 'USER': 'cltbld', 12:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:54 INFO - 'XPC_FLAGS': '0x0', 12:12:54 INFO - 'XPC_SERVICE_NAME': '0', 12:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:54 INFO - Downloading/unpacking jsonschema==2.5.1 12:12:54 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:12:54 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:12:54 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:12:54 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:12:54 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:12:54 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:12:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:12:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:12:56 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:12:56 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:12:56 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:12:56 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:12:56 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:12:56 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:12:57 INFO - Downloading functools32-3.2.3-2.tar.gz 12:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:12:57 INFO - warning: no files found matching '*.txt' 12:12:57 INFO - no previously-included directories found matching 'build' 12:12:57 INFO - no previously-included directories found matching 'dist' 12:12:57 INFO - no previously-included directories found matching '.git*' 12:12:57 INFO - Installing collected packages: jsonschema, functools32 12:12:57 INFO - Running setup.py install for functools32 12:12:57 INFO - warning: no files found matching '*.txt' 12:12:57 INFO - no previously-included directories found matching 'build' 12:12:57 INFO - no previously-included directories found matching 'dist' 12:12:57 INFO - no previously-included directories found matching '.git*' 12:12:57 INFO - Successfully installed jsonschema functools32 12:12:57 INFO - Cleaning up... 12:12:57 INFO - Return code: 0 12:12:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:57 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12:57 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:12:57 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:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:57 INFO - 'HOME': '/Users/cltbld', 12:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:57 INFO - 'LOGNAME': 'cltbld', 12:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:57 INFO - 'MOZ_NO_REMOTE': '1', 12:12:57 INFO - 'NO_EM_RESTART': '1', 12:12:57 INFO - 'PAGER': '/bin/cat', 12:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:57 INFO - 'PWD': '/builds/slave/test', 12:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:57 INFO - 'SHELL': '/bin/bash', 12:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:12:57 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:57 INFO - 'USER': 'cltbld', 12:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:57 INFO - 'XPC_FLAGS': '0x0', 12:12:57 INFO - 'XPC_SERVICE_NAME': '0', 12:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:12:57 INFO - Cleaning up... 12:12:57 INFO - Return code: 0 12:12:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:57 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:12:57 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:12:57 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:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:57 INFO - 'HOME': '/Users/cltbld', 12:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:57 INFO - 'LOGNAME': 'cltbld', 12:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:57 INFO - 'MOZ_NO_REMOTE': '1', 12:12:57 INFO - 'NO_EM_RESTART': '1', 12:12:57 INFO - 'PAGER': '/bin/cat', 12:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:57 INFO - 'PWD': '/builds/slave/test', 12:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:57 INFO - 'SHELL': '/bin/bash', 12:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:12:57 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:57 INFO - 'USER': 'cltbld', 12:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:57 INFO - 'XPC_FLAGS': '0x0', 12:12:57 INFO - 'XPC_SERVICE_NAME': '0', 12:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:58 INFO - Downloading/unpacking blobuploader==1.2.4 12:12:58 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:12:58 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:12:58 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:12:58 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:12:58 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:12:58 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:13:00 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:00 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:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:01 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:13:01 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:13:01 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:13:01 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:13:01 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:13:01 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:13:01 INFO - Downloading docopt-0.6.1.tar.gz 12:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:01 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:01 INFO - Running setup.py install for blobuploader 12:13:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:02 INFO - Running setup.py install for requests 12:13:02 INFO - Running setup.py install for docopt 12:13:02 INFO - Successfully installed blobuploader requests docopt 12:13:02 INFO - Cleaning up... 12:13:02 INFO - Return code: 0 12:13:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:02 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:13:02 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:13:02 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:13:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:13:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:02 INFO - 'HOME': '/Users/cltbld', 12:13:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:02 INFO - 'LOGNAME': 'cltbld', 12:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:02 INFO - 'MOZ_NO_REMOTE': '1', 12:13:02 INFO - 'NO_EM_RESTART': '1', 12:13:02 INFO - 'PAGER': '/bin/cat', 12:13:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:02 INFO - 'PWD': '/builds/slave/test', 12:13:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:02 INFO - 'SHELL': '/bin/bash', 12:13:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:13:02 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:13:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:02 INFO - 'USER': 'cltbld', 12:13:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:02 INFO - 'XPC_FLAGS': '0x0', 12:13:02 INFO - 'XPC_SERVICE_NAME': '0', 12:13:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-v7r4bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-uiMNar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-TfyAhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-BtUVx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-zOlYnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-e_mFa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-7hif1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-3F9RIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Vs0Q_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:03 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-jVYn_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-pDxdtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-HDXx_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-yFO8tB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-BCe4YL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-IeK8Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-MBK0ZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-BidPSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:04 INFO - Running setup.py install for manifestparser 12:13:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:04 INFO - Running setup.py install for mozcrash 12:13:05 INFO - Running setup.py install for mozdebug 12:13:05 INFO - Running setup.py install for mozdevice 12:13:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Running setup.py install for mozfile 12:13:05 INFO - Running setup.py install for mozhttpd 12:13:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Running setup.py install for mozinfo 12:13:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Running setup.py install for mozInstall 12:13:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:13:05 INFO - Running setup.py install for mozleak 12:13:06 INFO - Running setup.py install for mozlog 12:13:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:13:06 INFO - Running setup.py install for moznetwork 12:13:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:13:06 INFO - Running setup.py install for mozprocess 12:13:06 INFO - Running setup.py install for mozprofile 12:13:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:13:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:13:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:13:06 INFO - Running setup.py install for mozrunner 12:13:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:13:06 INFO - Running setup.py install for mozscreenshot 12:13:07 INFO - Running setup.py install for moztest 12:13:07 INFO - Running setup.py install for mozversion 12:13:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:13:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:13:07 INFO - Cleaning up... 12:13:07 INFO - Return code: 0 12:13:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:07 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:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:07 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:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:07 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:13:07 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:13:07 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:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:07 INFO - 'HOME': '/Users/cltbld', 12:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:07 INFO - 'LOGNAME': 'cltbld', 12:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:07 INFO - 'MOZ_NO_REMOTE': '1', 12:13:07 INFO - 'NO_EM_RESTART': '1', 12:13:07 INFO - 'PAGER': '/bin/cat', 12:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:07 INFO - 'PWD': '/builds/slave/test', 12:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:07 INFO - 'SHELL': '/bin/bash', 12:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:13:07 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:07 INFO - 'USER': 'cltbld', 12:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:07 INFO - 'XPC_FLAGS': '0x0', 12:13:07 INFO - 'XPC_SERVICE_NAME': '0', 12:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:07 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-1QEkNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:07 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:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:07 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-PYGgFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:07 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:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:07 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-LFHW3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:07 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:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:07 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-DjY9z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-jyBwWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-axnLQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-oN2xeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-v406Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-hRdBRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-by1juD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-XDD8DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-qUwYs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:08 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:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-rM2iiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:09 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:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-MrV7Nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:09 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:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-_D9OtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:09 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:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-9ULJ2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:09 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:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-rpQn6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:09 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:13:09 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:13:09 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:13:09 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:13:09 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:13:09 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:13:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13:09 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:13:09 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:13:09 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:13:09 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:13:09 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:13:09 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:13:11 INFO - Downloading blessings-1.6.tar.gz 12:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:13:11 INFO - Installing collected packages: blessings 12:13:11 INFO - Running setup.py install for blessings 12:13:12 INFO - Successfully installed blessings 12:13:12 INFO - Cleaning up... 12:13:12 INFO - Return code: 0 12:13:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:13:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:12 INFO - Reading from file tmpfile_stdout 12:13:12 INFO - Current package versions: 12:13:12 INFO - blessings == 1.6 12:13:12 INFO - blobuploader == 1.2.4 12:13:12 INFO - docopt == 0.6.1 12:13:12 INFO - functools32 == 3.2.3-2 12:13:12 INFO - jsonschema == 2.5.1 12:13:12 INFO - manifestparser == 1.1 12:13:12 INFO - mozInstall == 1.12 12:13:12 INFO - mozcrash == 0.17 12:13:12 INFO - mozdebug == 0.1 12:13:12 INFO - mozdevice == 0.48 12:13:12 INFO - mozfile == 1.2 12:13:12 INFO - mozhttpd == 0.7 12:13:12 INFO - mozinfo == 0.9 12:13:12 INFO - mozleak == 0.1 12:13:12 INFO - mozlog == 3.3 12:13:12 INFO - moznetwork == 0.27 12:13:12 INFO - mozprocess == 0.23 12:13:12 INFO - mozprofile == 0.28 12:13:12 INFO - mozrunner == 6.12 12:13:12 INFO - mozscreenshot == 0.1 12:13:12 INFO - mozsystemmonitor == 0.3 12:13:12 INFO - moztest == 0.7 12:13:12 INFO - mozversion == 1.4 12:13:12 INFO - psutil == 3.1.1 12:13:12 INFO - requests == 1.2.3 12:13:12 INFO - wsgiref == 0.1.2 12:13:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13: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:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:12 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:13:12 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:13:12 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:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:12 INFO - 'HOME': '/Users/cltbld', 12:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:12 INFO - 'LOGNAME': 'cltbld', 12:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:12 INFO - 'MOZ_NO_REMOTE': '1', 12:13:12 INFO - 'NO_EM_RESTART': '1', 12:13:12 INFO - 'PAGER': '/bin/cat', 12:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:12 INFO - 'PWD': '/builds/slave/test', 12:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:12 INFO - 'SHELL': '/bin/bash', 12:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:13:12 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:12 INFO - 'USER': 'cltbld', 12:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:12 INFO - 'XPC_FLAGS': '0x0', 12:13:12 INFO - 'XPC_SERVICE_NAME': '0', 12:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 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:13:12 INFO - Cleaning up... 12:13:12 INFO - Return code: 0 12:13:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:13: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:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:12 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 0x102666a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418458c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, '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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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:13:12 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:13:12 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:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:12 INFO - 'HOME': '/Users/cltbld', 12:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:12 INFO - 'LOGNAME': 'cltbld', 12:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:12 INFO - 'MOZ_NO_REMOTE': '1', 12:13:12 INFO - 'NO_EM_RESTART': '1', 12:13:12 INFO - 'PAGER': '/bin/cat', 12:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:12 INFO - 'PWD': '/builds/slave/test', 12:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:12 INFO - 'SHELL': '/bin/bash', 12:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:13:12 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:12 INFO - 'USER': 'cltbld', 12:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:12 INFO - 'XPC_FLAGS': '0x0', 12:13:12 INFO - 'XPC_SERVICE_NAME': '0', 12:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:13:13 INFO - Cleaning up... 12:13:13 INFO - Return code: 0 12:13:13 INFO - Running post-action listener: _resource_record_post_action 12:13:13 INFO - Running post-action listener: _start_resource_monitoring 12:13:13 INFO - Starting resource monitoring. 12:13:13 INFO - [mozharness: 2016-08-18 19:13:13.057938Z] Finished create-virtualenv step (success) 12:13:13 INFO - [mozharness: 2016-08-18 19:13:13.058900Z] Running install step. 12:13:13 INFO - Running pre-action listener: _resource_record_pre_action 12:13:13 INFO - Running main action method: install 12:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:13 INFO - Reading from file tmpfile_stdout 12:13:13 INFO - Detecting whether we're running mozinstall >=1.0... 12:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:13:13 INFO - Reading from file tmpfile_stdout 12:13:13 INFO - Output received: 12:13:13 INFO - Usage: mozinstall [options] installer 12:13:13 INFO - Options: 12:13:13 INFO - -h, --help show this help message and exit 12:13:13 INFO - -d DEST, --destination=DEST 12:13:13 INFO - Directory to install application into. [default: 12:13:13 INFO - "/builds/slave/test"] 12:13:13 INFO - --app=APP Application being installed. [default: firefox] 12:13:13 INFO - mkdir: /builds/slave/test/build/application 12:13:13 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:13:13 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:13:47 INFO - Reading from file tmpfile_stdout 12:13:47 INFO - Output received: 12:13:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:13:47 INFO - Running post-action listener: _resource_record_post_action 12:13:47 INFO - [mozharness: 2016-08-18 19:13:47.376337Z] Finished install step (success) 12:13:47 INFO - [mozharness: 2016-08-18 19:13:47.376486Z] Running run-tests step. 12:13:47 INFO - Running pre-action listener: _resource_record_pre_action 12:13:47 INFO - Running main action method: run_tests 12:13:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:13:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:13:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:13:47 INFO - 2016-08-18 12:13:47.391 screenresolution[1904:18152] starting screenresolution argv=screenresolution get 12:13:47 INFO - 2016-08-18 12:13:47.393 screenresolution[1904:18152] Display 0: 1600x1200x32@60 12:13:47 INFO - 2016-08-18 12:13:47.399 screenresolution[1905:18154] starting screenresolution argv=screenresolution list 12:13:47 INFO - Available Modes on Display 0 12:13:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:13:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:13:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:13:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:13:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:13:47 INFO - Intel Iris: 12:13:47 INFO - Chipset Model: Intel Iris 12:13:47 INFO - Type: GPU 12:13:47 INFO - Bus: Built-In 12:13:47 INFO - VRAM (Dynamic, Max): 1536 MB 12:13:47 INFO - Vendor: Intel (0x8086) 12:13:47 INFO - Device ID: 0x0a2e 12:13:47 INFO - Revision ID: 0x0009 12:13:47 INFO - Displays: 12:13:47 INFO - Display: 12:13:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:13:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:13:47 INFO - Display Serial Number: 1600x1200 60 12:13:47 INFO - Main Display: Yes 12:13:47 INFO - Mirror: Off 12:13:47 INFO - Online: Yes 12:13:47 INFO - Rotation: Supported 12:13:47 INFO - Return code: 0 12:13:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:13:47 INFO - Python 2.7.3 12:13:47 INFO - Return code: 0 12:13:47 INFO - grabbing minidump binary from tooltool 12:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:47 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 0x1025cad30>, '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 0x10232fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025c2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:13:47 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:13:47 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:13:47 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:13:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:13:47 INFO - Return code: 0 12:13:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:13:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:13:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:13:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:13:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:13:47 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/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--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:13:47 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/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0285 --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:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8BpVGEVTPZ/Render', 12:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:47 INFO - 'HOME': '/Users/cltbld', 12:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:47 INFO - 'LOGNAME': 'cltbld', 12:13:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:13:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:47 INFO - 'MOZ_NO_REMOTE': '1', 12:13:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:13:47 INFO - 'NO_EM_RESTART': '1', 12:13:47 INFO - 'PAGER': '/bin/cat', 12:13:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:47 INFO - 'PWD': '/builds/slave/test', 12:13:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:47 INFO - 'SHELL': '/bin/bash', 12:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6zv7AuGF3/Listeners', 12:13:47 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 12:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:47 INFO - 'USER': 'cltbld', 12:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:47 INFO - 'XPC_FLAGS': '0x0', 12:13:47 INFO - 'XPC_SERVICE_NAME': '0', 12:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:47 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/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--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:13:47 INFO - mozversion application_buildid: 20160818093455 12:13:47 INFO - mozversion application_changeset: 8a70c7469c5025ec9d644c3eb82f60f5982c1edb 12:13:47 INFO - mozversion application_display_name: Nightly 12:13:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:13:47 INFO - mozversion application_name: Firefox 12:13:47 INFO - mozversion application_remotingname: firefox 12:13:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:13:47 INFO - mozversion application_vendor: Mozilla 12:13:47 INFO - mozversion application_version: 51.0a1 12:13:47 INFO - mozversion platform_buildid: 20160818093455 12:13:47 INFO - mozversion platform_changeset: 8a70c7469c5025ec9d644c3eb82f60f5982c1edb 12:13:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:13:47 INFO - mozversion platform_version: 51.0a1 12:13:47 INFO - using testdate: 1471547627 12:13:47 INFO - actual date: 1471547627 12:13:47 INFO - starting webserver on 'localhost:53147' 12:13:47 INFO - SUITE-START | Running 2 tests 12:13:47 INFO - TEST-START | dromaeo_css 12:13:47 INFO - operating with platform_type : mac_ 12:13:47 INFO - Initialising browser for dromaeo_css test... 12:13:47 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmp01YNKx/profile http://localhost:53147/getInfo.html) 12:13:50 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 12:13:50 INFO - PROCESS | 1915 | colorDepth:24 12:13:50 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 12:13:50 INFO - PROCESS | 1915 | __metrics 12:13:50 INFO - PROCESS | 1915 | 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:13:50 INFO - PROCESS | 1915 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:13:51 INFO - TEST-INFO | 1915: exit 0 12:13:51 INFO - Browser initialized. 12:13:51 INFO - Running cycle 1/1 for dromaeo_css test... 12:13:51 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmp01YNKx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:02 INFO - PROCESS | 1917 | RSS: Main: 228364288 12:14:02 INFO - PROCESS | 1917 | 12:14:54 INFO - PROCESS | 1917 | 1471547694513 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:17:06 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53147/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53147/tests/dromaeo/cssquery-ext.html) 12:17:07 INFO - PROCESS | 1917 | RSS: Main: 364982272 12:17:07 INFO - PROCESS | 1917 | 12:20:11 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53147/tests/dromaeo/cssquery-ext.html (next: http://localhost:53147/tests/dromaeo/cssquery-jquery.html) 12:20:11 INFO - PROCESS | 1917 | RSS: Main: 375357440 12:20:11 INFO - PROCESS | 1917 | 12:23:14 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53147/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53147/tests/dromaeo/cssquery-mootools.html) 12:23:14 INFO - PROCESS | 1917 | RSS: Main: 330846208 12:23:14 INFO - PROCESS | 1917 | 12:26:17 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53147/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53147/tests/dromaeo/cssquery-prototype.html) 12:26:17 INFO - PROCESS | 1917 | RSS: Main: 358621184 12:26:17 INFO - PROCESS | 1917 | 12:29:21 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53147/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53147/tests/dromaeo/cssquery-yui.html) 12:29:21 INFO - PROCESS | 1917 | RSS: Main: 333524992 12:29:21 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53147/tests/dromaeo/cssquery-yui.html (next: http://localhost:53147/tests/dromaeo/cssquery-dojo.html) 12:32:25 INFO - PROCESS | 1917 | RSS: Main: 410820608 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | __start_tp_report 12:32:25 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 12:32:25 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 12:32:25 INFO - PROCESS | 1917 | |i|pagename|runs| 12:32:25 INFO - PROCESS | 1917 | |0;dojo.html;3315.602127744671;3343.5987681478227;3362.562866827313;3385.77992430492;3392.745544084194;5567.693776842272;5655.971720141399;5657.830265092054;5707.315122185339;5733.254676892004;5663.971680141593;5718.971405142968;5722.54219662426;5723.942760572393;5753.65478071315;5172.45689202633;5201.453847346031;5224.764885580158;5229.163333866582;5277.472252774711;5217.5565076968505;5233.685978841263;5247.921281180786;5266.789328426858;5298.36419629645;5167.302414174094;5179.533841954223;5205.739713014353;5205.973970130144;5276.445973172819;4403.779811009436;5236.764345604457;5252.159654455307;5271.710055946922;5283.973580132113;5130.127878260705;5131.743412829381;5144.022635699205;5171.4311425743135;5194.63637545372;5669.716514174274;5680.289963754531;5687.800926967578;5703.230063941398;5721.284839395075;6293.46505547029;6296.496280297565;6302.338254483259;6303.212098487689;6318.557700960934;6159.445649891486;6226.034964580497;6227.346128656516;6289.842753931142;6318.4629306509005;5870.970645146833;5871.853203669857;5906.114082887515;5909.054551271776;5909.556783241274;6268.435840774352;6280.214973128359;6284.654344011124;6288.434040936338;6289.528285378615;5978.432048955342;6043.153958445821;6099.3900609938655;6115.266168059861;6173.382661733791;6291.96854015727;6299.590526615755;6313.463355614732;6319.115323854664;6333.050042493663;5053.974730126326;6260.436560709558;6263.279722831932;6269;6283.7800676976085;6095.939040609537;6105.247473116927;6273.968630156911;6279.152314437584;6293.244810622663;4887.242477416006;4889.364382630235;4943.085529177114;4947.579455746302;4984.476629953875;4402.977985110054;4409.206342858316;4420.447444069491;4433.202023635712;4457.996950686134;5709.200711900337;5782.88434231313;5801.3038435388025;5808.361080281165;5858.121281807763;6195.535334849814;6216.720247588869;6235.906461403082;6241.438270555672;6281;5764.99112655292;5773.162891380726;5781.364049954502;5807.448292412214;5823.94176058234;5347.491988261109;5417.160340147283;5445.945540544623;5448.45515448452;5452.236686863842;6202.782902598387;6208.627482351073;6270.592411493239;6270.686465676789;6334.3665633436285;6297;6307.873842523216;6313.273973493107;6314.305426403183;6342.524310676626;6225.688715564294;6298.811035668938;6300.7479700811455;6317.178766760292;6569;6092.177556030057;6128.717018009172;6189.938100618936;6215.502759779117;6252.749890004349;6225.750969961151;6300.621962682254;6309.274433440213;6312.084747711648;6321;5962.2249107615735;6118.449339559461;6138.232720909887;6144.354842741626;6155.1382806406245;5946.791862284799;6054.757809687563;6084.148219249397;6119.816405507842;6123.479504242189;6039.275286965592;6064.090386441892;6076.270847498328;6082.817515474543;6083.391660833881;6114.174586430775;6132.736347182667;6154.046122850965;6169.691515424301;6191.1641928339095;4897.681650692836;5759.395263497272;5902.970485147547;5953.880922381615;5966.403359663999 12:32:25 INFO - PROCESS | 1917 | |1;ext.html;462.1288870479147;471.94100737407825;484.4888642482182;524.8031988004499;529.978800847966;30907.845460772693;31348.529772053407;32618.67381326186;32652.67347326526;32653.836730816372;27633.58549621752;43041.35437968425;43921.34117988225;43978.780106099504;46067.30899036517;9786.804263914735;9927.205823534136;9935.552900119475;9940.353876997971;9941.254405919548;9777.804443911118;9881.20950323976;9888.604455821758;9890.208783297356;9924.652637157666;9863.852042219372;9895.50522473877;9915.801683966316;9916.702498925008;9928.95035524825;9800.90199098007;9877.753056173582;9883.90116098837;9886.011398860128;9894.851577226347;9763.218942484655;9841.803163936682;9851.458169800659;9852.556634951445;9860;41382.37926431106;41675.791621042;41680;41687.166256674696;41699.7915010426;3699.667029967316;3816.809159542012;4112.691548133872;4126.834926602933;4289.077848262638;31297.061088167393;32453.6754632453;32547.674523254937;32826.50760238598;32838;2825.420788738324;3224.3551289742145;3334.8832790852202;3353.295807880348;3464.7055000325004;467052;473827.9394633294;475856;476802;479397;59737.70131149316;60085.79828403408;60111;60136.097958530656;60230;34436.48345274823;35084.29831403358;35173;36407.81796091056;63357.683211583644;13838.446462141603;16402.589935251475;16544.172791360383;16571;16575.088370139758;12738;12814.743705126031;12820.487180512862;12820.61538153857;12833.807492887427;19121.043947802555;19186.328478503186;19201.807981920003;19206.711899321523;19230;6687.598744075371;7772.922270777333;7855.607219638995;7880.64537095832;7938.325242354349;888.6800751729372;955.7753927826955;980.5930538826307;984.9704508864745;1003.7340104872214;28752;29718.622510199566;30016.249593759894;30173.849130754646;31150.844245778626;974.6744427578487;1043.1081425381342;1061.9221490187465;1102.2614848051824;1142.2175809570472;15211.391544338103;15735.44925927587;15755.133467659389;15821.604459888595;16193.352265909467;666350.3649635095;670905.6454717793;674033;674526.2547374463;675995;58921;59423.811523769284;59434.70282648559;59477.107843382386;59515;1242.105683907585;1288.8195652608642;1359.5785306555;1393.2406838273166;1418.524794193936;30289.394212116076;31297.8435107823;31312.9040483582;31608;32080.83959580187;51053.74473127611;51530.48469515257;51553.48446515637;51567;51630;457439;458076;458458;459298;460521;23192.884035579715;26287;27024.64876756115;27029.86485067562;27479.587806183325;47102.05795884132;47925;48493.75753121212;48614.756926215145;48741;38921.80539097286;39040.60959390483;39485.60514394934;40175.79912100421;40399.990000250225;38847.41728874013;39210.80394598123;39310;40364;40884.182316354105;38134.62806449639;39145.60854391533;39243.01892452712;39384.40923386209;39444;28730;30381.69618303877;34128.82935585405;34216.65783342135;34324;25141.994320226986;25244.495110097327;25298.620520691835;25581.060767670424;25717.871410642827 12:32:25 INFO - PROCESS | 1917 | |2;jquery.html;343.1815120936566;347.1581415068458;367.9117011917141;369.74672349440635;374.7376836214649;15559.455419060321;17563.38528151515;20613.587728245428;20713.067911943963;21121.68317475239;20103.698444523307;20617.587648247027;20719;20982.16071357144;21444.678329825067;5875.089361149007;5896.05663093904;6031.819045428622;6135.8772824543485;6141.631502109865;5865.413458654143;6011.128386383972;6022.909656355158;6030.246219222598;6031.547633927451;4150.481189851269;6431.163948686664;6686.431653309474;6732.485190832048;6838.760643377483;5778.42215778423;6469;6674.69963851623;6739.663016849163;6749.595024298558;5848.09936770547;5865.4134586541;5866.413358664142;5872.559558033165;5882.647041177543;22430;26574.86712566444;27010;27241.455170896472;27298.5905211422;2374.15717420315;2791.6231308773463;3133.6552979172275;3164.620245570546;3231.8222497762613;12626.936865315614;14810.481633142788;15680.451184208612;16355.427560035341;16733.497995060166;1618.8947718398383;1694.2799310293199;1749.475157452759;1843.6687525331915;1936.0610104099642;89580.55209723975;115448;116618.41690791577;117451.82548174495;118345;10103.191744660557;10689.984451477098;10697.946510267398;10972.122230221563;11012.449377531091;13861.376238069312;13868.861311386758;13886.513972010931;13892.513762018281;13909.095908765898;8401.915980840113;8652.264557512555;8689.435186712843;8799.824003519894;8839.779005524784;8703.260222881;8727.389082764272;8732.170443807829;8890.86663700045;8990.910090899139;6698.119808482302;6821.658917054127;6828.2488926218075;6847.726090956306;6853.451723862079;1797.739327797462;1844.603410266799;1845.4648152035759;1845.4924895653535;1855.2300795170124;707.4906067631297;847.013229587536;852.3351785607236;908.3596064774318;969.9757506062405;20487.487812804502;21762.89118554397;22645.320640380814;22681;22764.317070487912;867.8351113288455;942.4910548303839;988.1501908358806;1005.8181636576993;1025.8409946458312;10978;11114.499847506882;11371.715707107223;12108.878911210775;12134.878651213552;586418;592021;595500;602724.9863750741;602907;108711.45644271727;113484;113991;115283;117945;974.9616658258932;997.6607953295915;1035.9015893490107;1037.4294138223947;1037.9481025948671;16017.67964640724;19576.314828980918;19614;19773.60452790965;20419.693704594145;53966;54391;54753.45246547643;55083;55603.72198139145;73868;74325;75760.62119689367;76125;76251.23748762663;7968.721094761656;8018.3585313173635;8344.37417193725;8361.874571881304;8537.658493660185;9073.00393913338;12848.229106253655;12937.676558086123;13210.141340812816;13475.989300802283;10982.176336774613;11238.381888995857;11254.38100904458;11260.549578016788;11340.108272390487;5077.213031980049;7525.720627493449;10079.24405669563;11053.502592383524;11365.772684546097;10892.183086268404;11454.70905818823;11552.768944621228;11679.124065695276;11894;6727.730890764315;7854.764357069297;7982.361411087215;8329.625166867627;8342.916570834213;10511.579536818748;11004.174686898676;11308.547658093585;11334.943325283652;11402.087832973177 12:32:25 INFO - PROCESS | 1917 | |3;mootools.html;1528.5337971918561;1563.7341651919173;1727.9827201727987;1875.3436297295948;1878.6806242938699;8385.496870187786;8948.14992575706;8956.507392093432;8964.820703585932;8983.101689831014;7837.373010159188;8079.272865442109;8487.533185674802;8720.95639521803;8757.60590773415;2049.9692504612444;2341.899307325554;2348.788609025192;2382.094803974487;2396.880155992202;2341.9414514637183;2355.7879790818824;2386.713594368678;2388.2715771689627;2394.030417680842;2344.460774021976;2360.492494113765;2370.7866292033714;2384.082128380577;2385.654080158376;1983.424806806039;2340.169239919838;2352.3178278299274;2354;2362.6692263083096;2232.7543970163433;2242.338510139513;2242.9887850560644;2244.2706120510898;2250.3923940535965;8734.301255899512;8950.86573701395;8951.14964078418;8960.23837973773;8986.910130898672;4586.839460618862;5200.167973124319;5368.0605893968395;5382.542483888874;5383.138697808332;3485.5120283160377;3538.7876727396438;3657.7805331680183;3697.1126929536986;3707.59216486189;1040.8334666453406;1079.578964203961;1085.6037546295624;1103.9944800276028;1105.8064838653283;9844.65543705974;11525.423728813526;11730.70673233159;11735.23720958135;11777.293362398283;3971.212954170577;4669.579737823612;4781.474037855833;4783.928241076386;4807.61539076873;9902.504874756234;9902.900970990342;9904.60381584743;9906.851397229048;9937.552810123561;5302.390225124083;5303.098473259555;5315.043292207362;5317.069512835238;5322.520973112361;4377.430933978562;4532.773361331921;4730.550597693283;4836;4877.414710234794;3828.1769419574925;3875.6511913927884;3953.6639385651965;3995.700322475826;4002.639762421396;1286.408252203978;1298.2210673595766;1298.7662220800157;1312.3700623700486;1313.7175507265983;839.5550358310105;887.6316328723508;926.5691453474167;926.8007378413673;976.814405263012;5674.914876276859;5933.317668468095;5981.491573216325;5999.970000149971;6030.7889223876955;421.16188784318985;433.6816079119442;438.6468892541471;450.2435907675137;458.4635975908195;3644.942966539727;3743.513343265358;3919.941200881989;4038.5355684096116;4045.130296986162;10403.219758518142;10606.734831629272;10727.83908241377;10737;10750.94624526888;10669.946650266698;10677.78644427107;10678.985496377833;10684.358938463716;10688.572457101629;6986.895196572055;8150.4294699370475;8241.711540096068;8248.46384984986;8249.670013199524;5671.149327600893;5717.313922329347;5870.207521984477;5907.704614769158;5924.229850119457;8874.778130546789;9458.43249405038;9973.052560006789;9973.950130249303;10047.397156170653;8992.235659968976;9829.508524573886;10234.078932896075;10338.483075846027;10351.43067131315;6890.379865812101;7138.321859423347;7151.82120446993;7512.849743005218;7563.924360756542;7951.204879512003;8047.87928181066;8058.3150432213915;8224.971878515185;8483.236508714246;7266.927330726625;7544.434167437574;7561;7595.088589369311;7694.846103077795;7227.2772722729405;7359.006534117825;7446.292602202782;7446.515976461513;7537.170911199654;7059.5058345915295;7060.399866011241;7146.3568278855155;7153.141623005273;7157.391621712213;4927.531884470969;4953.083679519301;4983.053219888209;4994.400671919393;5083.262926875656;4951.3810773653295;5001.899962000813;5060.443351231435;5061.114304996538;5258.579313654822 12:32:25 INFO - PROCESS | 1917 | |4;prototype.html;279.3296089385476;286.3257029695068;299.80362862325177;310.1702944623133;312.62953400220727;4484.798184081716;4590.472095708995;4870.464248932614;5068.226120857698;5327.840164795058;4499.032707967786;4788.616910647149;5043.344365232515;5172.189733593241;5297.4437684043105;3373.578302712161;3698.519192504977;4031.6976226782963;4032.3145065338817;4040.525208298979;4066.6543343815665;4067.125568002879;4083.305838007546;4097.016715988171;4106.5072191337085;3798.753081049737;3881.7476864003893;3934.3705007198855;3989.9227208653647;4024.1146947671464;3865.9806700966315;4004.979975100135;4020.396940458925;4047.7571345719352;4074.921789887164;2779.069011881022;2965.1252880400116;3041.285297954979;3066.723994840475;3081.9075427737203;4269.658427325807;4477.865664030084;4796.112719146969;5150.871228219325;5209.348831396075;3833.7124715646437;4544.091181763661;4559.475660299075;4573.405457290531;4588.151192029485;4538.52345503724;5036.697798132087;5583.8604034899045;5626.803061892855;5658.0381335173115;3303.9174020649434;3659.0120667419915;3821.4458903458844;3832.1760821423527;3847.076701591626;5491.313585801815;5658.708223169623;5706.543476521868;5721.6853073080965;5794.97102514489;3672.192117734094;3714.8699795507027;3840.8271627776817;3845.4616353710503;3846.519185101862;3613.638636136418;3743.970408137784;3885.2229554089295;3886.8833934981994;3912.787036018843;3800.410936304877;4018.3771515415156;4269.466316710411;4368.951451651581;4506.504284528699;5389.326334208224;5438.265834112423;5540.612157148961;5555.77776888928;5662.320521537359;1152.7291086594644;1308.5997982159188;1320.570814485296;1328.4686125549858;1329.9867001330056;745.3478206569251;881.1761003461784;893.9329550283754;898.1557336104172;900.8063266397756;4295.153084173835;5051.686561494038;5090.567301779316;5105.15764898795;5165.819196328111;5152.948470515247;5667.4615911488345;5674.2977106867775;5695.8860822784145;5750.798722044792;4737.928931066037;5076.289319495274;5102.821401250939;5127.769250383667;5214.765335559909;2146.495573540217;2381.9880900595385;2536.885840137198;2543.427728761014;2568.0210955292146;4317.676174286941;4505.43682039745;4675.57919787214;4795.736234507136;4831.376467649263;4210.32634778434;4373.781310934441;4708.717476951394;4842.661013729075;4942.555170034714;4466.548371779119;4692.199318551984;4849.733264670406;4875.609951203895;4909.367068369381;4023.376376661622;4138.13099249149;4586.958803332485;4818.542238487338;4916.582090522345;4545.636189258217;4656.18408820567;4664.55686709762;4736.5973892219545;5038.496150384932;5219.634625576173;5795.275590551181;5890.7349169288345;5974.014287642491;6030.819075427744;3081.6918308169;3704.9073773155887;3707.277080969293;3760.417135344026;3811.352070148137;4972.6076698524475;5660.29246344207;5718.199452076629;5783.768649253982;5789.913151302818;4872.6832755870755;5493.780248790164;5558.333000039859;5654.575906807088;5666.345207341159;5432.483914028161;5473.728402712989;5486.939664648052;5492.642978206563;5498.890022199614;4987.875303117451;5395.298611180521;5424.267723857387;5451.972740136274;5556.888862222814;4558.794854231502;5081.5426611605135;5245.606579506629;5259.289995850511;5525.475079867406;4476.306172543261;5131.87170320745;5325.973370133124;5372.355317361941;5514.972425137848 12:32:25 INFO - PROCESS | 1917 | |5;yui.html;711.1999001123736;721.2102747491498;722.7362012865306;768.5388766739957;823.074041703084;15286.388544458223;16335.101569413677;16335.754963675554;16346.34614615417;16356.918215408936;14628.414863405478;14805.629859253524;14810.851891481081;14817.481388151416;14817.92591037046;660.9306022867602;676.6887231873319;690.5304393012761;822.6257053040852;837.6146972392706;831.8752187171912;840.2018082821344;841.6086519768306;844.3245403677065;844.932405407566;878.4202426398579;882.2016075451727;882.5631312500321;883.5626364949327;885.4554449013842;690.4200471603826;852.8635418333037;876.5441970175505;876.7501262140291;885.6014793342957;662.6620423583959;663.3101574362657;664.4219529009744;665.7902760630386;666.6166954001469;14990.175540345277;15017.924910375488;15023.549293521211;15029.774553381709;15039.624009399744;13928.791068133985;14082.788758168635;14201.857981420157;14314.069585477015;14348.282585870666;12897.2906490143;13027.934860325639;13123.475060997644;13277.203367797867;13689.589312320646;4715.575598196145;4718.386609740712;4842.491538388454;4943.1102401567705;4944.258361245806;71001.64499177523;111800;114576;116840.15799210005;118815.40592297069;11183.32900026001;11280.661580152444;11299.096072314194;11519.942400287944;11791.941040294743;18348.541286467676;22647.773522264895;22689.319320420414;22750.544989100126;22766.31701048971;7962.92500512435;8040.15578364275;8044.2760151586635;8098.31164351025;8286.8342633147;4427.335899615019;4451.3100469426645;4530.569595888385;4677.695949763254;4682.4381074271305;3136.5765621641244;3335.1328654549993;3335.682405449834;3483.8606455741487;3488.0930957951114;505.48945564979635;620.1101419463085;625.5714835337808;626.5676683088745;629.9874002519924;526.7550588976145;535.8901425207823;624.8250489862845;630.5302549600485;651.5048563092084;7948.960255198803;8054.838903221903;8622;8763.342749293828;9132.49771262587;528.5586535243115;581.7353104337516;617.8733359661259;626.7806267806322;631.8831016262006;3912.6087391260644;3955.149642826806;4026.355783074694;4160.895977600524;4237.491501019836;146198;147204.26397868156;152501;154054;164797.3520264806;120586;134175;134406.32796835955;137273;137304;68570;68692.656536717;89278;111467.44266278617;116592.41703791427;8231.917680823115;8317.542535160383;8556.229939305493;8640.611172497378;8756.824863502821;59743.40256597552;61220;63643;63851.68074159599;64672.35327646663;96923.51538242264;97862;100970;101137;102287;11298.9266019728;11465.656030319104;11554.133439992202;11631.76736465283;12045.33750643723;12216.877831221575;12223.144379893321;12356.073294502768;12363.62909112728;12540.937295313464;11664.6500604982;11818.940905295418;11825.526978920747;11886.692463829146;12399.380030998595;11988.580399685969;13283.136596121221;13581.701957651056;13784.931075344559;13791.241481718602;13266.668333291746;13448.327583620976;13582.52461163894;13779.517716879862;14270.500532481728;10706.601940782637;11179.88820111821;11693.356865372487;11967.700807479534;12019.535995120805;10098.394096354243;10231.874493805824;10995.945020275167;11200.831987520356;11401.429928503707 12:32:25 INFO - PROCESS | 1917 | __end_tp_report 12:32:25 INFO - PROCESS | 1917 | __start_cc_report 12:32:25 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,1369 12:32:25 INFO - PROCESS | 1917 | __end_cc_report 12:32:25 INFO - PROCESS | 1917 | __startTimestamp1471548745395__endTimestamp 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | ------- Summary: start ------- 12:32:25 INFO - PROCESS | 1917 | Number of tests: 6 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | [#0] dojo.html Cycles:165 Average:5750.77 Median:5966.40 stddev:656.91 (11.0%) stddev-sans-first:630.54 12:32:25 INFO - PROCESS | 1917 | Values: 3315.6 3343.6 3362.6 3385.8 3392.7 5567.7 5656.0 5657.8 5707.3 5733.3 5664.0 5719.0 5722.5 5723.9 5753.7 5172.5 5201.5 5224.8 5229.2 5277.5 5217.6 5233.7 5247.9 5266.8 5298.4 5167.3 5179.5 5205.7 5206.0 5276.4 4403.8 5236.8 5252.2 5271.7 5284.0 5130.1 5131.7 5144.0 5171.4 5194.6 5669.7 5680.3 5687.8 5703.2 5721.3 6293.5 6296.5 6302.3 6303.2 6318.6 6159.4 6226.0 6227.3 6289.8 6318.5 5871.0 5871.9 5906.1 5909.1 5909.6 6268.4 6280.2 6284.7 6288.4 6289.5 5978.4 6043.2 6099.4 6115.3 6173.4 6292.0 6299.6 6313.5 6319.1 6333.1 5054.0 6260.4 6263.3 6269.0 6283.8 6095.9 6105.2 6274.0 6279.2 6293.2 4887.2 4889.4 4943.1 4947.6 4984.5 4403.0 4409.2 4420.4 4433.2 4458.0 5709.2 5782.9 5801.3 5808.4 5858.1 6195.5 6216.7 6235.9 6241.4 6281.0 5765.0 5773.2 5781.4 5807.4 5823.9 5347.5 5417.2 5445.9 5448.5 5452.2 6202.8 6208.6 6270.6 6270.7 6334.4 6297.0 6307.9 6313.3 6314.3 6342.5 6225.7 6298.8 6300.7 6317.2 6569.0 6092.2 6128.7 6189.9 6215.5 6252.7 6225.8 6300.6 6309.3 6312.1 6321.0 5962.2 6118.4 6138.2 6144.4 6155.1 5946.8 6054.8 6084.1 6119.8 6123.5 6039.3 6064.1 6076.3 6082.8 6083.4 6114.2 6132.7 6154.0 6169.7 6191.2 4897.7 5759.4 5903.0 5953.9 5966.4 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | [#1] ext.html Cycles:180 Average:66892.27 Median:40642.09 stddev:145384.38 (357.7%) stddev-sans-first:145706.66 12:32:25 INFO - PROCESS | 1917 | Values: 462.1 471.9 484.5 524.8 530.0 30907.8 31348.5 32618.7 32652.7 32653.8 27633.6 43041.4 43921.3 43978.8 46067.3 9786.8 9927.2 9935.6 9940.4 9941.3 9777.8 9881.2 9888.6 9890.2 9924.7 9863.9 9895.5 9915.8 9916.7 9929.0 9800.9 9877.8 9883.9 9886.0 9894.9 9763.2 9841.8 9851.5 9852.6 9860.0 41382.4 41675.8 41680.0 41687.2 41699.8 3699.7 3816.8 4112.7 4126.8 4289.1 31297.1 32453.7 32547.7 32826.5 32838.0 2825.4 3224.4 3334.9 3353.3 3464.7 467052.0 473827.9 475856.0 476802.0 479397.0 59737.7 60085.8 60111.0 60136.1 60230.0 34436.5 35084.3 35173.0 36407.8 63357.7 13838.4 16402.6 16544.2 16571.0 16575.1 12738.0 12814.7 12820.5 12820.6 12833.8 19121.0 19186.3 19201.8 19206.7 19230.0 6687.6 7772.9 7855.6 7880.6 7938.3 888.7 955.8 980.6 985.0 1003.7 28752.0 29718.6 30016.2 30173.8 31150.8 974.7 1043.1 1061.9 1102.3 1142.2 15211.4 15735.4 15755.1 15821.6 16193.4 666350.4 670905.6 674033.0 674526.3 675995.0 58921.0 59423.8 59434.7 59477.1 59515.0 1242.1 1288.8 1359.6 1393.2 1418.5 30289.4 31297.8 31312.9 31608.0 32080.8 51053.7 51530.5 51553.5 51567.0 51630.0 457439.0 458076.0 458458.0 459298.0 460521.0 23192.9 26287.0 27024.6 27029.9 27479.6 47102.1 47925.0 48493.8 48614.8 48741.0 38921.8 39040.6 39485.6 40175.8 40400.0 38847.4 39210.8 39310.0 40364.0 40884.2 38134.6 39145.6 39243.0 39384.4 39444.0 28730.0 30381.7 34128.8 34216.7 34324.0 25142.0 25244.5 25298.6 25581.1 25717.9 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | [#2] jquery.html Cycles:180 Average:34600.88 Median:27270.02 stddev:98936.00 (362.8%) stddev-sans-first:99180.11 12:32:25 INFO - PROCESS | 1917 | Values: 343.2 347.2 367.9 369.7 374.7 15559.5 17563.4 20613.6 20713.1 21121.7 20103.7 20617.6 20719.0 20982.2 21444.7 5875.1 5896.1 6031.8 6135.9 6141.6 5865.4 6011.1 6022.9 6030.2 6031.5 4150.5 6431.2 6686.4 6732.5 6838.8 5778.4 6469.0 6674.7 6739.7 6749.6 5848.1 5865.4 5866.4 5872.6 5882.6 22430.0 26574.9 27010.0 27241.5 27298.6 2374.2 2791.6 3133.7 3164.6 3231.8 12626.9 14810.5 15680.5 16355.4 16733.5 1618.9 1694.3 1749.5 1843.7 1936.1 89580.6 115448.0 116618.4 117451.8 118345.0 10103.2 10690.0 10697.9 10972.1 11012.4 13861.4 13868.9 13886.5 13892.5 13909.1 8401.9 8652.3 8689.4 8799.8 8839.8 8703.3 8727.4 8732.2 8890.9 8990.9 6698.1 6821.7 6828.2 6847.7 6853.5 1797.7 1844.6 1845.5 1845.5 1855.2 707.5 847.0 852.3 908.4 970.0 20487.5 21762.9 22645.3 22681.0 22764.3 867.8 942.5 988.2 1005.8 1025.8 10978.0 11114.5 11371.7 12108.9 12134.9 586418.0 592021.0 595500.0 602725.0 602907.0 108711.5 113484.0 113991.0 115283.0 117945.0 975.0 997.7 1035.9 1037.4 1037.9 16017.7 19576.3 19614.0 19773.6 20419.7 53966.0 54391.0 54753.5 55083.0 55603.7 73868.0 74325.0 75760.6 76125.0 76251.2 7968.7 8018.4 8344.4 8361.9 8537.7 9073.0 12848.2 12937.7 13210.1 13476.0 10982.2 11238.4 11254.4 11260.5 11340.1 5077.2 7525.7 10079.2 11053.5 11365.8 10892.2 11454.7 11552.8 11679.1 11894.0 6727.7 7854.8 7982.4 8329.6 8342.9 10511.6 11004.2 11308.5 11334.9 11402.1 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | [#3] mootools.html Cycles:180 Average:5659.95 Median:4952.23 stddev:3182.15 (64.3%) stddev-sans-first:3175.93 12:32:25 INFO - PROCESS | 1917 | Values: 1528.5 1563.7 1728.0 1875.3 1878.7 8385.5 8948.1 8956.5 8964.8 8983.1 7837.4 8079.3 8487.5 8721.0 8757.6 2050.0 2341.9 2348.8 2382.1 2396.9 2341.9 2355.8 2386.7 2388.3 2394.0 2344.5 2360.5 2370.8 2384.1 2385.7 1983.4 2340.2 2352.3 2354.0 2362.7 2232.8 2242.3 2243.0 2244.3 2250.4 8734.3 8950.9 8951.1 8960.2 8986.9 4586.8 5200.2 5368.1 5382.5 5383.1 3485.5 3538.8 3657.8 3697.1 3707.6 1040.8 1079.6 1085.6 1104.0 1105.8 9844.7 11525.4 11730.7 11735.2 11777.3 3971.2 4669.6 4781.5 4783.9 4807.6 9902.5 9902.9 9904.6 9906.9 9937.6 5302.4 5303.1 5315.0 5317.1 5322.5 4377.4 4532.8 4730.6 4836.0 4877.4 3828.2 3875.7 3953.7 3995.7 4002.6 1286.4 1298.2 1298.8 1312.4 1313.7 839.6 887.6 926.6 926.8 976.8 5674.9 5933.3 5981.5 6000.0 6030.8 421.2 433.7 438.6 450.2 458.5 3644.9 3743.5 3919.9 4038.5 4045.1 10403.2 10606.7 10727.8 10737.0 10750.9 10669.9 10677.8 10679.0 10684.4 10688.6 6986.9 8150.4 8241.7 8248.5 8249.7 5671.1 5717.3 5870.2 5907.7 5924.2 8874.8 9458.4 9973.1 9974.0 10047.4 8992.2 9829.5 10234.1 10338.5 10351.4 6890.4 7138.3 7151.8 7512.8 7563.9 7951.2 8047.9 8058.3 8225.0 8483.2 7266.9 7544.4 7561.0 7595.1 7694.8 7227.3 7359.0 7446.3 7446.5 7537.2 7059.5 7060.4 7146.4 7153.1 7157.4 4927.5 4953.1 4983.1 4994.4 5083.3 4951.4 5001.9 5060.4 5061.1 5258.6 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | [#4] prototype.html Cycles:180 Average:4307.83 Median:4683.89 stddev:1333.49 (28.5%) stddev-sans-first:1302.49 12:32:25 INFO - PROCESS | 1917 | Values: 279.3 286.3 299.8 310.2 312.6 4484.8 4590.5 4870.5 5068.2 5327.8 4499.0 4788.6 5043.3 5172.2 5297.4 3373.6 3698.5 4031.7 4032.3 4040.5 4066.7 4067.1 4083.3 4097.0 4106.5 3798.8 3881.7 3934.4 3989.9 4024.1 3866.0 4005.0 4020.4 4047.8 4074.9 2779.1 2965.1 3041.3 3066.7 3081.9 4269.7 4477.9 4796.1 5150.9 5209.3 3833.7 4544.1 4559.5 4573.4 4588.2 4538.5 5036.7 5583.9 5626.8 5658.0 3303.9 3659.0 3821.4 3832.2 3847.1 5491.3 5658.7 5706.5 5721.7 5795.0 3672.2 3714.9 3840.8 3845.5 3846.5 3613.6 3744.0 3885.2 3886.9 3912.8 3800.4 4018.4 4269.5 4369.0 4506.5 5389.3 5438.3 5540.6 5555.8 5662.3 1152.7 1308.6 1320.6 1328.5 1330.0 745.3 881.2 893.9 898.2 900.8 4295.2 5051.7 5090.6 5105.2 5165.8 5152.9 5667.5 5674.3 5695.9 5750.8 4737.9 5076.3 5102.8 5127.8 5214.8 2146.5 2382.0 2536.9 2543.4 2568.0 4317.7 4505.4 4675.6 4795.7 4831.4 4210.3 4373.8 4708.7 4842.7 4942.6 4466.5 4692.2 4849.7 4875.6 4909.4 4023.4 4138.1 4587.0 4818.5 4916.6 4545.6 4656.2 4664.6 4736.6 5038.5 5219.6 5795.3 5890.7 5974.0 6030.8 3081.7 3704.9 3707.3 3760.4 3811.4 4972.6 5660.3 5718.2 5783.8 5789.9 4872.7 5493.8 5558.3 5654.6 5666.3 5432.5 5473.7 5486.9 5492.6 5498.9 4987.9 5395.3 5424.3 5452.0 5556.9 4558.8 5081.5 5245.6 5259.3 5525.5 4476.3 5131.9 5326.0 5372.4 5515.0 12:32:25 INFO - PROCESS | 1917 | 12:32:25 INFO - PROCESS | 1917 | [#5] yui.html Cycles:180 Average:24604.44 Median:3335.41 stddev:39758.76 (1192.0%) stddev-sans-first:39829.82 12:32:25 INFO - PROCESS | 1917 | Values: 711.2 721.2 722.7 768.5 823.1 15286.4 16335.1 16335.8 16346.3 16356.9 14628.4 14805.6 14810.9 14817.5 14817.9 660.9 676.7 690.5 822.6 837.6 831.9 840.2 841.6 844.3 844.9 878.4 882.2 882.6 883.6 885.5 690.4 852.9 876.5 876.8 885.6 662.7 663.3 664.4 665.8 666.6 14990.2 15017.9 15023.5 15029.8 15039.6 13928.8 14082.8 14201.9 14314.1 14348.3 12897.3 13027.9 13123.5 13277.2 13689.6 4715.6 4718.4 4842.5 4943.1 4944.3 71001.6 111800.0 114576.0 116840.2 118815.4 11183.3 11280.7 11299.1 11519.9 11791.9 18348.5 22647.8 22689.3 22750.5 22766.3 7962.9 8040.2 8044.3 8098.3 8286.8 4427.3 4451.3 4530.6 4677.7 4682.4 3136.6 3335.1 3335.7 3483.9 3488.1 505.5 620.1 625.6 626.6 630.0 526.8 535.9 624.8 630.5 651.5 7949.0 8054.8 8622.0 8763.3 9132.5 528.6 581.7 617.9 626.8 631.9 3912.6 3955.1 4026.4 4160.9 4237.5 146198.0 147204.3 152501.0 154054.0 164797.4 120586.0 134175.0 134406.3 137273.0 137304.0 68570.0 68692.7 89278.0 111467.4 116592.4 8231.9 8317.5 8556.2 8640.6 8756.8 59743.4 61220.0 63643.0 63851.7 64672.4 96923.5 97862.0 100970.0 101137.0 102287.0 11298.9 11465.7 11554.1 11631.8 12045.3 12216.9 12223.1 12356.1 12363.6 12540.9 11664.7 11818.9 11825.5 11886.7 12399.4 11988.6 13283.1 13581.7 13784.9 13791.2 13266.7 13448.3 13582.5 13779.5 14270.5 10706.6 11179.9 11693.4 11967.7 12019.5 10098.4 10231.9 10995.9 11200.8 11401.4 12:32:25 INFO - PROCESS | 1917 | -------- Summary: end -------- 12:32:25 INFO - PROCESS | 1917 | 12:32:26 INFO - TEST-INFO | 1917: exit 0 12:32:26 INFO - TEST-OK | dromaeo_css | took 1118050ms 12:32:26 INFO - TEST-START | kraken 12:32:26 INFO - operating with platform_type : mac_ 12:32:26 INFO - Initialising browser for kraken test... 12:32:26 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpzHwBWz/profile http://localhost:53147/getInfo.html) 12:32:28 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 12:32:28 INFO - PROCESS | 1921 | colorDepth:24 12:32:28 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 12:32:28 INFO - PROCESS | 1921 | __metrics 12:32:28 INFO - PROCESS | 1921 | 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:32:28 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:32:29 INFO - TEST-INFO | 1921: exit 0 12:32:29 INFO - Browser initialized. 12:32:29 INFO - Running cycle 1/1 for kraken test... 12:32:29 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpzHwBWz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:32:41 INFO - PROCESS | 1922 | RSS: Main: 228261888 12:32:41 INFO - PROCESS | 1922 | 12:32:43 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53147/tests/kraken/driver.html?testName=audio-beat-detection) 12:32:44 INFO - PROCESS | 1922 | RSS: Main: 285179904 12:32:44 INFO - PROCESS | 1922 | 12:32:47 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53147/tests/kraken/driver.html?testName=audio-dft) 12:32:47 INFO - PROCESS | 1922 | RSS: Main: 294084608 12:32:47 INFO - PROCESS | 1922 | 12:32:51 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53147/tests/kraken/driver.html?testName=audio-fft) 12:32:52 INFO - PROCESS | 1922 | RSS: Main: 387690496 12:32:52 INFO - PROCESS | 1922 | 12:32:54 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53147/tests/kraken/driver.html?testName=audio-oscillator) 12:32:54 INFO - PROCESS | 1922 | RSS: Main: 288342016 12:32:54 INFO - PROCESS | 1922 | 12:32:56 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53147/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:32:57 INFO - PROCESS | 1922 | RSS: Main: 292470784 12:32:57 INFO - PROCESS | 1922 | 12:33:00 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53147/tests/kraken/driver.html?testName=imaging-darkroom) 12:33:00 INFO - PROCESS | 1922 | RSS: Main: 331280384 12:33:00 INFO - PROCESS | 1922 | 12:33:03 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53147/tests/kraken/driver.html?testName=imaging-desaturate) 12:33:04 INFO - PROCESS | 1922 | RSS: Main: 332537856 12:33:04 INFO - PROCESS | 1922 | 12:33:07 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53147/tests/kraken/driver.html?testName=json-parse-financial) 12:33:07 INFO - PROCESS | 1922 | RSS: Main: 329797632 12:33:07 INFO - PROCESS | 1922 | 12:33:09 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53147/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:33:10 INFO - PROCESS | 1922 | RSS: Main: 301895680 12:33:10 INFO - PROCESS | 1922 | 12:33:11 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:33:12 INFO - PROCESS | 1922 | RSS: Main: 308207616 12:33:12 INFO - PROCESS | 1922 | 12:33:14 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:33:15 INFO - PROCESS | 1922 | RSS: Main: 301314048 12:33:15 INFO - PROCESS | 1922 | 12:33:18 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:33:19 INFO - PROCESS | 1922 | RSS: Main: 314126336 12:33:19 INFO - PROCESS | 1922 | 12:33:22 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:33:22 INFO - PROCESS | 1922 | RSS: Main: 315432960 12:33:22 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:53147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53147/tests/kraken/driver.html?testName=ai-astar) 12:33:24 INFO - PROCESS | 1922 | RSS: Main: 358871040 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | __start_tp_report 12:33:24 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 12:33:24 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 12:33:24 INFO - PROCESS | 1922 | |i|pagename|runs| 12:33:24 INFO - PROCESS | 1922 | |0;ai-astar;102;113;112;114;108;114;112;114;113;113 12:33:24 INFO - PROCESS | 1922 | |1;audio-beat-detection;159;139;138;167;108;128;129;107;158;105 12:33:24 INFO - PROCESS | 1922 | |2;audio-dft;184;184;184;183;185;185;202;225;183;182 12:33:24 INFO - PROCESS | 1922 | |3;audio-fft;76;78;56;88;60;58;60;60;98;56 12:33:24 INFO - PROCESS | 1922 | |4;audio-oscillator;81;91;94;70;71;68;97;70;68;104 12:33:24 INFO - PROCESS | 1922 | |5;imaging-gaussian-blur;88;86;89;87;89;86;90;106;111;85 12:33:24 INFO - PROCESS | 1922 | |6;imaging-darkroom;95;95;95;115;147;101;95;97;94;95 12:33:24 INFO - PROCESS | 1922 | |7;imaging-desaturate;86;86;83;85;87;81;88;84;84;85 12:33:24 INFO - PROCESS | 1922 | |8;json-parse-financial;55;53;56;56;53;55;53;53;54;54 12:33:24 INFO - PROCESS | 1922 | |9;json-stringify-tinderbox;65;75;75;73;70;67;68;69;68;68 12:33:24 INFO - PROCESS | 1922 | |10;stanford-crypto-aes;86;83;84;77;80;88;95;94;106;105 12:33:24 INFO - PROCESS | 1922 | |11;stanford-crypto-ccm;139;139;138;140;139;140;147;138;161;152 12:33:24 INFO - PROCESS | 1922 | |12;stanford-crypto-pbkdf2;228;187;168;167;168;169;170;165;167;163 12:33:24 INFO - PROCESS | 1922 | |13;stanford-crypto-sha256-iterative;68;67;68;67;66;68;68;67;66;67 12:33:24 INFO - PROCESS | 1922 | __end_tp_report 12:33:24 INFO - PROCESS | 1922 | __start_cc_report 12:33:24 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,2077 12:33:24 INFO - PROCESS | 1922 | __end_cc_report 12:33:24 INFO - PROCESS | 1922 | __startTimestamp1471548804627__endTimestamp 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | ------- Summary: start ------- 12:33:24 INFO - PROCESS | 1922 | Number of tests: 14 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.78 (3.3%) stddev-sans-first:1.88 12:33:24 INFO - PROCESS | 1922 | Values: 102.0 113.0 112.0 114.0 108.0 114.0 112.0 114.0 113.0 113.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:138.50 stddev:22.70 (16.4%) stddev-sans-first:22.17 12:33:24 INFO - PROCESS | 1922 | Values: 159.0 139.0 138.0 167.0 108.0 128.0 129.0 107.0 158.0 105.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#2] audio-dft Cycles:10 Average:189.70 Median:184.50 stddev:13.70 (7.4%) stddev-sans-first:14.37 12:33:24 INFO - PROCESS | 1922 | Values: 184.0 184.0 184.0 183.0 185.0 185.0 202.0 225.0 183.0 182.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#3] audio-fft Cycles:10 Average:69.00 Median:68.00 stddev:15.03 (22.1%) stddev-sans-first:15.73 12:33:24 INFO - PROCESS | 1922 | Values: 76.0 78.0 56.0 88.0 60.0 58.0 60.0 60.0 98.0 56.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:76.00 stddev:13.87 (18.3%) stddev-sans-first:14.71 12:33:24 INFO - PROCESS | 1922 | Values: 81.0 91.0 94.0 70.0 71.0 68.0 97.0 70.0 68.0 104.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:87.50 stddev:9.07 (10.4%) stddev-sans-first:9.52 12:33:24 INFO - PROCESS | 1922 | Values: 88.0 86.0 89.0 87.0 89.0 86.0 90.0 106.0 111.0 85.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#6] imaging-darkroom Cycles:10 Average:102.90 Median:95.00 stddev:16.74 (17.6%) stddev-sans-first:17.51 12:33:24 INFO - PROCESS | 1922 | Values: 95.0 95.0 95.0 115.0 147.0 101.0 95.0 97.0 94.0 95.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:2.02 (2.4%) stddev-sans-first:2.11 12:33:24 INFO - PROCESS | 1922 | Values: 86.0 86.0 83.0 85.0 87.0 81.0 88.0 84.0 84.0 85.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.23 (2.3%) stddev-sans-first:1.27 12:33:24 INFO - PROCESS | 1922 | Values: 55.0 53.0 56.0 56.0 53.0 55.0 53.0 53.0 54.0 54.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:69.50 stddev:3.43 (4.9%) stddev-sans-first:3.16 12:33:24 INFO - PROCESS | 1922 | Values: 65.0 75.0 75.0 73.0 70.0 67.0 68.0 69.0 68.0 68.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:85.00 stddev:9.98 (11.7%) stddev-sans-first:10.49 12:33:24 INFO - PROCESS | 1922 | Values: 86.0 83.0 84.0 77.0 80.0 88.0 95.0 94.0 106.0 105.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:140.00 stddev:7.72 (5.5%) stddev-sans-first:8.03 12:33:24 INFO - PROCESS | 1922 | Values: 139.0 139.0 138.0 140.0 139.0 140.0 147.0 138.0 161.0 152.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:168.50 stddev:19.67 (11.7%) stddev-sans-first:6.95 12:33:24 INFO - PROCESS | 1922 | Values: 228.0 187.0 168.0 167.0 168.0 169.0 170.0 165.0 167.0 163.0 12:33:24 INFO - PROCESS | 1922 | 12:33:24 INFO - PROCESS | 1922 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 12:33:24 INFO - PROCESS | 1922 | Values: 68.0 67.0 68.0 67.0 66.0 68.0 68.0 67.0 66.0 67.0 12:33:24 INFO - PROCESS | 1922 | -------- Summary: end -------- 12:33:24 INFO - PROCESS | 1922 | 12:33:25 INFO - TEST-INFO | 1922: exit 0 12:33:25 INFO - TEST-OK | kraken | took 59437ms 12:33:25 INFO - SUITE-END | took 1177s 12:33:25 INFO - Completed test suite (00:19:37) 12:33:25 INFO - javascript benchmark 12:33:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.602127744671, 3343.5987681478227, 3362.562866827313, 3385.77992430492, 3392.745544084194, 5567.693776842272, 5655.971720141399, 5657.830265092054, 5707.315122185339, 5733.254676892004, 5663.971680141593, 5718.971405142968, 5722.54219662426, 5723.942760572393, 5753.65478071315, 5172.45689202633, 5201.453847346031, 5224.764885580158, 5229.163333866582, 5277.472252774711, 5217.5565076968505, 5233.685978841263, 5247.921281180786, 5266.789328426858, 5298.36419629645, 5167.302414174094, 5179.533841954223, 5205.739713014353, 5205.973970130144, 5276.445973172819, 4403.779811009436, 5236.764345604457, 5252.159654455307, 5271.710055946922, 5283.973580132113, 5130.127878260705, 5131.743412829381, 5144.022635699205, 5171.4311425743135, 5194.63637545372, 5669.716514174274, 5680.289963754531, 5687.800926967578, 5703.230063941398, 5721.284839395075, 6293.46505547029, 6296.496280297565, 6302.338254483259, 6303.212098487689, 6318.557700960934, 6159.445649891486, 6226.034964580497, 6227.346128656516, 6289.842753931142, 6318.4629306509005, 5870.970645146833, 5871.853203669857, 5906.114082887515, 5909.054551271776, 5909.556783241274, 6268.435840774352, 6280.214973128359, 6284.654344011124, 6288.434040936338, 6289.528285378615, 5978.432048955342, 6043.153958445821, 6099.3900609938655, 6115.266168059861, 6173.382661733791, 6291.96854015727, 6299.590526615755, 6313.463355614732, 6319.115323854664, 6333.050042493663, 5053.974730126326, 6260.436560709558, 6263.279722831932, 6269.0, 6283.7800676976085, 6095.939040609537, 6105.247473116927, 6273.968630156911, 6279.152314437584, 6293.244810622663, 4887.242477416006, 4889.364382630235, 4943.085529177114, 4947.579455746302, 4984.476629953875, 4402.977985110054, 4409.206342858316, 4420.447444069491, 4433.202023635712, 4457.996950686134, 5709.200711900337, 5782.88434231313, 5801.3038435388025, 5808.361080281165, 5858.121281807763, 6195.535334849814, 6216.720247588869, 6235.906461403082, 6241.438270555672, 6281.0, 5764.99112655292, 5773.162891380726, 5781.364049954502, 5807.448292412214, 5823.94176058234, 5347.491988261109, 5417.160340147283, 5445.945540544623, 5448.45515448452, 5452.236686863842, 6202.782902598387, 6208.627482351073, 6270.592411493239, 6270.686465676789, 6334.3665633436285, 6297.0, 6307.873842523216, 6313.273973493107, 6314.305426403183, 6342.524310676626, 6225.688715564294, 6298.811035668938, 6300.7479700811455, 6317.178766760292, 6569.0, 6092.177556030057, 6128.717018009172, 6189.938100618936, 6215.502759779117, 6252.749890004349, 6225.750969961151, 6300.621962682254, 6309.274433440213, 6312.084747711648, 6321.0, 5962.2249107615735, 6118.449339559461, 6138.232720909887, 6144.354842741626, 6155.1382806406245, 5946.791862284799, 6054.757809687563, 6084.148219249397, 6119.816405507842, 6123.479504242189, 6039.275286965592, 6064.090386441892, 6076.270847498328, 6082.817515474543, 6083.391660833881, 6114.174586430775, 6132.736347182667, 6154.046122850965, 6169.691515424301, 6191.1641928339095, 4897.681650692836, 5759.395263497272, 5902.970485147547, 5953.880922381615, 5966.403359663999], "value": 5708.180788558387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.1288870479147, 471.94100737407825, 484.4888642482182, 524.8031988004499, 529.978800847966, 30907.845460772693, 31348.529772053407, 32618.67381326186, 32652.67347326526, 32653.836730816372, 27633.58549621752, 43041.35437968425, 43921.34117988225, 43978.780106099504, 46067.30899036517, 9786.804263914735, 9927.205823534136, 9935.552900119475, 9940.353876997971, 9941.254405919548, 9777.804443911118, 9881.20950323976, 9888.604455821758, 9890.208783297356, 9924.652637157666, 9863.852042219372, 9895.50522473877, 9915.801683966316, 9916.702498925008, 9928.95035524825, 9800.90199098007, 9877.753056173582, 9883.90116098837, 9886.011398860128, 9894.851577226347, 9763.218942484655, 9841.803163936682, 9851.458169800659, 9852.556634951445, 9860.0, 41382.37926431106, 41675.791621042, 41680.0, 41687.166256674696, 41699.7915010426, 3699.667029967316, 3816.809159542012, 4112.691548133872, 4126.834926602933, 4289.077848262638, 31297.061088167393, 32453.6754632453, 32547.674523254937, 32826.50760238598, 32838.0, 2825.420788738324, 3224.3551289742145, 3334.8832790852202, 3353.295807880348, 3464.7055000325004, 467052.0, 473827.9394633294, 475856.0, 476802.0, 479397.0, 59737.70131149316, 60085.79828403408, 60111.0, 60136.097958530656, 60230.0, 34436.48345274823, 35084.29831403358, 35173.0, 36407.81796091056, 63357.683211583644, 13838.446462141603, 16402.589935251475, 16544.172791360383, 16571.0, 16575.088370139758, 12738.0, 12814.743705126031, 12820.487180512862, 12820.61538153857, 12833.807492887427, 19121.043947802555, 19186.328478503186, 19201.807981920003, 19206.711899321523, 19230.0, 6687.598744075371, 7772.922270777333, 7855.607219638995, 7880.64537095832, 7938.325242354349, 888.6800751729372, 955.7753927826955, 980.5930538826307, 984.9704508864745, 1003.7340104872214, 28752.0, 29718.622510199566, 30016.249593759894, 30173.849130754646, 31150.844245778626, 974.6744427578487, 1043.1081425381342, 1061.9221490187465, 1102.2614848051824, 1142.2175809570472, 15211.391544338103, 15735.44925927587, 15755.133467659389, 15821.604459888595, 16193.352265909467, 666350.3649635095, 670905.6454717793, 674033.0, 674526.2547374463, 675995.0, 58921.0, 59423.811523769284, 59434.70282648559, 59477.107843382386, 59515.0, 1242.105683907585, 1288.8195652608642, 1359.5785306555, 1393.2406838273166, 1418.524794193936, 30289.394212116076, 31297.8435107823, 31312.9040483582, 31608.0, 32080.83959580187, 51053.74473127611, 51530.48469515257, 51553.48446515637, 51567.0, 51630.0, 457439.0, 458076.0, 458458.0, 459298.0, 460521.0, 23192.884035579715, 26287.0, 27024.64876756115, 27029.86485067562, 27479.587806183325, 47102.05795884132, 47925.0, 48493.75753121212, 48614.756926215145, 48741.0, 38921.80539097286, 39040.60959390483, 39485.60514394934, 40175.79912100421, 40399.990000250225, 38847.41728874013, 39210.80394598123, 39310.0, 40364.0, 40884.182316354105, 38134.62806449639, 39145.60854391533, 39243.01892452712, 39384.40923386209, 39444.0, 28730.0, 30381.69618303877, 34128.82935585405, 34216.65783342135, 34324.0, 25141.994320226986, 25244.495110097327, 25298.620520691835, 25581.060767670424, 25717.871410642827], "value": 19774.750180784795, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.1815120936566, 347.1581415068458, 367.9117011917141, 369.74672349440635, 374.7376836214649, 15559.455419060321, 17563.38528151515, 20613.587728245428, 20713.067911943963, 21121.68317475239, 20103.698444523307, 20617.587648247027, 20719.0, 20982.16071357144, 21444.678329825067, 5875.089361149007, 5896.05663093904, 6031.819045428622, 6135.8772824543485, 6141.631502109865, 5865.413458654143, 6011.128386383972, 6022.909656355158, 6030.246219222598, 6031.547633927451, 4150.481189851269, 6431.163948686664, 6686.431653309474, 6732.485190832048, 6838.760643377483, 5778.42215778423, 6469.0, 6674.69963851623, 6739.663016849163, 6749.595024298558, 5848.09936770547, 5865.4134586541, 5866.413358664142, 5872.559558033165, 5882.647041177543, 22430.0, 26574.86712566444, 27010.0, 27241.455170896472, 27298.5905211422, 2374.15717420315, 2791.6231308773463, 3133.6552979172275, 3164.620245570546, 3231.8222497762613, 12626.936865315614, 14810.481633142788, 15680.451184208612, 16355.427560035341, 16733.497995060166, 1618.8947718398383, 1694.2799310293199, 1749.475157452759, 1843.6687525331915, 1936.0610104099642, 89580.55209723975, 115448.0, 116618.41690791577, 117451.82548174495, 118345.0, 10103.191744660557, 10689.984451477098, 10697.946510267398, 10972.122230221563, 11012.449377531091, 13861.376238069312, 13868.861311386758, 13886.513972010931, 13892.513762018281, 13909.095908765898, 8401.915980840113, 8652.264557512555, 8689.435186712843, 8799.824003519894, 8839.779005524784, 8703.260222881, 8727.389082764272, 8732.170443807829, 8890.86663700045, 8990.910090899139, 6698.119808482302, 6821.658917054127, 6828.2488926218075, 6847.726090956306, 6853.451723862079, 1797.739327797462, 1844.603410266799, 1845.4648152035759, 1845.4924895653535, 1855.2300795170124, 707.4906067631297, 847.013229587536, 852.3351785607236, 908.3596064774318, 969.9757506062405, 20487.487812804502, 21762.89118554397, 22645.320640380814, 22681.0, 22764.317070487912, 867.8351113288455, 942.4910548303839, 988.1501908358806, 1005.8181636576993, 1025.8409946458312, 10978.0, 11114.499847506882, 11371.715707107223, 12108.878911210775, 12134.878651213552, 586418.0, 592021.0, 595500.0, 602724.9863750741, 602907.0, 108711.45644271727, 113484.0, 113991.0, 115283.0, 117945.0, 974.9616658258932, 997.6607953295915, 1035.9015893490107, 1037.4294138223947, 1037.9481025948671, 16017.67964640724, 19576.314828980918, 19614.0, 19773.60452790965, 20419.693704594145, 53966.0, 54391.0, 54753.45246547643, 55083.0, 55603.72198139145, 73868.0, 74325.0, 75760.62119689367, 76125.0, 76251.23748762663, 7968.721094761656, 8018.3585313173635, 8344.37417193725, 8361.874571881304, 8537.658493660185, 9073.00393913338, 12848.229106253655, 12937.676558086123, 13210.141340812816, 13475.989300802283, 10982.176336774613, 11238.381888995857, 11254.38100904458, 11260.549578016788, 11340.108272390487, 5077.213031980049, 7525.720627493449, 10079.24405669563, 11053.502592383524, 11365.772684546097, 10892.183086268404, 11454.70905818823, 11552.768944621228, 11679.124065695276, 11894.0, 6727.730890764315, 7854.764357069297, 7982.361411087215, 8329.625166867627, 8342.916570834213, 10511.579536818748, 11004.174686898676, 11308.547658093585, 11334.943325283652, 11402.087832973177], "value": 9792.430949698653, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.5337971918561, 1563.7341651919173, 1727.9827201727987, 1875.3436297295948, 1878.6806242938699, 8385.496870187786, 8948.14992575706, 8956.507392093432, 8964.820703585932, 8983.101689831014, 7837.373010159188, 8079.272865442109, 8487.533185674802, 8720.95639521803, 8757.60590773415, 2049.9692504612444, 2341.899307325554, 2348.788609025192, 2382.094803974487, 2396.880155992202, 2341.9414514637183, 2355.7879790818824, 2386.713594368678, 2388.2715771689627, 2394.030417680842, 2344.460774021976, 2360.492494113765, 2370.7866292033714, 2384.082128380577, 2385.654080158376, 1983.424806806039, 2340.169239919838, 2352.3178278299274, 2354.0, 2362.6692263083096, 2232.7543970163433, 2242.338510139513, 2242.9887850560644, 2244.2706120510898, 2250.3923940535965, 8734.301255899512, 8950.86573701395, 8951.14964078418, 8960.23837973773, 8986.910130898672, 4586.839460618862, 5200.167973124319, 5368.0605893968395, 5382.542483888874, 5383.138697808332, 3485.5120283160377, 3538.7876727396438, 3657.7805331680183, 3697.1126929536986, 3707.59216486189, 1040.8334666453406, 1079.578964203961, 1085.6037546295624, 1103.9944800276028, 1105.8064838653283, 9844.65543705974, 11525.423728813526, 11730.70673233159, 11735.23720958135, 11777.293362398283, 3971.212954170577, 4669.579737823612, 4781.474037855833, 4783.928241076386, 4807.61539076873, 9902.504874756234, 9902.900970990342, 9904.60381584743, 9906.851397229048, 9937.552810123561, 5302.390225124083, 5303.098473259555, 5315.043292207362, 5317.069512835238, 5322.520973112361, 4377.430933978562, 4532.773361331921, 4730.550597693283, 4836.0, 4877.414710234794, 3828.1769419574925, 3875.6511913927884, 3953.6639385651965, 3995.700322475826, 4002.639762421396, 1286.408252203978, 1298.2210673595766, 1298.7662220800157, 1312.3700623700486, 1313.7175507265983, 839.5550358310105, 887.6316328723508, 926.5691453474167, 926.8007378413673, 976.814405263012, 5674.914876276859, 5933.317668468095, 5981.491573216325, 5999.970000149971, 6030.7889223876955, 421.16188784318985, 433.6816079119442, 438.6468892541471, 450.2435907675137, 458.4635975908195, 3644.942966539727, 3743.513343265358, 3919.941200881989, 4038.5355684096116, 4045.130296986162, 10403.219758518142, 10606.734831629272, 10727.83908241377, 10737.0, 10750.94624526888, 10669.946650266698, 10677.78644427107, 10678.985496377833, 10684.358938463716, 10688.572457101629, 6986.895196572055, 8150.4294699370475, 8241.711540096068, 8248.46384984986, 8249.670013199524, 5671.149327600893, 5717.313922329347, 5870.207521984477, 5907.704614769158, 5924.229850119457, 8874.778130546789, 9458.43249405038, 9973.052560006789, 9973.950130249303, 10047.397156170653, 8992.235659968976, 9829.508524573886, 10234.078932896075, 10338.483075846027, 10351.43067131315, 6890.379865812101, 7138.321859423347, 7151.82120446993, 7512.849743005218, 7563.924360756542, 7951.204879512003, 8047.87928181066, 8058.3150432213915, 8224.971878515185, 8483.236508714246, 7266.927330726625, 7544.434167437574, 7561.0, 7595.088589369311, 7694.846103077795, 7227.2772722729405, 7359.006534117825, 7446.292602202782, 7446.515976461513, 7537.170911199654, 7059.5058345915295, 7060.399866011241, 7146.3568278855155, 7153.141623005273, 7157.391621712213, 4927.531884470969, 4953.083679519301, 4983.053219888209, 4994.400671919393, 5083.262926875656, 4951.3810773653295, 5001.899962000813, 5060.443351231435, 5061.114304996538, 5258.579313654822], "value": 4479.57883945052, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.3296089385476, 286.3257029695068, 299.80362862325177, 310.1702944623133, 312.62953400220727, 4484.798184081716, 4590.472095708995, 4870.464248932614, 5068.226120857698, 5327.840164795058, 4499.032707967786, 4788.616910647149, 5043.344365232515, 5172.189733593241, 5297.4437684043105, 3373.578302712161, 3698.519192504977, 4031.6976226782963, 4032.3145065338817, 4040.525208298979, 4066.6543343815665, 4067.125568002879, 4083.305838007546, 4097.016715988171, 4106.5072191337085, 3798.753081049737, 3881.7476864003893, 3934.3705007198855, 3989.9227208653647, 4024.1146947671464, 3865.9806700966315, 4004.979975100135, 4020.396940458925, 4047.7571345719352, 4074.921789887164, 2779.069011881022, 2965.1252880400116, 3041.285297954979, 3066.723994840475, 3081.9075427737203, 4269.658427325807, 4477.865664030084, 4796.112719146969, 5150.871228219325, 5209.348831396075, 3833.7124715646437, 4544.091181763661, 4559.475660299075, 4573.405457290531, 4588.151192029485, 4538.52345503724, 5036.697798132087, 5583.8604034899045, 5626.803061892855, 5658.0381335173115, 3303.9174020649434, 3659.0120667419915, 3821.4458903458844, 3832.1760821423527, 3847.076701591626, 5491.313585801815, 5658.708223169623, 5706.543476521868, 5721.6853073080965, 5794.97102514489, 3672.192117734094, 3714.8699795507027, 3840.8271627776817, 3845.4616353710503, 3846.519185101862, 3613.638636136418, 3743.970408137784, 3885.2229554089295, 3886.8833934981994, 3912.787036018843, 3800.410936304877, 4018.3771515415156, 4269.466316710411, 4368.951451651581, 4506.504284528699, 5389.326334208224, 5438.265834112423, 5540.612157148961, 5555.77776888928, 5662.320521537359, 1152.7291086594644, 1308.5997982159188, 1320.570814485296, 1328.4686125549858, 1329.9867001330056, 745.3478206569251, 881.1761003461784, 893.9329550283754, 898.1557336104172, 900.8063266397756, 4295.153084173835, 5051.686561494038, 5090.567301779316, 5105.15764898795, 5165.819196328111, 5152.948470515247, 5667.4615911488345, 5674.2977106867775, 5695.8860822784145, 5750.798722044792, 4737.928931066037, 5076.289319495274, 5102.821401250939, 5127.769250383667, 5214.765335559909, 2146.495573540217, 2381.9880900595385, 2536.885840137198, 2543.427728761014, 2568.0210955292146, 4317.676174286941, 4505.43682039745, 4675.57919787214, 4795.736234507136, 4831.376467649263, 4210.32634778434, 4373.781310934441, 4708.717476951394, 4842.661013729075, 4942.555170034714, 4466.548371779119, 4692.199318551984, 4849.733264670406, 4875.609951203895, 4909.367068369381, 4023.376376661622, 4138.13099249149, 4586.958803332485, 4818.542238487338, 4916.582090522345, 4545.636189258217, 4656.18408820567, 4664.55686709762, 4736.5973892219545, 5038.496150384932, 5219.634625576173, 5795.275590551181, 5890.7349169288345, 5974.014287642491, 6030.819075427744, 3081.6918308169, 3704.9073773155887, 3707.277080969293, 3760.417135344026, 3811.352070148137, 4972.6076698524475, 5660.29246344207, 5718.199452076629, 5783.768649253982, 5789.913151302818, 4872.6832755870755, 5493.780248790164, 5558.333000039859, 5654.575906807088, 5666.345207341159, 5432.483914028161, 5473.728402712989, 5486.939664648052, 5492.642978206563, 5498.890022199614, 4987.875303117451, 5395.298611180521, 5424.267723857387, 5451.972740136274, 5556.888862222814, 4558.794854231502, 5081.5426611605135, 5245.606579506629, 5259.289995850511, 5525.475079867406, 4476.306172543261, 5131.87170320745, 5325.973370133124, 5372.355317361941, 5514.972425137848], "value": 3886.4002851389255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.1999001123736, 721.2102747491498, 722.7362012865306, 768.5388766739957, 823.074041703084, 15286.388544458223, 16335.101569413677, 16335.754963675554, 16346.34614615417, 16356.918215408936, 14628.414863405478, 14805.629859253524, 14810.851891481081, 14817.481388151416, 14817.92591037046, 660.9306022867602, 676.6887231873319, 690.5304393012761, 822.6257053040852, 837.6146972392706, 831.8752187171912, 840.2018082821344, 841.6086519768306, 844.3245403677065, 844.932405407566, 878.4202426398579, 882.2016075451727, 882.5631312500321, 883.5626364949327, 885.4554449013842, 690.4200471603826, 852.8635418333037, 876.5441970175505, 876.7501262140291, 885.6014793342957, 662.6620423583959, 663.3101574362657, 664.4219529009744, 665.7902760630386, 666.6166954001469, 14990.175540345277, 15017.924910375488, 15023.549293521211, 15029.774553381709, 15039.624009399744, 13928.791068133985, 14082.788758168635, 14201.857981420157, 14314.069585477015, 14348.282585870666, 12897.2906490143, 13027.934860325639, 13123.475060997644, 13277.203367797867, 13689.589312320646, 4715.575598196145, 4718.386609740712, 4842.491538388454, 4943.1102401567705, 4944.258361245806, 71001.64499177523, 111800.0, 114576.0, 116840.15799210005, 118815.40592297069, 11183.32900026001, 11280.661580152444, 11299.096072314194, 11519.942400287944, 11791.941040294743, 18348.541286467676, 22647.773522264895, 22689.319320420414, 22750.544989100126, 22766.31701048971, 7962.92500512435, 8040.15578364275, 8044.2760151586635, 8098.31164351025, 8286.8342633147, 4427.335899615019, 4451.3100469426645, 4530.569595888385, 4677.695949763254, 4682.4381074271305, 3136.5765621641244, 3335.1328654549993, 3335.682405449834, 3483.8606455741487, 3488.0930957951114, 505.48945564979635, 620.1101419463085, 625.5714835337808, 626.5676683088745, 629.9874002519924, 526.7550588976145, 535.8901425207823, 624.8250489862845, 630.5302549600485, 651.5048563092084, 7948.960255198803, 8054.838903221903, 8622.0, 8763.342749293828, 9132.49771262587, 528.5586535243115, 581.7353104337516, 617.8733359661259, 626.7806267806322, 631.8831016262006, 3912.6087391260644, 3955.149642826806, 4026.355783074694, 4160.895977600524, 4237.491501019836, 146198.0, 147204.26397868156, 152501.0, 154054.0, 164797.3520264806, 120586.0, 134175.0, 134406.32796835955, 137273.0, 137304.0, 68570.0, 68692.656536717, 89278.0, 111467.44266278617, 116592.41703791427, 8231.917680823115, 8317.542535160383, 8556.229939305493, 8640.611172497378, 8756.824863502821, 59743.40256597552, 61220.0, 63643.0, 63851.68074159599, 64672.35327646663, 96923.51538242264, 97862.0, 100970.0, 101137.0, 102287.0, 11298.9266019728, 11465.656030319104, 11554.133439992202, 11631.76736465283, 12045.33750643723, 12216.877831221575, 12223.144379893321, 12356.073294502768, 12363.62909112728, 12540.937295313464, 11664.6500604982, 11818.940905295418, 11825.526978920747, 11886.692463829146, 12399.380030998595, 11988.580399685969, 13283.136596121221, 13581.701957651056, 13784.931075344559, 13791.241481718602, 13266.668333291746, 13448.327583620976, 13582.52461163894, 13779.517716879862, 14270.500532481728, 10706.601940782637, 11179.88820111821, 11693.356865372487, 11967.700807479534, 12019.535995120805, 10098.394096354243, 10231.874493805824, 10995.945020275167, 11200.831987520356, 11401.429928503707], "value": 7660.510896551311, "unit": "score"}], "name": "dromaeo_css", "value": 7268.282937785127, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 114.0, 108.0, 114.0, 112.0, 114.0, 113.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 139.0, 138.0, 167.0, 108.0, 128.0, 129.0, 107.0, 158.0, 105.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 183.0, 185.0, 185.0, 202.0, 225.0, 183.0, 182.0], "value": 189.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 56.0, 88.0, 60.0, 58.0, 60.0, 60.0, 98.0, 56.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 91.0, 94.0, 70.0, 71.0, 68.0, 97.0, 70.0, 68.0, 104.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 106.0, 111.0, 85.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 115.0, 147.0, 101.0, 95.0, 97.0, 94.0, 95.0], "value": 102.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 85.0, 87.0, 81.0, 88.0, 84.0, 84.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 56.0, 56.0, 53.0, 55.0, 53.0, 53.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 75.0, 73.0, 70.0, 67.0, 68.0, 69.0, 68.0, 68.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 77.0, 80.0, 88.0, 95.0, 94.0, 106.0, 105.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 138.0, 140.0, 139.0, 140.0, 147.0, 138.0, 161.0, 152.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 187.0, 168.0, 167.0, 168.0, 169.0, 170.0, 165.0, 167.0, 163.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 66.0, 68.0, 68.0, 67.0, 66.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1464.4, "alertThreshold": 2.0}]} 12:33:25 INFO - Return code: 0 12:33:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:33:25 INFO - # TBPL SUCCESS # 12:33:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:33:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:33:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:33:25 INFO - 2016-08-18 12:33:25.933 screenresolution[1926:20114] starting screenresolution argv=screenresolution get 12:33:25 INFO - 2016-08-18 12:33:25.936 screenresolution[1926:20114] Display 0: 1600x1200x32@60 12:33:25 INFO - 2016-08-18 12:33:25.942 screenresolution[1927:20117] starting screenresolution argv=screenresolution list 12:33:25 INFO - Available Modes on Display 0 12:33:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:33:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:33:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:33:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:33:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:33:26 INFO - Intel Iris: 12:33:26 INFO - Chipset Model: Intel Iris 12:33:26 INFO - Type: GPU 12:33:26 INFO - Bus: Built-In 12:33:26 INFO - VRAM (Dynamic, Max): 1536 MB 12:33:26 INFO - Vendor: Intel (0x8086) 12:33:26 INFO - Device ID: 0x0a2e 12:33:26 INFO - Revision ID: 0x0009 12:33:26 INFO - Displays: 12:33:26 INFO - Display: 12:33:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:33:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:33:26 INFO - Display Serial Number: 1600x1200 60 12:33:26 INFO - Main Display: Yes 12:33:26 INFO - Mirror: Off 12:33:26 INFO - Online: Yes 12:33:26 INFO - Rotation: Supported 12:33:26 INFO - Return code: 0 12:33:26 INFO - Running post-action listener: _resource_record_post_action 12:33:26 INFO - [mozharness: 2016-08-18 19:33:26.181224Z] Finished run-tests step (success) 12:33:26 INFO - Running post-run listener: _resource_record_post_run 12:33:26 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1089536; Write bytes: 704967168; Read time: 11; Write time: 1999 12:33:26 INFO - TinderboxPrint: CPU usage
53.6% 12:33:26 INFO - TinderboxPrint: I/O read bytes / time
1,089,536 / 11 12:33:26 INFO - TinderboxPrint: I/O write bytes / time
704,967,168 / 1,999 12:33:26 INFO - TinderboxPrint: CPU idle
2,249.9 (46.4%) 12:33:26 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 12:33:26 INFO - TinderboxPrint: CPU user
1,628.3 (33.6%) 12:33:26 INFO - TinderboxPrint: Swap in / out
406,638,592 / 0 12:33:26 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400757248; Write bytes: 394618880; Read time: 29700; Write time: 856 12:33:26 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1036288; Write bytes: 295035904; Read time: 9; Write time: 1092 12:33:26 INFO - Running post-run listener: _upload_blobber_files 12:33:26 INFO - Blob upload gear active. 12:33:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:33:26 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:33:26 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:33:26 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:33:27 INFO - (blobuploader) - INFO - Open directory for files ... 12:33:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:33:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:33:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:28 INFO - (blobuploader) - INFO - Done attempting. 12:33:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:33:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:33:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:29 INFO - (blobuploader) - INFO - Done attempting. 12:33:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:33:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:33:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:32 INFO - (blobuploader) - INFO - Done attempting. 12:33:32 INFO - (blobuploader) - INFO - Iteration through files over. 12:33:32 INFO - Return code: 0 12:33:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:33:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:33:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a23f89f446580e8037d93e64123d036ef5bcec233400c9b73b4fe04adf0917ef61e7cc9d6b92fa61d49b86a35aa9decb9e3f752eaaa534fb43e2967c2c88a2cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa182c02a09a57bd006e14982c99c59c7523bc05fb8f31ec848e5f5ec73d3f01ed8244732399ba24e91796be903fe432ce8238090f02177e9d3802e29c56dfc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9892fb83b35dffb80ac76391345a10a5c577ff968a6ab4d92c1e8d359e4dd9959dd69021ba4820628e467b0a697839ea3f6f2db989d9b63659d941170e7bae5"} 12:33:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:33:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:33:32 INFO - Contents: 12:33:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a23f89f446580e8037d93e64123d036ef5bcec233400c9b73b4fe04adf0917ef61e7cc9d6b92fa61d49b86a35aa9decb9e3f752eaaa534fb43e2967c2c88a2cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa182c02a09a57bd006e14982c99c59c7523bc05fb8f31ec848e5f5ec73d3f01ed8244732399ba24e91796be903fe432ce8238090f02177e9d3802e29c56dfc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9892fb83b35dffb80ac76391345a10a5c577ff968a6ab4d92c1e8d359e4dd9959dd69021ba4820628e467b0a697839ea3f6f2db989d9b63659d941170e7bae5"} 12:33:32 INFO - Running post-run listener: copy_logs_to_upload_dir 12:33:32 INFO - Copying logs to upload dir... 12:33:32 INFO - mkdir: /builds/slave/test/build/upload/logs 12:33:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.058506 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-18 12:33:32.591300) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 12:33:32.594798) ========= 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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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/a23f89f446580e8037d93e64123d036ef5bcec233400c9b73b4fe04adf0917ef61e7cc9d6b92fa61d49b86a35aa9decb9e3f752eaaa534fb43e2967c2c88a2cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa182c02a09a57bd006e14982c99c59c7523bc05fb8f31ec848e5f5ec73d3f01ed8244732399ba24e91796be903fe432ce8238090f02177e9d3802e29c56dfc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9892fb83b35dffb80ac76391345a10a5c577ff968a6ab4d92c1e8d359e4dd9959dd69021ba4820628e467b0a697839ea3f6f2db989d9b63659d941170e7bae5"} build_url:https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/A66FzqQqQze6GpTl2lXGjA/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/a23f89f446580e8037d93e64123d036ef5bcec233400c9b73b4fe04adf0917ef61e7cc9d6b92fa61d49b86a35aa9decb9e3f752eaaa534fb43e2967c2c88a2cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa182c02a09a57bd006e14982c99c59c7523bc05fb8f31ec848e5f5ec73d3f01ed8244732399ba24e91796be903fe432ce8238090f02177e9d3802e29c56dfc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9892fb83b35dffb80ac76391345a10a5c577ff968a6ab4d92c1e8d359e4dd9959dd69021ba4820628e467b0a697839ea3f6f2db989d9b63659d941170e7bae5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 12:33:32.628324) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:33:32.628646) ========= 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.8BpVGEVTPZ/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.d6zv7AuGF3/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.004647 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:33:32.676132) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 12:33:32.676432) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 12:33:32.676743) ========= ========= Total master_lag: 0.18 =========