builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0073 starttime: 1472226587.9 results: success (0) buildid: 20160826063819 builduid: 3a6c257ce43c48329c9be3451026d773 revision: a65b35c8e5b17c2585968974aef1da67a8c56642 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:47.903836) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:47.904262) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:47.904537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:47.928458) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:47.928790) ========= 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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-26 08:49:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.25M=0.001s 2016-08-26 08:49:48 (8.25 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:48.117795) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:48.118104) ========= 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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.020112 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:48.166132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 08:49:48.166436) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a65b35c8e5b17c2585968974aef1da67a8c56642 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a65b35c8e5b17c2585968974aef1da67a8c56642 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VJqpSaqyEs/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.YaMu9uCYo4/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-26 08:49:48,242 truncating revision to first 12 chars 2016-08-26 08:49:48,242 Setting DEBUG logging. 2016-08-26 08:49:48,243 attempt 1/10 2016-08-26 08:49:48,243 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a65b35c8e5b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 08:49:49,448 unpacking tar archive at: mozilla-central-a65b35c8e5b1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.456443 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 08:49:49.645802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:49.646104) ========= script_repo_revision: a65b35c8e5b17c2585968974aef1da67a8c56642 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:49.646481) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:49.646761) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:49:49.666096) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 08:49:49.666362) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VJqpSaqyEs/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.YaMu9uCYo4/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:49:49 INFO - MultiFileLogger online at 20160826 08:49:49 in /builds/slave/test 08:49:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:49:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:49 INFO - {'append_to_log': False, 08:49:49 INFO - 'base_work_dir': '/builds/slave/test', 08:49:49 INFO - 'blob_upload_branch': 'Firefox', 08:49:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:49 INFO - 'branch': 'Firefox', 08:49:49 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:49 INFO - 'config_files': ('talos/mac_config.py',), 08:49:49 INFO - 'default_actions': ('clobber', 08:49:49 INFO - 'read-buildbot-config', 08:49:49 INFO - 'download-and-extract', 08:49:49 INFO - 'populate-webroot', 08:49:49 INFO - 'create-virtualenv', 08:49:49 INFO - 'install', 08:49:49 INFO - 'run-tests'), 08:49:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:49 INFO - 'download_minidump_stackwalk': True, 08:49:49 INFO - 'download_symbols': 'ondemand', 08:49:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:49 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:49 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:49 INFO - 'installer_path': 'installer.exe', 08:49:49 INFO - 'log_level': 'info', 08:49:49 INFO - 'log_name': 'talos', 08:49:49 INFO - 'log_to_console': True, 08:49:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:49:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:49:49 INFO - 'opt_config_files': (), 08:49:49 INFO - 'pip_index': False, 08:49:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:49 INFO - 'cmd': ('bash', 08:49:49 INFO - '-c', 08:49:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:49 INFO - 'enabled': True, 08:49:49 INFO - 'halt_on_failure': False, 08:49:49 INFO - 'name': 'check_screen_resolution'},), 08:49:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:49 INFO - 'cmd': ('bash', 08:49:49 INFO - '-c', 08:49:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:49 INFO - 'enabled': True, 08:49:49 INFO - 'halt_on_failure': False, 08:49:49 INFO - 'name': 'check_screen_resolution'},), 08:49:49 INFO - 'run_cmd_checks_enabled': True, 08:49:49 INFO - 'sps_profile': False, 08:49:49 INFO - 'sps_profile_interval': 0, 08:49:49 INFO - 'suite': 'dromaeojs', 08:49:49 INFO - 'system_bits': '32', 08:49:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:49 INFO - 'title': 't-yosemite-r7-0073', 08:49:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:49 INFO - 'use_talos_json': True, 08:49:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:49 INFO - 'work_dir': 'build'} 08:49:49 INFO - [mozharness: 2016-08-26 15:49:49.862963Z] Running clobber step. 08:49:49 INFO - Running pre-action listener: _resource_record_pre_action 08:49:49 INFO - Running main action method: clobber 08:49:49 INFO - rmtree: /builds/slave/test/build 08:49:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:51 INFO - Running post-action listener: _resource_record_post_action 08:49:51 INFO - [mozharness: 2016-08-26 15:49:51.231513Z] Finished clobber step (success) 08:49:51 INFO - [mozharness: 2016-08-26 15:49:51.231660Z] Running read-buildbot-config step. 08:49:51 INFO - Running pre-action listener: _resource_record_pre_action 08:49:51 INFO - Running main action method: read_buildbot_config 08:49:51 INFO - Using buildbot properties: 08:49:51 INFO - { 08:49:51 INFO - "project": "", 08:49:51 INFO - "product": "firefox", 08:49:51 INFO - "script_repo_revision": "production", 08:49:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:49:51 INFO - "repository": "", 08:49:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:49:51 INFO - "buildid": "20160826063819", 08:49:51 INFO - "pgo_build": "False", 08:49:51 INFO - "basedir": "/builds/slave/test", 08:49:51 INFO - "buildnumber": 44, 08:49:51 INFO - "slavename": "t-yosemite-r7-0073", 08:49:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:49:51 INFO - "platform": "yosemite_r7", 08:49:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:49:51 INFO - "branch": "mozilla-central", 08:49:51 INFO - "repo_path": "mozilla-central", 08:49:51 INFO - "revision": "a65b35c8e5b17c2585968974aef1da67a8c56642", 08:49:51 INFO - "stage_platform": "macosx64", 08:49:51 INFO - "builduid": "3a6c257ce43c48329c9be3451026d773", 08:49:51 INFO - "slavebuilddir": "test" 08:49:51 INFO - } 08:49:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:49:51 INFO - Running post-action listener: _resource_record_post_action 08:49:51 INFO - [mozharness: 2016-08-26 15:49:51.234931Z] Finished read-buildbot-config step (success) 08:49:51 INFO - [mozharness: 2016-08-26 15:49:51.235038Z] Running download-and-extract step. 08:49:51 INFO - Running pre-action listener: _resource_record_pre_action 08:49:51 INFO - Running main action method: download_and_extract 08:49:51 INFO - mkdir: /builds/slave/test/build/tests 08:49: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')]} 08:49:51 INFO - trying https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:49:51 INFO - Downloading https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:49:52 INFO - Downloaded 1199 bytes. 08:49:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:49:52 INFO - Using the following test package requirements: 08:49:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:49:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:49:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:49:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'jsshell-mac.zip'], 08:49:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:49:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:49:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:49:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:49:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:49:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:49:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:49:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:52 INFO - trying https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:49:52 INFO - Downloading https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:49:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:49:53 INFO - Downloaded 16368019 bytes. 08:49:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:50:00 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 08:50:00 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')]} 08:50:00 INFO - trying https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:50:00 INFO - Downloading https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:50:01 INFO - Downloaded 16368019 bytes. 08:50:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:50: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')]} 08:50:07 INFO - trying https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:50:07 INFO - Downloading https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:50:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:50:09 INFO - Downloaded 14413998 bytes. 08:50:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:50:09 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')]} 08:50:09 INFO - trying https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:50:09 INFO - Downloading https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:50:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/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 08:50:12 INFO - Downloaded 111613388 bytes. 08:50:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:50:12 INFO - mkdir: /builds/slave/test/properties 08:50:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:50:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:50:12 INFO - Contents: 08:50:12 INFO - build_url:https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:50:13 INFO - Running post-action listener: _resource_record_post_action 08:50:13 INFO - Running post-action listener: set_extra_try_arguments 08:50:13 INFO - [mozharness: 2016-08-26 15:50:13.400784Z] Finished download-and-extract step (success) 08:50:13 INFO - [mozharness: 2016-08-26 15:50:13.400910Z] Running populate-webroot step. 08:50:13 INFO - Running pre-action listener: _resource_record_pre_action 08:50:13 INFO - Running main action method: populate_webroot 08:50:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:50:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:50:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:50:13 INFO - '--tppagecycles', 08:50:13 INFO - '7'], 08:50:13 INFO - 'tests': ['tsvgm']}}, 08:50:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:50:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:50:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:50:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:50:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['damp', 'tps']}, 08:50:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'tests': ['damp', 'tps']}, 08:50:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['dromaeo_dom']}, 08:50:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:50:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['basic_compositor_video']}, 08:50:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:50:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['a11yr', 08:50:13 INFO - 'ts_paint', 08:50:13 INFO - 'tpaint', 08:50:13 INFO - 'sessionrestore', 08:50:13 INFO - 'sessionrestore_no_auto_restore', 08:50:13 INFO - 'tabpaint']}, 08:50:13 INFO - 'other-e10s': {'tests': ['a11yr', 08:50:13 INFO - 'ts_paint', 08:50:13 INFO - 'tpaint', 08:50:13 INFO - 'sessionrestore', 08:50:13 INFO - 'sessionrestore_no_auto_restore', 08:50:13 INFO - 'tabpaint']}, 08:50:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:50:13 INFO - 'ts_paint', 08:50:13 INFO - 'tpaint', 08:50:13 INFO - 'sessionrestore', 08:50:13 INFO - 'sessionrestore_no_auto_restore', 08:50:13 INFO - 'tabpaint']}, 08:50:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:50:13 INFO - 'ts_paint', 08:50:13 INFO - 'tpaint', 08:50:13 INFO - 'sessionrestore', 08:50:13 INFO - 'sessionrestore_no_auto_restore', 08:50:13 INFO - 'tabpaint']}, 08:50:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['a11yr', 08:50:13 INFO - 'ts_paint', 08:50:13 INFO - 'tpaint', 08:50:13 INFO - 'sessionrestore', 08:50:13 INFO - 'sessionrestore_no_auto_restore', 08:50:13 INFO - 'tabpaint']}, 08:50:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['a11yr', 08:50:13 INFO - 'ts_paint', 08:50:13 INFO - 'tpaint', 08:50:13 INFO - 'sessionrestore', 08:50:13 INFO - 'sessionrestore_no_auto_restore', 08:50:13 INFO - 'tabpaint']}, 08:50:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['tsvgx', 08:50:13 INFO - 'tsvgr_opacity', 08:50:13 INFO - 'tart', 08:50:13 INFO - 'tscrollx', 08:50:13 INFO - 'cart']}, 08:50:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:50:13 INFO - 'tsvgr_opacity', 08:50:13 INFO - 'tart', 08:50:13 INFO - 'tscrollx', 08:50:13 INFO - 'cart']}, 08:50:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'talos_options': ['--disable-e10s'], 08:50:13 INFO - 'tests': ['tp5o']}, 08:50:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'tests': ['tp5o']}, 08:50:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'talos_options': ['--disable-e10s', 08:50:13 INFO - '--xperf_path', 08:50:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:13 INFO - 'tests': ['tp5n']}, 08:50:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:13 INFO - 'talos_options': ['--xperf_path', 08:50:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:13 INFO - 'tests': ['tp5n']}}, 08:50:13 INFO - 'talos.zip': {'path': '', 08:50:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:50:13 INFO - Running post-action listener: _resource_record_post_action 08:50:13 INFO - [mozharness: 2016-08-26 15:50:13.419899Z] Finished populate-webroot step (success) 08:50:13 INFO - [mozharness: 2016-08-26 15:50:13.420048Z] Running create-virtualenv step. 08:50:13 INFO - Running pre-action listener: _resource_record_pre_action 08:50:13 INFO - Running main action method: create_virtualenv 08:50:13 INFO - Creating virtualenv /builds/slave/test/build/venv 08:50:13 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 08:50:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:50:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:50:13 INFO - Using real prefix '/tools/python27' 08:50:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:50:14 INFO - Installing distribute.............................................................................................................................................................................................done. 08:50:17 INFO - Installing pip.................done. 08:50:17 INFO - Return code: 0 08:50:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:50:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:17 INFO - 'HOME': '/Users/cltbld', 08:50:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:17 INFO - 'LOGNAME': 'cltbld', 08:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:17 INFO - 'MOZ_NO_REMOTE': '1', 08:50:17 INFO - 'NO_EM_RESTART': '1', 08:50:17 INFO - 'PAGER': '/bin/cat', 08:50:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:17 INFO - 'PWD': '/builds/slave/test', 08:50:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:17 INFO - 'SHELL': '/bin/bash', 08:50:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:17 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:17 INFO - 'USER': 'cltbld', 08:50:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:17 INFO - 'XPC_FLAGS': '0x0', 08:50:17 INFO - 'XPC_SERVICE_NAME': '0', 08:50:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:17 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 08:50:17 INFO - Cleaning up... 08:50:17 INFO - Return code: 0 08:50:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:50:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:17 INFO - 'HOME': '/Users/cltbld', 08:50:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:17 INFO - 'LOGNAME': 'cltbld', 08:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:17 INFO - 'MOZ_NO_REMOTE': '1', 08:50:17 INFO - 'NO_EM_RESTART': '1', 08:50:17 INFO - 'PAGER': '/bin/cat', 08:50:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:17 INFO - 'PWD': '/builds/slave/test', 08:50:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:17 INFO - 'SHELL': '/bin/bash', 08:50:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:17 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:17 INFO - 'USER': 'cltbld', 08:50:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:17 INFO - 'XPC_FLAGS': '0x0', 08:50:17 INFO - 'XPC_SERVICE_NAME': '0', 08:50:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:17 INFO - Downloading/unpacking psutil>=3.1.1 08:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:50:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:20 INFO - Installing collected packages: psutil 08:50:20 INFO - Running setup.py install for psutil 08:50:20 INFO - building 'psutil._psutil_osx' extension 08:50:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:50:21 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 08:50:21 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 08:50:21 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 08:50:21 INFO - building 'psutil._psutil_posix' extension 08:50:21 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 08:50:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:50:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:50:21 INFO - ^ 08:50:21 INFO - 1 warning generated. 08:50:21 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 08:50:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:21 INFO - Successfully installed psutil 08:50:21 INFO - Cleaning up... 08:50:21 INFO - Return code: 0 08:50:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:50:21 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')]} 08:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:21 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')]} 08:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:21 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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:21 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 08:50:21 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 08:50:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:21 INFO - 'HOME': '/Users/cltbld', 08:50:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:21 INFO - 'LOGNAME': 'cltbld', 08:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:21 INFO - 'MOZ_NO_REMOTE': '1', 08:50:21 INFO - 'NO_EM_RESTART': '1', 08:50:21 INFO - 'PAGER': '/bin/cat', 08:50:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:21 INFO - 'PWD': '/builds/slave/test', 08:50:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:21 INFO - 'SHELL': '/bin/bash', 08:50:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:21 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:21 INFO - 'USER': 'cltbld', 08:50:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:21 INFO - 'XPC_FLAGS': '0x0', 08:50:21 INFO - 'XPC_SERVICE_NAME': '0', 08:50:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:50:24 INFO - Installing collected packages: mozsystemmonitor 08:50:24 INFO - Running setup.py install for mozsystemmonitor 08:50:24 INFO - Successfully installed mozsystemmonitor 08:50:24 INFO - Cleaning up... 08:50:24 INFO - Return code: 0 08:50:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:24 INFO - 'HOME': '/Users/cltbld', 08:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:24 INFO - 'LOGNAME': 'cltbld', 08:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:24 INFO - 'MOZ_NO_REMOTE': '1', 08:50:24 INFO - 'NO_EM_RESTART': '1', 08:50:24 INFO - 'PAGER': '/bin/cat', 08:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:24 INFO - 'PWD': '/builds/slave/test', 08:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:24 INFO - 'SHELL': '/bin/bash', 08:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:24 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:24 INFO - 'USER': 'cltbld', 08:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:24 INFO - 'XPC_FLAGS': '0x0', 08:50:24 INFO - 'XPC_SERVICE_NAME': '0', 08:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:24 INFO - Downloading/unpacking jsonschema==2.5.1 08:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:50:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:27 INFO - Downloading functools32-3.2.3-2.tar.gz 08:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:50:27 INFO - warning: no files found matching '*.txt' 08:50:27 INFO - no previously-included directories found matching 'build' 08:50:27 INFO - no previously-included directories found matching 'dist' 08:50:27 INFO - no previously-included directories found matching '.git*' 08:50:27 INFO - Installing collected packages: jsonschema, functools32 08:50:27 INFO - Running setup.py install for functools32 08:50:27 INFO - warning: no files found matching '*.txt' 08:50:27 INFO - no previously-included directories found matching 'build' 08:50:27 INFO - no previously-included directories found matching 'dist' 08:50:27 INFO - no previously-included directories found matching '.git*' 08:50:27 INFO - Successfully installed jsonschema functools32 08:50:27 INFO - Cleaning up... 08:50:28 INFO - Return code: 0 08:50:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:50:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:50:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:28 INFO - 'HOME': '/Users/cltbld', 08:50:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:28 INFO - 'LOGNAME': 'cltbld', 08:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:28 INFO - 'MOZ_NO_REMOTE': '1', 08:50:28 INFO - 'NO_EM_RESTART': '1', 08:50:28 INFO - 'PAGER': '/bin/cat', 08:50:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:28 INFO - 'PWD': '/builds/slave/test', 08:50:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:28 INFO - 'SHELL': '/bin/bash', 08:50:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:28 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:28 INFO - 'USER': 'cltbld', 08:50:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:28 INFO - 'XPC_FLAGS': '0x0', 08:50:28 INFO - 'XPC_SERVICE_NAME': '0', 08:50:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:50:28 INFO - Cleaning up... 08:50:28 INFO - Return code: 0 08:50:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:50:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:50:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:28 INFO - 'HOME': '/Users/cltbld', 08:50:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:28 INFO - 'LOGNAME': 'cltbld', 08:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:28 INFO - 'MOZ_NO_REMOTE': '1', 08:50:28 INFO - 'NO_EM_RESTART': '1', 08:50:28 INFO - 'PAGER': '/bin/cat', 08:50:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:28 INFO - 'PWD': '/builds/slave/test', 08:50:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:28 INFO - 'SHELL': '/bin/bash', 08:50:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:28 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:28 INFO - 'USER': 'cltbld', 08:50:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:28 INFO - 'XPC_FLAGS': '0x0', 08:50:28 INFO - 'XPC_SERVICE_NAME': '0', 08:50:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:28 INFO - Downloading/unpacking blobuploader==1.2.4 08:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:31 INFO - Downloading blobuploader-1.2.4.tar.gz 08:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:50:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:50:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:32 INFO - Downloading docopt-0.6.1.tar.gz 08:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:50:32 INFO - Installing collected packages: blobuploader, requests, docopt 08:50:32 INFO - Running setup.py install for blobuploader 08:50:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:50:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:50:32 INFO - Running setup.py install for requests 08:50:32 INFO - Running setup.py install for docopt 08:50:33 INFO - Successfully installed blobuploader requests docopt 08:50:33 INFO - Cleaning up... 08:50:33 INFO - Return code: 0 08:50:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:33 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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:33 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 08:50:33 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 08:50:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:33 INFO - 'HOME': '/Users/cltbld', 08:50:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:33 INFO - 'LOGNAME': 'cltbld', 08:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:33 INFO - 'MOZ_NO_REMOTE': '1', 08:50:33 INFO - 'NO_EM_RESTART': '1', 08:50:33 INFO - 'PAGER': '/bin/cat', 08:50:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:33 INFO - 'PWD': '/builds/slave/test', 08:50:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:33 INFO - 'SHELL': '/bin/bash', 08:50:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:33 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:33 INFO - 'USER': 'cltbld', 08:50:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:33 INFO - 'XPC_FLAGS': '0x0', 08:50:33 INFO - 'XPC_SERVICE_NAME': '0', 08:50:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:33 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-XuWO3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:33 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-5ui3dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:33 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-TQJyHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:33 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-D9R1VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:33 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-jqHa4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:33 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-LI1XF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-YPd47q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-rhgYjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-P9jhiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-bpxflq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-M6fKvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-vRYs6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Kfl9y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-fnGe_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-XlvUFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:34 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-CWSr4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:35 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Y8YAiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:50:35 INFO - Running setup.py install for manifestparser 08:50:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:50:35 INFO - Running setup.py install for mozcrash 08:50:35 INFO - Running setup.py install for mozdebug 08:50:35 INFO - Running setup.py install for mozdevice 08:50:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:50:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:50:35 INFO - Running setup.py install for mozfile 08:50:35 INFO - Running setup.py install for mozhttpd 08:50:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Running setup.py install for mozinfo 08:50:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Running setup.py install for mozInstall 08:50:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Running setup.py install for mozleak 08:50:36 INFO - Running setup.py install for mozlog 08:50:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Running setup.py install for moznetwork 08:50:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:50:36 INFO - Running setup.py install for mozprocess 08:50:36 INFO - Running setup.py install for mozprofile 08:50:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:50:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:50:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:50:37 INFO - Running setup.py install for mozrunner 08:50:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:50:37 INFO - Running setup.py install for mozscreenshot 08:50:37 INFO - Running setup.py install for moztest 08:50:37 INFO - Running setup.py install for mozversion 08:50:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:50:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:50:37 INFO - Cleaning up... 08:50:37 INFO - Return code: 0 08:50:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:37 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')]} 08:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:37 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')]} 08:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:37 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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:37 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 08:50:37 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 08:50:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:37 INFO - 'HOME': '/Users/cltbld', 08:50:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:37 INFO - 'LOGNAME': 'cltbld', 08:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:37 INFO - 'MOZ_NO_REMOTE': '1', 08:50:37 INFO - 'NO_EM_RESTART': '1', 08:50:37 INFO - 'PAGER': '/bin/cat', 08:50:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:37 INFO - 'PWD': '/builds/slave/test', 08:50:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:37 INFO - 'SHELL': '/bin/bash', 08:50:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:37 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:37 INFO - 'USER': 'cltbld', 08:50:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:37 INFO - 'XPC_FLAGS': '0x0', 08:50:37 INFO - 'XPC_SERVICE_NAME': '0', 08:50:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-dCsRR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1lZ9Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-BQGxm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-_h2zht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Zm8bP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-AxWQpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-EKIJhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-vgVrBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:38 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)) 08:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:38 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-QXGpxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-bwVPRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-zHvDGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-9qyJlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1Tdsc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-A2aizT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-6Iq9O8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-B92H4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:39 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)) 08:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:39 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-B11zpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:39 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)) 08:50:39 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)) 08:50:39 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)) 08:50:39 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)) 08:50:39 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)) 08:50:39 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)) 08:50:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:42 INFO - Downloading blessings-1.6.tar.gz 08:50:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:50:42 INFO - Installing collected packages: blessings 08:50:42 INFO - Running setup.py install for blessings 08:50:42 INFO - Successfully installed blessings 08:50:42 INFO - Cleaning up... 08:50:42 INFO - Return code: 0 08:50:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:50:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:42 INFO - Reading from file tmpfile_stdout 08:50:42 INFO - Current package versions: 08:50:42 INFO - blessings == 1.6 08:50:42 INFO - blobuploader == 1.2.4 08:50:42 INFO - docopt == 0.6.1 08:50:42 INFO - functools32 == 3.2.3-2 08:50:42 INFO - jsonschema == 2.5.1 08:50:42 INFO - manifestparser == 1.1 08:50:42 INFO - mozInstall == 1.12 08:50:42 INFO - mozcrash == 0.17 08:50:42 INFO - mozdebug == 0.1 08:50:42 INFO - mozdevice == 0.48 08:50:42 INFO - mozfile == 1.2 08:50:42 INFO - mozhttpd == 0.7 08:50:42 INFO - mozinfo == 0.9 08:50:42 INFO - mozleak == 0.1 08:50:42 INFO - mozlog == 3.3 08:50:42 INFO - moznetwork == 0.27 08:50:42 INFO - mozprocess == 0.23 08:50:42 INFO - mozprofile == 0.28 08:50:42 INFO - mozrunner == 6.12 08:50:42 INFO - mozscreenshot == 0.1 08:50:42 INFO - mozsystemmonitor == 0.3 08:50:42 INFO - moztest == 0.7 08:50:42 INFO - mozversion == 1.4 08:50:42 INFO - psutil == 3.1.1 08:50:42 INFO - requests == 1.2.3 08:50:42 INFO - wsgiref == 0.1.2 08:50:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:50:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:42 INFO - 'HOME': '/Users/cltbld', 08:50:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:42 INFO - 'LOGNAME': 'cltbld', 08:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:42 INFO - 'MOZ_NO_REMOTE': '1', 08:50:42 INFO - 'NO_EM_RESTART': '1', 08:50:42 INFO - 'PAGER': '/bin/cat', 08:50:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:42 INFO - 'PWD': '/builds/slave/test', 08:50:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:42 INFO - 'SHELL': '/bin/bash', 08:50:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:42 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:42 INFO - 'USER': 'cltbld', 08:50:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:42 INFO - 'XPC_FLAGS': '0x0', 08:50:42 INFO - 'XPC_SERVICE_NAME': '0', 08:50:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 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)) 08:50:43 INFO - Cleaning up... 08:50:43 INFO - Return code: 0 08:50:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:50:43 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')]} 08:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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')]} 08:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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 0x10548ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105523618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc32b465e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, '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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:50:43 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 08:50:43 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 08:50:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:50:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:43 INFO - 'HOME': '/Users/cltbld', 08:50:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:43 INFO - 'LOGNAME': 'cltbld', 08:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:43 INFO - 'MOZ_NO_REMOTE': '1', 08:50:43 INFO - 'NO_EM_RESTART': '1', 08:50:43 INFO - 'PAGER': '/bin/cat', 08:50:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:43 INFO - 'PWD': '/builds/slave/test', 08:50:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:43 INFO - 'SHELL': '/bin/bash', 08:50:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:50:43 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:50:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:43 INFO - 'USER': 'cltbld', 08:50:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:43 INFO - 'XPC_FLAGS': '0x0', 08:50:43 INFO - 'XPC_SERVICE_NAME': '0', 08:50:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:50:43 INFO - Cleaning up... 08:50:43 INFO - Return code: 0 08:50:43 INFO - Running post-action listener: _resource_record_post_action 08:50:43 INFO - Running post-action listener: _start_resource_monitoring 08:50:43 INFO - Starting resource monitoring. 08:50:43 INFO - [mozharness: 2016-08-26 15:50:43.557137Z] Finished create-virtualenv step (success) 08:50:43 INFO - [mozharness: 2016-08-26 15:50:43.558189Z] Running install step. 08:50:43 INFO - Running pre-action listener: _resource_record_pre_action 08:50:43 INFO - Running main action method: install 08:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:43 INFO - Reading from file tmpfile_stdout 08:50:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:50:43 INFO - Reading from file tmpfile_stdout 08:50:43 INFO - Output received: 08:50:43 INFO - Usage: mozinstall [options] installer 08:50:43 INFO - Options: 08:50:43 INFO - -h, --help show this help message and exit 08:50:43 INFO - -d DEST, --destination=DEST 08:50:43 INFO - Directory to install application into. [default: 08:50:43 INFO - "/builds/slave/test"] 08:50:43 INFO - --app=APP Application being installed. [default: firefox] 08:50:43 INFO - mkdir: /builds/slave/test/build/application 08:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:51:18 INFO - Reading from file tmpfile_stdout 08:51:18 INFO - Output received: 08:51:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:51:18 INFO - Running post-action listener: _resource_record_post_action 08:51:18 INFO - [mozharness: 2016-08-26 15:51:18.242752Z] Finished install step (success) 08:51:18 INFO - [mozharness: 2016-08-26 15:51:18.242882Z] Running run-tests step. 08:51:18 INFO - Running pre-action listener: _resource_record_pre_action 08:51:18 INFO - Running main action method: run_tests 08:51:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:18 INFO - 2016-08-26 08:51:18.257 screenresolution[1918:14550] starting screenresolution argv=screenresolution get 08:51:18 INFO - 2016-08-26 08:51:18.260 screenresolution[1918:14550] Display 0: 1600x1200x32@60 08:51:18 INFO - 2016-08-26 08:51:18.265 screenresolution[1919:14552] starting screenresolution argv=screenresolution list 08:51:18 INFO - Available Modes on Display 0 08:51:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:18 INFO - Intel Iris: 08:51:18 INFO - Chipset Model: Intel Iris 08:51:18 INFO - Type: GPU 08:51:18 INFO - Bus: Built-In 08:51:18 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:18 INFO - Vendor: Intel (0x8086) 08:51:18 INFO - Device ID: 0x0a2e 08:51:18 INFO - Revision ID: 0x0009 08:51:18 INFO - Displays: 08:51:18 INFO - Display: 08:51:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:18 INFO - Display Serial Number: 1600x1200 60 08:51:18 INFO - Main Display: Yes 08:51:18 INFO - Mirror: Off 08:51:18 INFO - Online: Yes 08:51:18 INFO - Rotation: Supported 08:51:18 INFO - Return code: 0 08:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:18 INFO - Python 2.7.3 08:51:18 INFO - Return code: 0 08:51:18 INFO - grabbing minidump binary from tooltool 08:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:18 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 0x1053f2d30>, '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 0x105157db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053eab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:18 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 08:51:18 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 08:51:18 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 08:51:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:18 INFO - Return code: 0 08:51:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:51:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:51:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 08:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0073 --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 08:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqpSaqyEs/Render', 08:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:18 INFO - 'HOME': '/Users/cltbld', 08:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:18 INFO - 'LOGNAME': 'cltbld', 08:51:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:18 INFO - 'MOZ_NO_REMOTE': '1', 08:51:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:18 INFO - 'NO_EM_RESTART': '1', 08:51:18 INFO - 'PAGER': '/bin/cat', 08:51:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:18 INFO - 'PWD': '/builds/slave/test', 08:51:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:18 INFO - 'SHELL': '/bin/bash', 08:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaMu9uCYo4/Listeners', 08:51:18 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:18 INFO - 'USER': 'cltbld', 08:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:18 INFO - 'XPC_FLAGS': '0x0', 08:51:18 INFO - 'XPC_SERVICE_NAME': '0', 08:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 08:51:18 INFO - mozversion application_buildid: 20160826063819 08:51:18 INFO - mozversion application_changeset: a65b35c8e5b17c2585968974aef1da67a8c56642 08:51:18 INFO - mozversion application_display_name: Nightly 08:51:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:18 INFO - mozversion application_name: Firefox 08:51:18 INFO - mozversion application_remotingname: firefox 08:51:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:51:18 INFO - mozversion application_vendor: Mozilla 08:51:18 INFO - mozversion application_version: 51.0a1 08:51:18 INFO - mozversion platform_buildid: 20160826063819 08:51:18 INFO - mozversion platform_changeset: a65b35c8e5b17c2585968974aef1da67a8c56642 08:51:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:51:18 INFO - mozversion platform_version: 51.0a1 08:51:18 INFO - using testdate: 1472226678 08:51:18 INFO - actual date: 1472226678 08:51:18 INFO - starting webserver on 'localhost:51526' 08:51:18 INFO - SUITE-START | Running 2 tests 08:51:18 INFO - TEST-START | dromaeo_css 08:51:18 INFO - operating with platform_type : mac_ 08:51:18 INFO - Initialising browser for dromaeo_css test... 08:51:18 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpONCWsI/profile http://localhost:51526/getInfo.html) 08:51:21 INFO - PROCESS | 1929 | __metrics Screen width/height:1600/1200 08:51:21 INFO - PROCESS | 1929 | colorDepth:24 08:51:21 INFO - PROCESS | 1929 | Browser inner width/height: 1024/689 08:51:21 INFO - PROCESS | 1929 | __metrics 08:51:21 INFO - PROCESS | 1929 | 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" 08:51:21 INFO - PROCESS | 1929 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:21 INFO - TEST-INFO | 1929: exit 0 08:51:21 INFO - Browser initialized. 08:51:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:21 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpONCWsI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:33 INFO - PROCESS | 1931 | RSS: Main: 233537536 08:51:33 INFO - PROCESS | 1931 | 08:52:25 INFO - PROCESS | 1931 | 1472226745231 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] 08:54:37 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51526/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51526/tests/dromaeo/cssquery-ext.html) 08:54:38 INFO - PROCESS | 1931 | RSS: Main: 407318528 08:54:38 INFO - PROCESS | 1931 | 08:57:41 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51526/tests/dromaeo/cssquery-ext.html (next: http://localhost:51526/tests/dromaeo/cssquery-jquery.html) 08:57:42 INFO - PROCESS | 1931 | RSS: Main: 367833088 08:57:42 INFO - PROCESS | 1931 | 09:00:44 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51526/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51526/tests/dromaeo/cssquery-mootools.html) 09:00:45 INFO - PROCESS | 1931 | RSS: Main: 333029376 09:00:45 INFO - PROCESS | 1931 | 09:03:47 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51526/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51526/tests/dromaeo/cssquery-prototype.html) 09:03:48 INFO - PROCESS | 1931 | RSS: Main: 335400960 09:03:48 INFO - PROCESS | 1931 | 09:06:51 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51526/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51526/tests/dromaeo/cssquery-yui.html) 09:06:52 INFO - PROCESS | 1931 | RSS: Main: 322977792 09:06:52 INFO - PROCESS | 1931 | 09:09:55 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:51526/tests/dromaeo/cssquery-yui.html (next: http://localhost:51526/tests/dromaeo/cssquery-dojo.html) 09:09:55 INFO - PROCESS | 1931 | RSS: Main: 440303616 09:09:55 INFO - PROCESS | 1931 | 09:09:55 INFO - PROCESS | 1931 | __start_tp_report 09:09:55 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 09:09:55 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 09:09:55 INFO - PROCESS | 1931 | |i|pagename|runs| 09:09:55 INFO - PROCESS | 1931 | |0;dojo.html;2970.6583742869566;3245.318483118545;3304.5538852254954;3325.218573635194;3343.8996830095107;5493.148561972891;5535.833924982252;5631.380548139701;5638.492535671789;5647.915281270779;5575.776968921248;5614.438556144383;5693.914591281134;5712.742926568304;5780.855478613037;5117.846464606049;5120.974395128019;5140.974295128538;5164.199549069881;5241.528262456378;5285.788568457277;5287.762050707708;5288.788448462057;5301.973490132544;5323.547498462635;5114;5169.974150129263;5177.741112944375;5215.8435246942845;5227.059129356714;4663.2305669564485;4935.728534930578;5231.476852314776;5238.554722848562;5243.842684719464;5145.948540514584;5167.844964651066;5188.870278243036;5195.454477279869;5210.739463026872;5663.461971112738;5663.915041274384;5664.122061080539;5667.971660141714;5679.488846003879;6056.507513450625;6119.296280927706;6133.1510485532735;6133.386661333876;6136.702895113906;5340.118880384771;5820.505257053155;6227.657478838662;6247.843803904939;6261.843453913689;5754.3094828620415;5775.133729940459;5780.161876527922;5780.219670344533;5784.855378615568;6152.953997820381;6160.014397696436;6179.258488981351;6214.689265536705;6249.6875156242;5922.082077278029;5944.821655350346;5971;5998.25021872266;6011.278646562353;6070.332263451017;6113.174721412553;6125.938740612537;6126.356732543108;6142.692865356715;5989.760409583655;6098.32918378978;6102.2771839767565;6122.112293717386;6125.785597504066;6183.536234782409;6213.875722485525;6222.253329600477;6226.221722284715;6226.283977342573;4968;4980.825671101494;4989.301497790312;4998.500149985045;5011.473795251519;4346.739195648272;4370.737755734666;4417.580329868657;4432.29083346663;4469.7318160910445;5584.329880414295;5615.943840561624;5642.125470552071;5672.205891175238;5723.141528770634;6229.4393504584805;6243.843903902439;6244.750209991549;6254.874902501924;6263.060540918807;5475.616706830563;5606.943930560642;5639.379668236491;5646.774129034875;5653.010723123519;5337.2527846101575;5365.168398898177;5367.114426119647;5393.919091213635;5449.5640348772;6224.782132625336;6303.621782693053;6317.336679648571;6323.873522529524;6331.525135614939;6119.357467465852;6228.906566401598;6232;6235.875282494416;6247.125402443752;6266.216722909637;6289.937100628935;6293.150424692791;6294.339094395205;6319.494440444845;6116.480099191618;6150.1082343059115;6154.507639388748;6156.199694039925;6177.84555386101;6104.847378815565;6188.133661287513;6200.627962322275;6262.718177682106;6274.215723034621;5958.493528050164;6004.849878753066;6010;6099.939000610115;6120.265568131853;6053.243344581927;6054.212952316171;6055.485283750931;6059.697015149313;6080.179175811209;5718.942810571841;5849.736761845812;5855.121731740273;5876.794312199053;5878.882422351615;5869.6771677558145;5999;6006.909896351645;6027.367126451835;6035.305939816977;5807.186993820784;5818.796342128006;5832.562557808096;5866.090755932837;5875.764969401176 09:09:55 INFO - PROCESS | 1931 | |1;ext.html;464.8675127588637;477.6918887317678;500.7145926821713;517.1234757086738;517.9585633149348;31547.053588392355;41408.75773726789;42612.573874261245;42649;42790.358144627855;43078.784606077;43310.7834460828;43369.34945975813;43442.78278608603;43664.90837729059;8736.432131911406;9616.423014619128;9809.754756131082;9817.410955342666;9843.753906152368;11956.581519646814;12226.694332641666;12238.632841014783;12395.318257495834;12445.751084978296;12063.698407539838;12085.159987402953;12130.514779408857;12197.573084942029;12215.572454964078;12129.21160124589;12184.39078046094;12201.20692155007;12225.449854756513;12248.81626775599;12009.819852702216;12090.758184836342;12112.21270617407;12114.81827772584;12115.454804533818;40921.181576368304;41282.79358603218;41289.58710412888;41294;41346;3296.571445712066;3612.2233719750116;3849.441830934527;3973.22522108189;4113.958860411388;30649.387012259853;31149.377012459852;31270.21824454384;31909.84045079783;31943.680563194535;3207.935841283185;3234.191452136966;3255.7720959532844;3258.9022329329923;3346.113279980807;492216.9999999964;494715;495103;495450;496154;59861.10208346878;60290;60363;60382.698086509285;60432;34144.65855341464;34932.12669683278;63474;63569.185848849214;63599;16083.839161608234;16476.588085297724;16645.583860403585;16677.332906683838;16680;11734.413279335999;11921.761564768829;11926.761464770656;12749.17130386522;12861.035422343362;16852.07313597764;16997;19049;19471.02644867751;19474.5131371714;6513.609183449008;7611.467197296247;7665.578393188317;7814.413918956101;7874.960625196837;862.8101817600118;937.7749340158313;949.3259785552198;1002.4386343647581;1027.7173777211326;24414.38964025878;29818.701812982028;30131.397372052437;30555.042321040408;30852.69147308543;1009.5204777730519;1010.95450704717;1015.9746006349752;1061.4215252687304;1071.8552995345685;15525.689486210438;15564.688706225812;15645;15680.686386272211;16003.599910002344;624147;624441;633998;634071;634157;58264.70867645634;58869;59082.70458647679;59140;59141.704291478265;1234.697499112723;1268.365817091436;1322.0679421007974;1370.8903287736957;1396.399548194266;24526.877365613058;31203;31242.531362030044;31882.52176217405;31918.840405797822;49789.50210497993;50411;50429.73925651888;50515.2422713667;50520.736981574286;498225.5088724533;502716;502755;505168;506562;26568;26833.463330732884;27127;27338.72661273362;28040.158795236177;47159;47361.52638473571;47904.5209547914;48153.75923120362;48286;38890.6110938887;38976;39110.217795644494;40014.1997160061;40450.79774601226;38613.80693096516;38939;39119.80440097781;39857.00357491086;40260.993475163355;38176.61823381731;38687.41968870413;38873;38908.832735018;39435;32450;33688.157796054315;34124.56121160338;34445.827770861986;34488.65511344854;20756.650817696802;21675.891620541795;25075.996960121392;25566.74433255718;25850 09:09:56 INFO - PROCESS | 1931 | |2;jquery.html;341.9880304189354;359.4715767821303;360.2111376086369;371.75278439837507;392.47212499188583;14854.034487758301;15676.764848527268;18970.810291897076;20565.971701414906;21266.680999784978;20343.49141271469;20433.386998390033;20693.482662933435;21146.365609031753;21419.250326238584;5928.466438020567;6272.717727702265;6291.3708629136945;6294.496440284766;6296.590721603104;4304.80628371724;5630.436956304377;6173.444390004899;6909;6928.757493487729;6899.827504312408;6901;6908.309169083077;6912.3778859902595;6915.619640919747;6522.967385163068;6758.391744742972;6789.830254243683;6809.659517024179;6812.14848143982;5775.931452681268;5877.559183061245;5891.293044834647;5898.970505147489;5974.312954010301;21075.68386474204;25114.372140696447;25286.747132528806;27005.729942700516;27183;2175.8259339252822;2248.415411992877;2262.468319944812;2352.1532248390567;2358.0803486640225;9231.538423078819;9807.411555306704;10352.637657681946;11060.668179954615;14924.0299380541;1835.7613510243716;1896.3362823011973;1926.1717461491553;1929.8552608554273;1933.5069557262807;119083;120028.39985800015;121661.78338216593;124218.230538877;125030.37484812518;11046.061084807721;11492.885071149349;11520.711982200512;11558.07535397183;11683.474243659055;12617.053720970964;13321.267330296732;13329.400176992058;13570.728585428236;13594.728105437836;6523.739050437929;7534.623268836646;7681.308682218627;7902.881456778153;8731.432456890281;5934.1692163097205;5957.463828255478;7192.964035179791;8587.398882078194;8816.8677469838;4411.955880441219;4485.663575231871;4500.369948207253;4553.157665831832;4639.976800115978;1829.8078701736392;1881.3509339277928;1882.8399586035341;1884.9717254241198;1894.2612381171355;803.4616806739382;848.4654667559521;866.1944391715639;896.614455784006;906.2795077913075;17545.94724316545;21010.684839727415;21725.13099476003;21740;21789.23737669174;841.8316336732678;857.9790049840669;864.9005364383177;927.8051609162017;964.363520076746;10059.446730429896;11281.04111150545;11313.038391736794;11337.206395552397;11351.716207094723;573179;574677.7596672106;579924;592480.0375998178;594146;129252.06121908179;134234.32882835524;136322;142367;146917;991.4249735153594;1008.8638033865334;1016.2632091733582;1038.4081073787868;1038.657243109787;18962.715559266348;19799.71301865374;19924.90037549803;19937.700934486285;20118.49703757418;32026.19934501656;43646.781766090964;52836.207456888944;56847.43152568587;57419;84950;85520.14479855122;85600.1439985617;86776.56611716902;87584.1241587576;7198.244184360776;7820.687172513263;8024.398170137147;8207.917920820953;8281.54451505149;10051.748706282402;12581.496740130293;12745.17156384832;13003.6996300373;13165.47338106465;10355.378677279386;10972.122230221403;11167.4822430934;11402.657920262405;11495.425228738697;10909.127269818555;10984.835227471754;11330.289374993901;11437.771244575228;11473.885261147281;9786.119249267314;11193.27243729155;11496.770064598828;11584.189106762782;11591.826122608;7955.323797477048;8175.1416101308505;8186.959065204874;8248.587570621565;8335.541545215072;8463;9785.36395134314;11242.718932026764;11307;11316 09:09:56 INFO - PROCESS | 1931 | |3;mootools.html;1359.8504164541903;1376.5250988408995;1380.868817462341;1675.388483203631;1904.7142928560713;7781.961090194548;8584.270337021353;8845.336599755012;8902.198802107809;9018.954905225464;8740.388172827905;8819.955900220506;8838;8843.602037908293;8858.512781796999;2045.5602045560202;2326.848754830939;2370.4429459077105;2373.240563019826;2379.0721618568687;2321.7097862767155;2355.9528809423887;2366.018102487474;2366.171839856054;2371.9762802372015;2334.159702507096;2363.503664230505;2364.515274368759;2367.585672507313;2369.8104151667826;2308.9422764430856;2334.7081614798067;2335.322756400642;2339.076064954342;2339.356676913845;2290.1297506947253;2336.8130549555995;2340.6020976434047;2340.8829558522007;2341.789238968501;8706.738797836075;8795.780105497413;8879;8881.42270752399;8884.156005179497;5171.120909445366;5352.170413585862;5363.704996225206;5370.194470829368;5378.252068077852;3591.4612808078737;3610.837512311952;3672.2839046385966;3676.1361080146144;3683.834227459744;1092.5083712329404;1095.2606990281529;1096.2271598523103;1120.3334016260312;1121.1479275750407;11433.942830285878;11468.13988950832;11556.248843825124;11558.884411155948;11566.826497602458;4363.890902727394;4466.59800617946;4629.421322334708;4638.6752927294765;4644.2801365788355;9199.264058875275;9199.95400023009;9205;9221.90778092224;9231.584578693975;5240.921386179134;5245.606579506476;5245.737713114329;5247.212918062245;5250.448702886142;4266.719984004786;4403.251447253974;4588.816447342069;4866.172750632401;4921.064997650435;3681.3189559931498;3715.108373990223;3828.310904037244;3835.9041023974064;3847.3844184930276;1077.53665923652;1107.8725946516092;1116.2967330581844;1142.4744617475887;1153.460130725474;771.2711487644123;864.8745931839848;901.6032945503957;902.5848109869403;940.9905900941039;5813.09896965971;5948.226730525091;6027.457528822427;6032.095185722089;6136.048912418493;871.4117970370102;882.594006756899;898.3172788680649;956.4978386347084;957.482959202037;3927.6465118139504;3966.5438474575217;3979.265512200692;4100.979495102565;4118.444010058603;9875;10833.241673082966;10895.727606809893;10928.180386471047;10936.015758581765;10911.236213464981;10916.672499825017;10918.563257469772;10921.12630989519;10931.617393391192;8080.4343695940715;8190.5904704764525;8210.425270231146;8216.958915205505;8233.38249631268;5514.117741161395;5637.830865074055;5921.230240068763;5931.1992880960515;5949.077892926603;9993.500324983866;10180.643677471253;10248.077673009466;10262.692119236435;10271.178305735673;9763.267754918268;10269.435181065113;10271.383716977005;10272.33229840058;10274.794504109726;6604.768833090819;6821.420179284618;7072.5402848814665;7202.927970720226;7214.639268036683;8195.975503062116;8328.83342333162;8493.87259191125;8571.22858942698;8603.182697643713;7464.104307483137;7602.46782725204;7619.69521219145;7707.383409327353;7773.339266162439;7234.96382518084;7319.048523691886;7363.889541656987;7400.629968501446;7452.925470745223;6982.546134501241;7171.354578087997;7173.748918787817;7174.067371241914;7185.425165986813;4999.45006049327;5028.9245661315845;5077.31456253401;5085.8728531787;5164.3802743671;4952.479989601185;4994.75236143744;5131.615128865275;5156.484351564814;5225.242339860775 09:09:56 INFO - PROCESS | 1931 | |4;prototype.html;261.97118316985126;293.3853576756694;307.5340858599222;309.8806959320661;312.02336686172276;4190.497140343159;4362.803673834677;4917.704937703736;5104.5335331584865;5285.603579731516;3878.4570160177527;4498.595126438619;4510.849733317999;4642.932125611112;4969.602431805456;3888.8833334999995;3937.7243592948575;4020.4170395292663;4035.717499775017;4058.756474611518;3935.701218597856;3994.740341877783;4027.6979226558124;4030.798460076999;4047.817848196838;3439.690427861492;3870.8645197418095;3978.0452691354158;4019.8794036178815;4075.6535694466006;3977.443157957888;4037.788663400968;4049;4055.2700513907484;4078.5921407859273;2736.4253506763603;2755.594646730162;3061.8315992620396;3380.577427821522;3489.0753950203216;4854.393200849894;5041.924371134399;5080.339555857752;5102.872428189288;5127.717975511346;4353.867994321499;4550.953280745414;4551;4570.063137056895;4620.722756634613;4755.667103302771;5501.697406642633;5509.862253443656;5521.475459831349;5554.1391084381985;3863.5943225961432;3864.0339915021245;3873.3802591585213;3880.7387599030426;3910.120222949842;4355.825766969318;5699.430056994309;5767.048437007888;5839.737211825478;5878.706684529397;3353.2958078803726;3788.8105594720155;3816.8473261069803;3822.522184726909;3823.1397935464297;3892.474515940312;3895.9091454392815;3900.258950799339;3907.4724912137067;3930.5283365995692;4175.62419382257;4461.107778444324;4529.297958816389;4574.6376413336175;4576.107659006529;5477.643953143033;5506.64206826555;5511.3937466879415;5515.944840551543;5518.944810551843;1186.4779497021284;1198.5796830755407;1199.4902166579343;1324.735052989406;1330.8402991641065;749.056189201599;833.9499630022218;846.4413487098485;873.6505397840788;890.5725251879135;4997.002398081593;5013.724245166478;5023.623228257895;5036.571891389273;5072.4673909239;5462.6889546508555;5516.172574113834;5524.944750552523;5528.778848846082;5546.722663866791;4716.858494245178;4956.553910148032;4959.975200123977;5021;5123.231515272739;2911.941761164765;2941.4999450093537;2963.7475518605584;2982.910512684623;3019.184820098561;5573.123516922693;5769.567282453749;5790.189373487715;5804.593678442468;5830.8542286442325;5356.71438854672;5675.5459563234845;5736.627119237236;5795.27622017651;5860.677662728591;5514.621344663834;5726.828195154152;5803.47768700819;5817.70911454434;5841.88316233673;5080.26336181259;5465.562754979672;5486.780528778964;5524.972375138099;5657.2928383952;4630.699004564693;5155;5536.7231638417115;5546.389897111234;5656.3495198051105;5741.971290143522;5753.468473871483;5771.769129234784;5783.363829978614;5815.651060936358;3375.561177046968;3661.7468721346763;3718.925621487609;3847.249786291646;3948.3287841066513;5611.737359094172;5619.8595035124445;5632.915506267327;5635.464630860061;5727.770889164553;5423.945760542502;5435.885643443164;5442.374126975448;5464.562834973113;5466.699331536644;4605.526231605854;5048.596112310933;5151.974240128776;5274.472552744694;5433.972830135824;5099.515546023122;5384.838454846361;5387.218853266333;5396.811111611075;5401.72991350423;4556.020455602062;5343.5457986071615;5351.304330437174;5395.2176934343515;5526.447355264441;4509.849988253106;4847.054824309226;5159.626571776875;5392.352917649907;5430.348358197041 09:09:56 INFO - PROCESS | 1931 | |5;yui.html;681.8534015186734;685.7736946807554;750.4671683104996;762.2453770766939;768.7117331000875;13769.862301376983;14212.934029947744;14685.779713304295;15476;15549;13874.930625346884;14194.148351098942;14200.573982780506;14206.573802785908;14226.359813808378;673.3603077076772;831.9168083191678;856.8800367948493;859.7678626770768;860.389123722158;691.6196092149298;850.0394554153797;850.7022542110274;864.0668078475263;872.0233338660677;888.5512816027897;889.6485888074207;890.1365675294986;891.4606662968907;891.6477991193475;701.8912068629371;884.3279107878027;884.9646014159426;885.335998001502;887.9467231966103;654.6792071884743;655.2333769489709;655.9967200164016;668.4184759259426;670.6345041952147;14129.010969232158;14157.009009369349;14180.716385672229;14204.928975355057;14218.431262749582;10432.582696692123;10765.461726913623;10857.185711071701;10923.016928476396;11009.944950275198;12117.33354665493;12214.694632634166;12232.633021009384;12261;12605.621831345074;4419.690621656486;4515.7516336601475;4557.9772101139615;4561.338605902159;4561.384213131218;106472.46763766209;108923.82152356987;111956.32065519024;112880;113191.43404283008;10180.287379883484;10248.692539223835;10788.298760580537;10916.126709863192;11070.889291106985;20249.898750506447;20251.898740506203;20266.392008239778;20291.797082029287;20346.694799578017;6523.249826269945;6718.529702920749;6764.188297404245;6815.488838337144;6816.659167041628;4287.656987440997;4399.868003959887;4503.729776213372;4504.864854054318;4551.954480455153;3018.864151113205;3206.3907857506997;3272.3128143090216;3363.680450357212;3379.2396710739886;620.8292719502083;628.1018144053955;628.7359309090193;638.1703785079378;657.1785268414482;502.2566601429904;524.6170295684095;527.2407732864663;558.3104865491115;596.8060380376309;8304.418690691593;8620.353473489387;8632.913670863336;8791.736247912573;8824.470531768115;583.3175185033521;607.0075426677414;609.558070398966;617.4473845907887;622.330994181257;3663.6336366363685;4087.4481944937647;4181.958180418157;4307.267764480046;4364.825406983685;89818.10181898228;106286.93713062925;143805;147285.26357368144;148878;111959.88040119657;114015;120551;123490;124169;67314;90198.54900725455;90973.54513227523;94639;107608;9242.306826988137;9396.295277854326;9562.760930976503;9577.904220957702;9740.415575065519;53141.20288195603;62304;63545.682271588346;64213.67893160504;65375.34624653692;84541;92818.53590732004;94502.52748736212;95644.5653315187;97885.72199918906;12168.330741809286;12618.49526018949;13069.607911762663;13074.477020919057;13279.933600331937;14098.859011409755;14382.137071775727;14563.635909102357;14613.050151740103;14694.485693000694;13525.864741352461;13676.452941882213;13972.790408143683;14066.156030638194;14108.858911411166;13196.20822750638;13539.255340956342;13581.592552223448;13763.560833225443;13815.309234538032;13166.01254905905;13297.734045318846;13461.46154153862;13469.46122155142;13581.592552223448;11072.889271107186;11286.943565282121;11323;11340.886591133984;11406.414248596706;9162.450252984841;9757.24346126732;10697.893021070002;11048.337099774039;11653.76692466163 09:09:56 INFO - PROCESS | 1931 | __end_tp_report 09:09:56 INFO - PROCESS | 1931 | __start_cc_report 09:09:56 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,1536 09:09:56 INFO - PROCESS | 1931 | __end_cc_report 09:09:56 INFO - PROCESS | 1931 | __startTimestamp1472227795993__endTimestamp 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | ------- Summary: start ------- 09:09:56 INFO - PROCESS | 1931 | Number of tests: 6 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | [#0] dojo.html Cycles:165 Average:5687.99 Median:5855.12 stddev:636.06 (10.9%) stddev-sans-first:601.23 09:09:56 INFO - PROCESS | 1931 | Values: 2970.7 3245.3 3304.6 3325.2 3343.9 5493.1 5535.8 5631.4 5638.5 5647.9 5575.8 5614.4 5693.9 5712.7 5780.9 5117.8 5121.0 5141.0 5164.2 5241.5 5285.8 5287.8 5288.8 5302.0 5323.5 5114.0 5170.0 5177.7 5215.8 5227.1 4663.2 4935.7 5231.5 5238.6 5243.8 5145.9 5167.8 5188.9 5195.5 5210.7 5663.5 5663.9 5664.1 5668.0 5679.5 6056.5 6119.3 6133.2 6133.4 6136.7 5340.1 5820.5 6227.7 6247.8 6261.8 5754.3 5775.1 5780.2 5780.2 5784.9 6153.0 6160.0 6179.3 6214.7 6249.7 5922.1 5944.8 5971.0 5998.3 6011.3 6070.3 6113.2 6125.9 6126.4 6142.7 5989.8 6098.3 6102.3 6122.1 6125.8 6183.5 6213.9 6222.3 6226.2 6226.3 4968.0 4980.8 4989.3 4998.5 5011.5 4346.7 4370.7 4417.6 4432.3 4469.7 5584.3 5615.9 5642.1 5672.2 5723.1 6229.4 6243.8 6244.8 6254.9 6263.1 5475.6 5606.9 5639.4 5646.8 5653.0 5337.3 5365.2 5367.1 5393.9 5449.6 6224.8 6303.6 6317.3 6323.9 6331.5 6119.4 6228.9 6232.0 6235.9 6247.1 6266.2 6289.9 6293.2 6294.3 6319.5 6116.5 6150.1 6154.5 6156.2 6177.8 6104.8 6188.1 6200.6 6262.7 6274.2 5958.5 6004.8 6010.0 6099.9 6120.3 6053.2 6054.2 6055.5 6059.7 6080.2 5718.9 5849.7 5855.1 5876.8 5878.9 5869.7 5999.0 6006.9 6027.4 6035.3 5807.2 5818.8 5832.6 5866.1 5875.8 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | [#1] ext.html Cycles:180 Average:68181.42 Median:19272.18 stddev:145632.39 (755.7%) stddev-sans-first:145952.18 09:09:56 INFO - PROCESS | 1931 | Values: 464.9 477.7 500.7 517.1 518.0 31547.1 41408.8 42612.6 42649.0 42790.4 43078.8 43310.8 43369.3 43442.8 43664.9 8736.4 9616.4 9809.8 9817.4 9843.8 11956.6 12226.7 12238.6 12395.3 12445.8 12063.7 12085.2 12130.5 12197.6 12215.6 12129.2 12184.4 12201.2 12225.4 12248.8 12009.8 12090.8 12112.2 12114.8 12115.5 40921.2 41282.8 41289.6 41294.0 41346.0 3296.6 3612.2 3849.4 3973.2 4114.0 30649.4 31149.4 31270.2 31909.8 31943.7 3207.9 3234.2 3255.8 3258.9 3346.1 492217.0 494715.0 495103.0 495450.0 496154.0 59861.1 60290.0 60363.0 60382.7 60432.0 34144.7 34932.1 63474.0 63569.2 63599.0 16083.8 16476.6 16645.6 16677.3 16680.0 11734.4 11921.8 11926.8 12749.2 12861.0 16852.1 16997.0 19049.0 19471.0 19474.5 6513.6 7611.5 7665.6 7814.4 7875.0 862.8 937.8 949.3 1002.4 1027.7 24414.4 29818.7 30131.4 30555.0 30852.7 1009.5 1011.0 1016.0 1061.4 1071.9 15525.7 15564.7 15645.0 15680.7 16003.6 624147.0 624441.0 633998.0 634071.0 634157.0 58264.7 58869.0 59082.7 59140.0 59141.7 1234.7 1268.4 1322.1 1370.9 1396.4 24526.9 31203.0 31242.5 31882.5 31918.8 49789.5 50411.0 50429.7 50515.2 50520.7 498225.5 502716.0 502755.0 505168.0 506562.0 26568.0 26833.5 27127.0 27338.7 28040.2 47159.0 47361.5 47904.5 48153.8 48286.0 38890.6 38976.0 39110.2 40014.2 40450.8 38613.8 38939.0 39119.8 39857.0 40261.0 38176.6 38687.4 38873.0 38908.8 39435.0 32450.0 33688.2 34124.6 34445.8 34488.7 20756.7 21675.9 25076.0 25566.7 25850.0 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | [#2] jquery.html Cycles:180 Average:35045.39 Median:25200.56 stddev:97926.66 (388.6%) stddev-sans-first:98166.70 09:09:56 INFO - PROCESS | 1931 | Values: 342.0 359.5 360.2 371.8 392.5 14854.0 15676.8 18970.8 20566.0 21266.7 20343.5 20433.4 20693.5 21146.4 21419.3 5928.5 6272.7 6291.4 6294.5 6296.6 4304.8 5630.4 6173.4 6909.0 6928.8 6899.8 6901.0 6908.3 6912.4 6915.6 6523.0 6758.4 6789.8 6809.7 6812.1 5775.9 5877.6 5891.3 5899.0 5974.3 21075.7 25114.4 25286.7 27005.7 27183.0 2175.8 2248.4 2262.5 2352.2 2358.1 9231.5 9807.4 10352.6 11060.7 14924.0 1835.8 1896.3 1926.2 1929.9 1933.5 119083.0 120028.4 121661.8 124218.2 125030.4 11046.1 11492.9 11520.7 11558.1 11683.5 12617.1 13321.3 13329.4 13570.7 13594.7 6523.7 7534.6 7681.3 7902.9 8731.4 5934.2 5957.5 7193.0 8587.4 8816.9 4412.0 4485.7 4500.4 4553.2 4640.0 1829.8 1881.4 1882.8 1885.0 1894.3 803.5 848.5 866.2 896.6 906.3 17545.9 21010.7 21725.1 21740.0 21789.2 841.8 858.0 864.9 927.8 964.4 10059.4 11281.0 11313.0 11337.2 11351.7 573179.0 574677.8 579924.0 592480.0 594146.0 129252.1 134234.3 136322.0 142367.0 146917.0 991.4 1008.9 1016.3 1038.4 1038.7 18962.7 19799.7 19924.9 19937.7 20118.5 32026.2 43646.8 52836.2 56847.4 57419.0 84950.0 85520.1 85600.1 86776.6 87584.1 7198.2 7820.7 8024.4 8207.9 8281.5 10051.7 12581.5 12745.2 13003.7 13165.5 10355.4 10972.1 11167.5 11402.7 11495.4 10909.1 10984.8 11330.3 11437.8 11473.9 9786.1 11193.3 11496.8 11584.2 11591.8 7955.3 8175.1 8187.0 8248.6 8335.5 8463.0 9785.4 11242.7 11307.0 11316.0 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | [#3] mootools.html Cycles:180 Average:5696.29 Median:5053.12 stddev:3205.51 (63.4%) stddev-sans-first:3197.94 09:09:56 INFO - PROCESS | 1931 | Values: 1359.9 1376.5 1380.9 1675.4 1904.7 7782.0 8584.3 8845.3 8902.2 9019.0 8740.4 8820.0 8838.0 8843.6 8858.5 2045.6 2326.8 2370.4 2373.2 2379.1 2321.7 2356.0 2366.0 2366.2 2372.0 2334.2 2363.5 2364.5 2367.6 2369.8 2308.9 2334.7 2335.3 2339.1 2339.4 2290.1 2336.8 2340.6 2340.9 2341.8 8706.7 8795.8 8879.0 8881.4 8884.2 5171.1 5352.2 5363.7 5370.2 5378.3 3591.5 3610.8 3672.3 3676.1 3683.8 1092.5 1095.3 1096.2 1120.3 1121.1 11433.9 11468.1 11556.2 11558.9 11566.8 4363.9 4466.6 4629.4 4638.7 4644.3 9199.3 9200.0 9205.0 9221.9 9231.6 5240.9 5245.6 5245.7 5247.2 5250.4 4266.7 4403.3 4588.8 4866.2 4921.1 3681.3 3715.1 3828.3 3835.9 3847.4 1077.5 1107.9 1116.3 1142.5 1153.5 771.3 864.9 901.6 902.6 941.0 5813.1 5948.2 6027.5 6032.1 6136.0 871.4 882.6 898.3 956.5 957.5 3927.6 3966.5 3979.3 4101.0 4118.4 9875.0 10833.2 10895.7 10928.2 10936.0 10911.2 10916.7 10918.6 10921.1 10931.6 8080.4 8190.6 8210.4 8217.0 8233.4 5514.1 5637.8 5921.2 5931.2 5949.1 9993.5 10180.6 10248.1 10262.7 10271.2 9763.3 10269.4 10271.4 10272.3 10274.8 6604.8 6821.4 7072.5 7202.9 7214.6 8196.0 8328.8 8493.9 8571.2 8603.2 7464.1 7602.5 7619.7 7707.4 7773.3 7235.0 7319.0 7363.9 7400.6 7452.9 6982.5 7171.4 7173.7 7174.1 7185.4 4999.5 5028.9 5077.3 5085.9 5164.4 4952.5 4994.8 5131.6 5156.5 5225.2 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | [#4] prototype.html Cycles:180 Average:4452.25 Median:5017.36 stddev:1368.82 (27.3%) stddev-sans-first:1336.04 09:09:56 INFO - PROCESS | 1931 | Values: 262.0 293.4 307.5 309.9 312.0 4190.5 4362.8 4917.7 5104.5 5285.6 3878.5 4498.6 4510.8 4642.9 4969.6 3888.9 3937.7 4020.4 4035.7 4058.8 3935.7 3994.7 4027.7 4030.8 4047.8 3439.7 3870.9 3978.0 4019.9 4075.7 3977.4 4037.8 4049.0 4055.3 4078.6 2736.4 2755.6 3061.8 3380.6 3489.1 4854.4 5041.9 5080.3 5102.9 5127.7 4353.9 4551.0 4551.0 4570.1 4620.7 4755.7 5501.7 5509.9 5521.5 5554.1 3863.6 3864.0 3873.4 3880.7 3910.1 4355.8 5699.4 5767.0 5839.7 5878.7 3353.3 3788.8 3816.8 3822.5 3823.1 3892.5 3895.9 3900.3 3907.5 3930.5 4175.6 4461.1 4529.3 4574.6 4576.1 5477.6 5506.6 5511.4 5515.9 5518.9 1186.5 1198.6 1199.5 1324.7 1330.8 749.1 833.9 846.4 873.7 890.6 4997.0 5013.7 5023.6 5036.6 5072.5 5462.7 5516.2 5524.9 5528.8 5546.7 4716.9 4956.6 4960.0 5021.0 5123.2 2911.9 2941.5 2963.7 2982.9 3019.2 5573.1 5769.6 5790.2 5804.6 5830.9 5356.7 5675.5 5736.6 5795.3 5860.7 5514.6 5726.8 5803.5 5817.7 5841.9 5080.3 5465.6 5486.8 5525.0 5657.3 4630.7 5155.0 5536.7 5546.4 5656.3 5742.0 5753.5 5771.8 5783.4 5815.7 3375.6 3661.7 3718.9 3847.2 3948.3 5611.7 5619.9 5632.9 5635.5 5727.8 5423.9 5435.9 5442.4 5464.6 5466.7 4605.5 5048.6 5152.0 5274.5 5434.0 5099.5 5384.8 5387.2 5396.8 5401.7 4556.0 5343.5 5351.3 5395.2 5526.4 4509.8 4847.1 5159.6 5392.4 5430.3 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - PROCESS | 1931 | [#5] yui.html Cycles:180 Average:23233.69 Median:4234.81 stddev:36396.07 (859.5%) stddev-sans-first:36458.78 09:09:56 INFO - PROCESS | 1931 | Values: 681.9 685.8 750.5 762.2 768.7 13769.9 14212.9 14685.8 15476.0 15549.0 13874.9 14194.1 14200.6 14206.6 14226.4 673.4 831.9 856.9 859.8 860.4 691.6 850.0 850.7 864.1 872.0 888.6 889.6 890.1 891.5 891.6 701.9 884.3 885.0 885.3 887.9 654.7 655.2 656.0 668.4 670.6 14129.0 14157.0 14180.7 14204.9 14218.4 10432.6 10765.5 10857.2 10923.0 11009.9 12117.3 12214.7 12232.6 12261.0 12605.6 4419.7 4515.8 4558.0 4561.3 4561.4 106472.5 108923.8 111956.3 112880.0 113191.4 10180.3 10248.7 10788.3 10916.1 11070.9 20249.9 20251.9 20266.4 20291.8 20346.7 6523.2 6718.5 6764.2 6815.5 6816.7 4287.7 4399.9 4503.7 4504.9 4552.0 3018.9 3206.4 3272.3 3363.7 3379.2 620.8 628.1 628.7 638.2 657.2 502.3 524.6 527.2 558.3 596.8 8304.4 8620.4 8632.9 8791.7 8824.5 583.3 607.0 609.6 617.4 622.3 3663.6 4087.4 4182.0 4307.3 4364.8 89818.1 106286.9 143805.0 147285.3 148878.0 111959.9 114015.0 120551.0 123490.0 124169.0 67314.0 90198.5 90973.5 94639.0 107608.0 9242.3 9396.3 9562.8 9577.9 9740.4 53141.2 62304.0 63545.7 64213.7 65375.3 84541.0 92818.5 94502.5 95644.6 97885.7 12168.3 12618.5 13069.6 13074.5 13279.9 14098.9 14382.1 14563.6 14613.1 14694.5 13525.9 13676.5 13972.8 14066.2 14108.9 13196.2 13539.3 13581.6 13763.6 13815.3 13166.0 13297.7 13461.5 13469.5 13581.6 11072.9 11286.9 11323.0 11340.9 11406.4 9162.5 9757.2 10697.9 11048.3 11653.8 09:09:56 INFO - PROCESS | 1931 | -------- Summary: end -------- 09:09:56 INFO - PROCESS | 1931 | 09:09:56 INFO - TEST-INFO | 1931: exit 0 09:09:56 INFO - TEST-OK | dromaeo_css | took 1117782ms 09:09:56 INFO - TEST-START | kraken 09:09:56 INFO - operating with platform_type : mac_ 09:09:56 INFO - Initialising browser for kraken test... 09:09:56 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpn59BUu/profile http://localhost:51526/getInfo.html) 09:09:58 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 09:09:58 INFO - PROCESS | 1934 | colorDepth:24 09:09:58 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 09:09:58 INFO - PROCESS | 1934 | __metrics 09:09:59 INFO - PROCESS | 1934 | 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" 09:09:59 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:09:59 INFO - TEST-INFO | 1934: exit 0 09:09:59 INFO - Browser initialized. 09:09:59 INFO - Running cycle 1/1 for kraken test... 09:09:59 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpn59BUu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:11 INFO - PROCESS | 1935 | RSS: Main: 233496576 09:10:11 INFO - PROCESS | 1935 | 09:10:14 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51526/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:15 INFO - PROCESS | 1935 | RSS: Main: 291151872 09:10:15 INFO - PROCESS | 1935 | 09:10:18 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51526/tests/kraken/driver.html?testName=audio-dft) 09:10:18 INFO - PROCESS | 1935 | RSS: Main: 288301056 09:10:18 INFO - PROCESS | 1935 | 09:10:22 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51526/tests/kraken/driver.html?testName=audio-fft) 09:10:22 INFO - PROCESS | 1935 | RSS: Main: 376758272 09:10:22 INFO - PROCESS | 1935 | 09:10:24 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51526/tests/kraken/driver.html?testName=audio-oscillator) 09:10:25 INFO - PROCESS | 1935 | RSS: Main: 279908352 09:10:25 INFO - PROCESS | 1935 | 09:10:27 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51526/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:10:28 INFO - PROCESS | 1935 | RSS: Main: 283238400 09:10:28 INFO - PROCESS | 1935 | 09:10:30 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51526/tests/kraken/driver.html?testName=imaging-darkroom) 09:10:31 INFO - PROCESS | 1935 | RSS: Main: 322232320 09:10:31 INFO - PROCESS | 1935 | 09:10:34 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51526/tests/kraken/driver.html?testName=imaging-desaturate) 09:10:35 INFO - PROCESS | 1935 | RSS: Main: 322322432 09:10:35 INFO - PROCESS | 1935 | 09:10:37 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51526/tests/kraken/driver.html?testName=json-parse-financial) 09:10:38 INFO - PROCESS | 1935 | RSS: Main: 321556480 09:10:38 INFO - PROCESS | 1935 | 09:10:39 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51526/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:10:40 INFO - PROCESS | 1935 | RSS: Main: 297603072 09:10:40 INFO - PROCESS | 1935 | 09:10:42 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:10:43 INFO - PROCESS | 1935 | RSS: Main: 303112192 09:10:43 INFO - PROCESS | 1935 | 09:10:45 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:10:46 INFO - PROCESS | 1935 | RSS: Main: 306073600 09:10:46 INFO - PROCESS | 1935 | 09:10:48 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:10:49 INFO - PROCESS | 1935 | RSS: Main: 320446464 09:10:49 INFO - PROCESS | 1935 | 09:10:52 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:10:53 INFO - PROCESS | 1935 | RSS: Main: 316796928 09:10:53 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:51526/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51526/tests/kraken/driver.html?testName=ai-astar) 09:10:55 INFO - PROCESS | 1935 | RSS: Main: 360075264 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | __start_tp_report 09:10:55 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 09:10:55 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 09:10:55 INFO - PROCESS | 1935 | |i|pagename|runs| 09:10:55 INFO - PROCESS | 1935 | |0;ai-astar;114;112;132;114;115;114;113;112;113;113 09:10:55 INFO - PROCESS | 1935 | |1;audio-beat-detection;183;131;132;192;109;115;148;101;185;139 09:10:55 INFO - PROCESS | 1935 | |2;audio-dft;187;185;202;264;187;183;184;178;184;189 09:10:55 INFO - PROCESS | 1935 | |3;audio-fft;75;78;75;157;58;57;58;61;139;60 09:10:55 INFO - PROCESS | 1935 | |4;audio-oscillator;82;82;129;70;71;82;118;67;70;117 09:10:55 INFO - PROCESS | 1935 | |5;imaging-gaussian-blur;89;86;89;86;89;86;89;86;89;87 09:10:55 INFO - PROCESS | 1935 | |6;imaging-darkroom;92;94;94;95;94;93;93;94;94;94 09:10:55 INFO - PROCESS | 1935 | |7;imaging-desaturate;86;85;84;86;86;85;85;84;85;84 09:10:55 INFO - PROCESS | 1935 | |8;json-parse-financial;55;56;55;54;53;53;54;51;53;55 09:10:55 INFO - PROCESS | 1935 | |9;json-stringify-tinderbox;65;65;68;75;72;78;75;69;69;72 09:10:55 INFO - PROCESS | 1935 | |10;stanford-crypto-aes;86;86;90;87;87;88;87;86;98;110 09:10:55 INFO - PROCESS | 1935 | |11;stanford-crypto-ccm;144;154;161;151;149;134;144;162;173;123 09:10:55 INFO - PROCESS | 1935 | |12;stanford-crypto-pbkdf2;178;174;176;173;173;174;173;176;174;173 09:10:55 INFO - PROCESS | 1935 | |13;stanford-crypto-sha256-iterative;70;80;91;88;73;67;69;68;68;69 09:10:55 INFO - PROCESS | 1935 | __end_tp_report 09:10:55 INFO - PROCESS | 1935 | __start_cc_report 09:10:55 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,2140 09:10:55 INFO - PROCESS | 1935 | __end_cc_report 09:10:55 INFO - PROCESS | 1935 | __startTimestamp1472227855287__endTimestamp 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | ------- Summary: start ------- 09:10:55 INFO - PROCESS | 1935 | Number of tests: 14 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#0] ai-astar Cycles:10 Average:115.20 Median:114.00 stddev:5.98 (5.2%) stddev-sans-first:6.32 09:10:55 INFO - PROCESS | 1935 | Values: 114.0 112.0 132.0 114.0 115.0 114.0 113.0 112.0 113.0 113.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:143.50 stddev:32.95 (23.0%) stddev-sans-first:31.70 09:10:55 INFO - PROCESS | 1935 | Values: 183.0 131.0 132.0 192.0 109.0 115.0 148.0 101.0 185.0 139.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#2] audio-dft Cycles:10 Average:194.30 Median:187.00 stddev:25.26 (13.5%) stddev-sans-first:26.66 09:10:55 INFO - PROCESS | 1935 | Values: 187.0 185.0 202.0 264.0 187.0 183.0 184.0 178.0 184.0 189.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#3] audio-fft Cycles:10 Average:81.80 Median:60.50 stddev:36.04 (59.6%) stddev-sans-first:38.14 09:10:55 INFO - PROCESS | 1935 | Values: 75.0 78.0 75.0 157.0 58.0 57.0 58.0 61.0 139.0 60.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:70.50 stddev:23.34 (33.1%) stddev-sans-first:24.62 09:10:55 INFO - PROCESS | 1935 | Values: 82.0 82.0 129.0 70.0 71.0 82.0 118.0 67.0 70.0 117.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:89.00 stddev:1.51 (1.7%) stddev-sans-first:1.51 09:10:55 INFO - PROCESS | 1935 | Values: 89.0 86.0 89.0 86.0 89.0 86.0 89.0 86.0 89.0 87.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.60 09:10:55 INFO - PROCESS | 1935 | Values: 92.0 94.0 94.0 95.0 94.0 93.0 93.0 94.0 94.0 94.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 09:10:55 INFO - PROCESS | 1935 | Values: 86.0 85.0 84.0 86.0 86.0 85.0 85.0 84.0 85.0 84.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.50 stddev:1.45 (2.7%) stddev-sans-first:1.48 09:10:55 INFO - PROCESS | 1935 | Values: 55.0 56.0 55.0 54.0 53.0 53.0 54.0 51.0 53.0 55.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:72.00 stddev:4.37 (6.1%) stddev-sans-first:4.10 09:10:55 INFO - PROCESS | 1935 | Values: 65.0 65.0 68.0 75.0 72.0 78.0 75.0 69.0 69.0 72.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:87.00 stddev:7.75 (8.9%) stddev-sans-first:8.05 09:10:55 INFO - PROCESS | 1935 | Values: 86.0 86.0 90.0 87.0 87.0 88.0 87.0 86.0 98.0 110.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:152.50 stddev:14.40 (9.4%) stddev-sans-first:15.14 09:10:55 INFO - PROCESS | 1935 | Values: 144.0 154.0 161.0 151.0 149.0 134.0 144.0 162.0 173.0 123.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:174.00 stddev:1.71 (1.0%) stddev-sans-first:1.22 09:10:55 INFO - PROCESS | 1935 | Values: 178.0 174.0 176.0 173.0 173.0 174.0 173.0 176.0 174.0 173.0 09:10:55 INFO - PROCESS | 1935 | 09:10:55 INFO - PROCESS | 1935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:71.50 stddev:8.87 (12.4%) stddev-sans-first:9.27 09:10:55 INFO - PROCESS | 1935 | Values: 70.0 80.0 91.0 88.0 73.0 67.0 69.0 68.0 68.0 69.0 09:10:55 INFO - PROCESS | 1935 | -------- Summary: end -------- 09:10:55 INFO - PROCESS | 1935 | 09:10:56 INFO - TEST-INFO | 1935: exit 0 09:10:56 INFO - TEST-OK | kraken | took 59477ms 09:10:56 INFO - SUITE-END | took 1177s 09:10:56 INFO - Completed test suite (00:19:37) 09:10:56 INFO - javascript benchmark 09:10:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2970.6583742869566, 3245.318483118545, 3304.5538852254954, 3325.218573635194, 3343.8996830095107, 5493.148561972891, 5535.833924982252, 5631.380548139701, 5638.492535671789, 5647.915281270779, 5575.776968921248, 5614.438556144383, 5693.914591281134, 5712.742926568304, 5780.855478613037, 5117.846464606049, 5120.974395128019, 5140.974295128538, 5164.199549069881, 5241.528262456378, 5285.788568457277, 5287.762050707708, 5288.788448462057, 5301.973490132544, 5323.547498462635, 5114.0, 5169.974150129263, 5177.741112944375, 5215.8435246942845, 5227.059129356714, 4663.2305669564485, 4935.728534930578, 5231.476852314776, 5238.554722848562, 5243.842684719464, 5145.948540514584, 5167.844964651066, 5188.870278243036, 5195.454477279869, 5210.739463026872, 5663.461971112738, 5663.915041274384, 5664.122061080539, 5667.971660141714, 5679.488846003879, 6056.507513450625, 6119.296280927706, 6133.1510485532735, 6133.386661333876, 6136.702895113906, 5340.118880384771, 5820.505257053155, 6227.657478838662, 6247.843803904939, 6261.843453913689, 5754.3094828620415, 5775.133729940459, 5780.161876527922, 5780.219670344533, 5784.855378615568, 6152.953997820381, 6160.014397696436, 6179.258488981351, 6214.689265536705, 6249.6875156242, 5922.082077278029, 5944.821655350346, 5971.0, 5998.25021872266, 6011.278646562353, 6070.332263451017, 6113.174721412553, 6125.938740612537, 6126.356732543108, 6142.692865356715, 5989.760409583655, 6098.32918378978, 6102.2771839767565, 6122.112293717386, 6125.785597504066, 6183.536234782409, 6213.875722485525, 6222.253329600477, 6226.221722284715, 6226.283977342573, 4968.0, 4980.825671101494, 4989.301497790312, 4998.500149985045, 5011.473795251519, 4346.739195648272, 4370.737755734666, 4417.580329868657, 4432.29083346663, 4469.7318160910445, 5584.329880414295, 5615.943840561624, 5642.125470552071, 5672.205891175238, 5723.141528770634, 6229.4393504584805, 6243.843903902439, 6244.750209991549, 6254.874902501924, 6263.060540918807, 5475.616706830563, 5606.943930560642, 5639.379668236491, 5646.774129034875, 5653.010723123519, 5337.2527846101575, 5365.168398898177, 5367.114426119647, 5393.919091213635, 5449.5640348772, 6224.782132625336, 6303.621782693053, 6317.336679648571, 6323.873522529524, 6331.525135614939, 6119.357467465852, 6228.906566401598, 6232.0, 6235.875282494416, 6247.125402443752, 6266.216722909637, 6289.937100628935, 6293.150424692791, 6294.339094395205, 6319.494440444845, 6116.480099191618, 6150.1082343059115, 6154.507639388748, 6156.199694039925, 6177.84555386101, 6104.847378815565, 6188.133661287513, 6200.627962322275, 6262.718177682106, 6274.215723034621, 5958.493528050164, 6004.849878753066, 6010.0, 6099.939000610115, 6120.265568131853, 6053.243344581927, 6054.212952316171, 6055.485283750931, 6059.697015149313, 6080.179175811209, 5718.942810571841, 5849.736761845812, 5855.121731740273, 5876.794312199053, 5878.882422351615, 5869.6771677558145, 5999.0, 6006.909896351645, 6027.367126451835, 6035.305939816977, 5807.186993820784, 5818.796342128006, 5832.562557808096, 5866.090755932837, 5875.764969401176], "value": 5645.937505388965, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.8675127588637, 477.6918887317678, 500.7145926821713, 517.1234757086738, 517.9585633149348, 31547.053588392355, 41408.75773726789, 42612.573874261245, 42649.0, 42790.358144627855, 43078.784606077, 43310.7834460828, 43369.34945975813, 43442.78278608603, 43664.90837729059, 8736.432131911406, 9616.423014619128, 9809.754756131082, 9817.410955342666, 9843.753906152368, 11956.581519646814, 12226.694332641666, 12238.632841014783, 12395.318257495834, 12445.751084978296, 12063.698407539838, 12085.159987402953, 12130.514779408857, 12197.573084942029, 12215.572454964078, 12129.21160124589, 12184.39078046094, 12201.20692155007, 12225.449854756513, 12248.81626775599, 12009.819852702216, 12090.758184836342, 12112.21270617407, 12114.81827772584, 12115.454804533818, 40921.181576368304, 41282.79358603218, 41289.58710412888, 41294.0, 41346.0, 3296.571445712066, 3612.2233719750116, 3849.441830934527, 3973.22522108189, 4113.958860411388, 30649.387012259853, 31149.377012459852, 31270.21824454384, 31909.84045079783, 31943.680563194535, 3207.935841283185, 3234.191452136966, 3255.7720959532844, 3258.9022329329923, 3346.113279980807, 492216.9999999964, 494715.0, 495103.0, 495450.0, 496154.0, 59861.10208346878, 60290.0, 60363.0, 60382.698086509285, 60432.0, 34144.65855341464, 34932.12669683278, 63474.0, 63569.185848849214, 63599.0, 16083.839161608234, 16476.588085297724, 16645.583860403585, 16677.332906683838, 16680.0, 11734.413279335999, 11921.761564768829, 11926.761464770656, 12749.17130386522, 12861.035422343362, 16852.07313597764, 16997.0, 19049.0, 19471.02644867751, 19474.5131371714, 6513.609183449008, 7611.467197296247, 7665.578393188317, 7814.413918956101, 7874.960625196837, 862.8101817600118, 937.7749340158313, 949.3259785552198, 1002.4386343647581, 1027.7173777211326, 24414.38964025878, 29818.701812982028, 30131.397372052437, 30555.042321040408, 30852.69147308543, 1009.5204777730519, 1010.95450704717, 1015.9746006349752, 1061.4215252687304, 1071.8552995345685, 15525.689486210438, 15564.688706225812, 15645.0, 15680.686386272211, 16003.599910002344, 624147.0, 624441.0, 633998.0, 634071.0, 634157.0, 58264.70867645634, 58869.0, 59082.70458647679, 59140.0, 59141.704291478265, 1234.697499112723, 1268.365817091436, 1322.0679421007974, 1370.8903287736957, 1396.399548194266, 24526.877365613058, 31203.0, 31242.531362030044, 31882.52176217405, 31918.840405797822, 49789.50210497993, 50411.0, 50429.73925651888, 50515.2422713667, 50520.736981574286, 498225.5088724533, 502716.0, 502755.0, 505168.0, 506562.0, 26568.0, 26833.463330732884, 27127.0, 27338.72661273362, 28040.158795236177, 47159.0, 47361.52638473571, 47904.5209547914, 48153.75923120362, 48286.0, 38890.6110938887, 38976.0, 39110.217795644494, 40014.1997160061, 40450.79774601226, 38613.80693096516, 38939.0, 39119.80440097781, 39857.00357491086, 40260.993475163355, 38176.61823381731, 38687.41968870413, 38873.0, 38908.832735018, 39435.0, 32450.0, 33688.157796054315, 34124.56121160338, 34445.827770861986, 34488.65511344854, 20756.650817696802, 21675.891620541795, 25075.996960121392, 25566.74433255718, 25850.0], "value": 20369.988537430418, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.9880304189354, 359.4715767821303, 360.2111376086369, 371.75278439837507, 392.47212499188583, 14854.034487758301, 15676.764848527268, 18970.810291897076, 20565.971701414906, 21266.680999784978, 20343.49141271469, 20433.386998390033, 20693.482662933435, 21146.365609031753, 21419.250326238584, 5928.466438020567, 6272.717727702265, 6291.3708629136945, 6294.496440284766, 6296.590721603104, 4304.80628371724, 5630.436956304377, 6173.444390004899, 6909.0, 6928.757493487729, 6899.827504312408, 6901.0, 6908.309169083077, 6912.3778859902595, 6915.619640919747, 6522.967385163068, 6758.391744742972, 6789.830254243683, 6809.659517024179, 6812.14848143982, 5775.931452681268, 5877.559183061245, 5891.293044834647, 5898.970505147489, 5974.312954010301, 21075.68386474204, 25114.372140696447, 25286.747132528806, 27005.729942700516, 27183.0, 2175.8259339252822, 2248.415411992877, 2262.468319944812, 2352.1532248390567, 2358.0803486640225, 9231.538423078819, 9807.411555306704, 10352.637657681946, 11060.668179954615, 14924.0299380541, 1835.7613510243716, 1896.3362823011973, 1926.1717461491553, 1929.8552608554273, 1933.5069557262807, 119083.0, 120028.39985800015, 121661.78338216593, 124218.230538877, 125030.37484812518, 11046.061084807721, 11492.885071149349, 11520.711982200512, 11558.07535397183, 11683.474243659055, 12617.053720970964, 13321.267330296732, 13329.400176992058, 13570.728585428236, 13594.728105437836, 6523.739050437929, 7534.623268836646, 7681.308682218627, 7902.881456778153, 8731.432456890281, 5934.1692163097205, 5957.463828255478, 7192.964035179791, 8587.398882078194, 8816.8677469838, 4411.955880441219, 4485.663575231871, 4500.369948207253, 4553.157665831832, 4639.976800115978, 1829.8078701736392, 1881.3509339277928, 1882.8399586035341, 1884.9717254241198, 1894.2612381171355, 803.4616806739382, 848.4654667559521, 866.1944391715639, 896.614455784006, 906.2795077913075, 17545.94724316545, 21010.684839727415, 21725.13099476003, 21740.0, 21789.23737669174, 841.8316336732678, 857.9790049840669, 864.9005364383177, 927.8051609162017, 964.363520076746, 10059.446730429896, 11281.04111150545, 11313.038391736794, 11337.206395552397, 11351.716207094723, 573179.0, 574677.7596672106, 579924.0, 592480.0375998178, 594146.0, 129252.06121908179, 134234.32882835524, 136322.0, 142367.0, 146917.0, 991.4249735153594, 1008.8638033865334, 1016.2632091733582, 1038.4081073787868, 1038.657243109787, 18962.715559266348, 19799.71301865374, 19924.90037549803, 19937.700934486285, 20118.49703757418, 32026.19934501656, 43646.781766090964, 52836.207456888944, 56847.43152568587, 57419.0, 84950.0, 85520.14479855122, 85600.1439985617, 86776.56611716902, 87584.1241587576, 7198.244184360776, 7820.687172513263, 8024.398170137147, 8207.917920820953, 8281.54451505149, 10051.748706282402, 12581.496740130293, 12745.17156384832, 13003.6996300373, 13165.47338106465, 10355.378677279386, 10972.122230221403, 11167.4822430934, 11402.657920262405, 11495.425228738697, 10909.127269818555, 10984.835227471754, 11330.289374993901, 11437.771244575228, 11473.885261147281, 9786.119249267314, 11193.27243729155, 11496.770064598828, 11584.189106762782, 11591.826122608, 7955.323797477048, 8175.1416101308505, 8186.959065204874, 8248.587570621565, 8335.541545215072, 8463.0, 9785.36395134314, 11242.718932026764, 11307.0, 11316.0], "value": 9573.675297409298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.8504164541903, 1376.5250988408995, 1380.868817462341, 1675.388483203631, 1904.7142928560713, 7781.961090194548, 8584.270337021353, 8845.336599755012, 8902.198802107809, 9018.954905225464, 8740.388172827905, 8819.955900220506, 8838.0, 8843.602037908293, 8858.512781796999, 2045.5602045560202, 2326.848754830939, 2370.4429459077105, 2373.240563019826, 2379.0721618568687, 2321.7097862767155, 2355.9528809423887, 2366.018102487474, 2366.171839856054, 2371.9762802372015, 2334.159702507096, 2363.503664230505, 2364.515274368759, 2367.585672507313, 2369.8104151667826, 2308.9422764430856, 2334.7081614798067, 2335.322756400642, 2339.076064954342, 2339.356676913845, 2290.1297506947253, 2336.8130549555995, 2340.6020976434047, 2340.8829558522007, 2341.789238968501, 8706.738797836075, 8795.780105497413, 8879.0, 8881.42270752399, 8884.156005179497, 5171.120909445366, 5352.170413585862, 5363.704996225206, 5370.194470829368, 5378.252068077852, 3591.4612808078737, 3610.837512311952, 3672.2839046385966, 3676.1361080146144, 3683.834227459744, 1092.5083712329404, 1095.2606990281529, 1096.2271598523103, 1120.3334016260312, 1121.1479275750407, 11433.942830285878, 11468.13988950832, 11556.248843825124, 11558.884411155948, 11566.826497602458, 4363.890902727394, 4466.59800617946, 4629.421322334708, 4638.6752927294765, 4644.2801365788355, 9199.264058875275, 9199.95400023009, 9205.0, 9221.90778092224, 9231.584578693975, 5240.921386179134, 5245.606579506476, 5245.737713114329, 5247.212918062245, 5250.448702886142, 4266.719984004786, 4403.251447253974, 4588.816447342069, 4866.172750632401, 4921.064997650435, 3681.3189559931498, 3715.108373990223, 3828.310904037244, 3835.9041023974064, 3847.3844184930276, 1077.53665923652, 1107.8725946516092, 1116.2967330581844, 1142.4744617475887, 1153.460130725474, 771.2711487644123, 864.8745931839848, 901.6032945503957, 902.5848109869403, 940.9905900941039, 5813.09896965971, 5948.226730525091, 6027.457528822427, 6032.095185722089, 6136.048912418493, 871.4117970370102, 882.594006756899, 898.3172788680649, 956.4978386347084, 957.482959202037, 3927.6465118139504, 3966.5438474575217, 3979.265512200692, 4100.979495102565, 4118.444010058603, 9875.0, 10833.241673082966, 10895.727606809893, 10928.180386471047, 10936.015758581765, 10911.236213464981, 10916.672499825017, 10918.563257469772, 10921.12630989519, 10931.617393391192, 8080.4343695940715, 8190.5904704764525, 8210.425270231146, 8216.958915205505, 8233.38249631268, 5514.117741161395, 5637.830865074055, 5921.230240068763, 5931.1992880960515, 5949.077892926603, 9993.500324983866, 10180.643677471253, 10248.077673009466, 10262.692119236435, 10271.178305735673, 9763.267754918268, 10269.435181065113, 10271.383716977005, 10272.33229840058, 10274.794504109726, 6604.768833090819, 6821.420179284618, 7072.5402848814665, 7202.927970720226, 7214.639268036683, 8195.975503062116, 8328.83342333162, 8493.87259191125, 8571.22858942698, 8603.182697643713, 7464.104307483137, 7602.46782725204, 7619.69521219145, 7707.383409327353, 7773.339266162439, 7234.96382518084, 7319.048523691886, 7363.889541656987, 7400.629968501446, 7452.925470745223, 6982.546134501241, 7171.354578087997, 7173.748918787817, 7174.067371241914, 7185.425165986813, 4999.45006049327, 5028.9245661315845, 5077.31456253401, 5085.8728531787, 5164.3802743671, 4952.479989601185, 4994.75236143744, 5131.615128865275, 5156.484351564814, 5225.242339860775], "value": 4556.8089195901675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.97118316985126, 293.3853576756694, 307.5340858599222, 309.8806959320661, 312.02336686172276, 4190.497140343159, 4362.803673834677, 4917.704937703736, 5104.5335331584865, 5285.603579731516, 3878.4570160177527, 4498.595126438619, 4510.849733317999, 4642.932125611112, 4969.602431805456, 3888.8833334999995, 3937.7243592948575, 4020.4170395292663, 4035.717499775017, 4058.756474611518, 3935.701218597856, 3994.740341877783, 4027.6979226558124, 4030.798460076999, 4047.817848196838, 3439.690427861492, 3870.8645197418095, 3978.0452691354158, 4019.8794036178815, 4075.6535694466006, 3977.443157957888, 4037.788663400968, 4049.0, 4055.2700513907484, 4078.5921407859273, 2736.4253506763603, 2755.594646730162, 3061.8315992620396, 3380.577427821522, 3489.0753950203216, 4854.393200849894, 5041.924371134399, 5080.339555857752, 5102.872428189288, 5127.717975511346, 4353.867994321499, 4550.953280745414, 4551.0, 4570.063137056895, 4620.722756634613, 4755.667103302771, 5501.697406642633, 5509.862253443656, 5521.475459831349, 5554.1391084381985, 3863.5943225961432, 3864.0339915021245, 3873.3802591585213, 3880.7387599030426, 3910.120222949842, 4355.825766969318, 5699.430056994309, 5767.048437007888, 5839.737211825478, 5878.706684529397, 3353.2958078803726, 3788.8105594720155, 3816.8473261069803, 3822.522184726909, 3823.1397935464297, 3892.474515940312, 3895.9091454392815, 3900.258950799339, 3907.4724912137067, 3930.5283365995692, 4175.62419382257, 4461.107778444324, 4529.297958816389, 4574.6376413336175, 4576.107659006529, 5477.643953143033, 5506.64206826555, 5511.3937466879415, 5515.944840551543, 5518.944810551843, 1186.4779497021284, 1198.5796830755407, 1199.4902166579343, 1324.735052989406, 1330.8402991641065, 749.056189201599, 833.9499630022218, 846.4413487098485, 873.6505397840788, 890.5725251879135, 4997.002398081593, 5013.724245166478, 5023.623228257895, 5036.571891389273, 5072.4673909239, 5462.6889546508555, 5516.172574113834, 5524.944750552523, 5528.778848846082, 5546.722663866791, 4716.858494245178, 4956.553910148032, 4959.975200123977, 5021.0, 5123.231515272739, 2911.941761164765, 2941.4999450093537, 2963.7475518605584, 2982.910512684623, 3019.184820098561, 5573.123516922693, 5769.567282453749, 5790.189373487715, 5804.593678442468, 5830.8542286442325, 5356.71438854672, 5675.5459563234845, 5736.627119237236, 5795.27622017651, 5860.677662728591, 5514.621344663834, 5726.828195154152, 5803.47768700819, 5817.70911454434, 5841.88316233673, 5080.26336181259, 5465.562754979672, 5486.780528778964, 5524.972375138099, 5657.2928383952, 4630.699004564693, 5155.0, 5536.7231638417115, 5546.389897111234, 5656.3495198051105, 5741.971290143522, 5753.468473871483, 5771.769129234784, 5783.363829978614, 5815.651060936358, 3375.561177046968, 3661.7468721346763, 3718.925621487609, 3847.249786291646, 3948.3287841066513, 5611.737359094172, 5619.8595035124445, 5632.915506267327, 5635.464630860061, 5727.770889164553, 5423.945760542502, 5435.885643443164, 5442.374126975448, 5464.562834973113, 5466.699331536644, 4605.526231605854, 5048.596112310933, 5151.974240128776, 5274.472552744694, 5433.972830135824, 5099.515546023122, 5384.838454846361, 5387.218853266333, 5396.811111611075, 5401.72991350423, 4556.020455602062, 5343.5457986071615, 5351.304330437174, 5395.2176934343515, 5526.447355264441, 4509.849988253106, 4847.054824309226, 5159.626571776875, 5392.352917649907, 5430.348358197041], "value": 4008.1639772836165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.8534015186734, 685.7736946807554, 750.4671683104996, 762.2453770766939, 768.7117331000875, 13769.862301376983, 14212.934029947744, 14685.779713304295, 15476.0, 15549.0, 13874.930625346884, 14194.148351098942, 14200.573982780506, 14206.573802785908, 14226.359813808378, 673.3603077076772, 831.9168083191678, 856.8800367948493, 859.7678626770768, 860.389123722158, 691.6196092149298, 850.0394554153797, 850.7022542110274, 864.0668078475263, 872.0233338660677, 888.5512816027897, 889.6485888074207, 890.1365675294986, 891.4606662968907, 891.6477991193475, 701.8912068629371, 884.3279107878027, 884.9646014159426, 885.335998001502, 887.9467231966103, 654.6792071884743, 655.2333769489709, 655.9967200164016, 668.4184759259426, 670.6345041952147, 14129.010969232158, 14157.009009369349, 14180.716385672229, 14204.928975355057, 14218.431262749582, 10432.582696692123, 10765.461726913623, 10857.185711071701, 10923.016928476396, 11009.944950275198, 12117.33354665493, 12214.694632634166, 12232.633021009384, 12261.0, 12605.621831345074, 4419.690621656486, 4515.7516336601475, 4557.9772101139615, 4561.338605902159, 4561.384213131218, 106472.46763766209, 108923.82152356987, 111956.32065519024, 112880.0, 113191.43404283008, 10180.287379883484, 10248.692539223835, 10788.298760580537, 10916.126709863192, 11070.889291106985, 20249.898750506447, 20251.898740506203, 20266.392008239778, 20291.797082029287, 20346.694799578017, 6523.249826269945, 6718.529702920749, 6764.188297404245, 6815.488838337144, 6816.659167041628, 4287.656987440997, 4399.868003959887, 4503.729776213372, 4504.864854054318, 4551.954480455153, 3018.864151113205, 3206.3907857506997, 3272.3128143090216, 3363.680450357212, 3379.2396710739886, 620.8292719502083, 628.1018144053955, 628.7359309090193, 638.1703785079378, 657.1785268414482, 502.2566601429904, 524.6170295684095, 527.2407732864663, 558.3104865491115, 596.8060380376309, 8304.418690691593, 8620.353473489387, 8632.913670863336, 8791.736247912573, 8824.470531768115, 583.3175185033521, 607.0075426677414, 609.558070398966, 617.4473845907887, 622.330994181257, 3663.6336366363685, 4087.4481944937647, 4181.958180418157, 4307.267764480046, 4364.825406983685, 89818.10181898228, 106286.93713062925, 143805.0, 147285.26357368144, 148878.0, 111959.88040119657, 114015.0, 120551.0, 123490.0, 124169.0, 67314.0, 90198.54900725455, 90973.54513227523, 94639.0, 107608.0, 9242.306826988137, 9396.295277854326, 9562.760930976503, 9577.904220957702, 9740.415575065519, 53141.20288195603, 62304.0, 63545.682271588346, 64213.67893160504, 65375.34624653692, 84541.0, 92818.53590732004, 94502.52748736212, 95644.5653315187, 97885.72199918906, 12168.330741809286, 12618.49526018949, 13069.607911762663, 13074.477020919057, 13279.933600331937, 14098.859011409755, 14382.137071775727, 14563.635909102357, 14613.050151740103, 14694.485693000694, 13525.864741352461, 13676.452941882213, 13972.790408143683, 14066.156030638194, 14108.858911411166, 13196.20822750638, 13539.255340956342, 13581.592552223448, 13763.560833225443, 13815.309234538032, 13166.01254905905, 13297.734045318846, 13461.46154153862, 13469.46122155142, 13581.592552223448, 11072.889271107186, 11286.943565282121, 11323.0, 11340.886591133984, 11406.414248596706, 9162.450252984841, 9757.24346126732, 10697.893021070002, 11048.337099774039, 11653.76692466163], "value": 7497.072655035766, "unit": "score"}], "name": "dromaeo_css", "value": 7295.5593565485215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 132.0, 114.0, 115.0, 114.0, 113.0, 112.0, 113.0, 113.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 131.0, 132.0, 192.0, 109.0, 115.0, 148.0, 101.0, 185.0, 139.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 202.0, 264.0, 187.0, 183.0, 184.0, 178.0, 184.0, 189.0], "value": 194.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 157.0, 58.0, 57.0, 58.0, 61.0, 139.0, 60.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 129.0, 70.0, 71.0, 82.0, 118.0, 67.0, 70.0, 117.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 89.0, 86.0, 89.0, 86.0, 89.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 94.0, 95.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 86.0, 86.0, 85.0, 85.0, 84.0, 85.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 53.0, 53.0, 54.0, 51.0, 53.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 68.0, 75.0, 72.0, 78.0, 75.0, 69.0, 69.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 90.0, 87.0, 87.0, 88.0, 87.0, 86.0, 98.0, 110.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 154.0, 161.0, 151.0, 149.0, 134.0, 144.0, 162.0, 173.0, 123.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 174.0, 176.0, 173.0, 173.0, 174.0, 173.0, 176.0, 174.0, 173.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 80.0, 91.0, 88.0, 73.0, 67.0, 69.0, 68.0, 68.0, 69.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1503.3, "alertThreshold": 2.0}]} 09:10:56 INFO - Return code: 0 09:10:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:10:56 INFO - # TBPL SUCCESS # 09:10:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:10:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:10:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:10:56 INFO - 2016-08-26 09:10:56.596 screenresolution[1939:16746] starting screenresolution argv=screenresolution get 09:10:56 INFO - 2016-08-26 09:10:56.598 screenresolution[1939:16746] Display 0: 1600x1200x32@60 09:10:56 INFO - 2016-08-26 09:10:56.604 screenresolution[1940:16749] starting screenresolution argv=screenresolution list 09:10:56 INFO - Available Modes on Display 0 09:10:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:10:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:10:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:10:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:10:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:10:56 INFO - Intel Iris: 09:10:56 INFO - Chipset Model: Intel Iris 09:10:56 INFO - Type: GPU 09:10:56 INFO - Bus: Built-In 09:10:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:10:56 INFO - Vendor: Intel (0x8086) 09:10:56 INFO - Device ID: 0x0a2e 09:10:56 INFO - Revision ID: 0x0009 09:10:56 INFO - Displays: 09:10:56 INFO - Display: 09:10:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:10:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:10:56 INFO - Display Serial Number: 1600x1200 60 09:10:56 INFO - Main Display: Yes 09:10:56 INFO - Mirror: Off 09:10:56 INFO - Online: Yes 09:10:56 INFO - Rotation: Supported 09:10:56 INFO - Return code: 0 09:10:56 INFO - Running post-action listener: _resource_record_post_action 09:10:56 INFO - [mozharness: 2016-08-26 16:10:56.842026Z] Finished run-tests step (success) 09:10:56 INFO - Running post-run listener: _resource_record_post_run 09:10:56 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 1052672; Write bytes: 707496960; Read time: 11; Write time: 2126 09:10:56 INFO - TinderboxPrint: CPU usage
53.5% 09:10:56 INFO - TinderboxPrint: I/O read bytes / time
1,052,672 / 11 09:10:56 INFO - TinderboxPrint: I/O write bytes / time
707,496,960 / 2,126 09:10:56 INFO - TinderboxPrint: CPU idle
2,256.0 (46.5%) 09:10:56 INFO - TinderboxPrint: CPU system
968.1 (20.0%) 09:10:56 INFO - TinderboxPrint: CPU user
1,625.6 (33.5%) 09:10:56 INFO - TinderboxPrint: Swap in / out
406,560,768 / 0 09:10:56 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394226176; Write bytes: 388507136; Read time: 29299; Write time: 699 09:10:56 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 1044480; Write bytes: 296923136; Read time: 10; Write time: 1316 09:10:57 INFO - Running post-run listener: _upload_blobber_files 09:10:57 INFO - Blob upload gear active. 09:10:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:10:57 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 09:10:57 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:10:57 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:10:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:10:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:10:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:10:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:59 INFO - (blobuploader) - INFO - Done attempting. 09:10:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:10:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:11:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:00 INFO - (blobuploader) - INFO - Done attempting. 09:11:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:03 INFO - (blobuploader) - INFO - Done attempting. 09:11:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:03 INFO - Return code: 0 09:11:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbfabd8c1a35b93debf57116ee124eb02f5f8cf02a84e35043478fcacb6db0ef7842ed342770216dcfc860402b46fb2625662b52e6e12b01b7b63e8732ee55c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7805d1590a658dc2819223082095b2067cc357ef6779dc7f0b3ab4f2c110fae5aa09f0f4badc24ec5fc87878cb227eb9db37027787b42bc56d0e2b3f4d6115", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17b79ccf3d29318ff1983750b90c31ff256d6a4f3b216913d501dfa609617b61b4dad445b07899b528c587fc9e9d10feecc4cb0061f08085223728acc227a95a"} 09:11:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:03 INFO - Contents: 09:11:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbfabd8c1a35b93debf57116ee124eb02f5f8cf02a84e35043478fcacb6db0ef7842ed342770216dcfc860402b46fb2625662b52e6e12b01b7b63e8732ee55c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7805d1590a658dc2819223082095b2067cc357ef6779dc7f0b3ab4f2c110fae5aa09f0f4badc24ec5fc87878cb227eb9db37027787b42bc56d0e2b3f4d6115", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17b79ccf3d29318ff1983750b90c31ff256d6a4f3b216913d501dfa609617b61b4dad445b07899b528c587fc9e9d10feecc4cb0061f08085223728acc227a95a"} 09:11:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:03 INFO - Copying logs to upload dir... 09:11:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.124055 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 09:11:03.981939) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:11:03.985023) ========= 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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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/Firefox/sha512/6bbfabd8c1a35b93debf57116ee124eb02f5f8cf02a84e35043478fcacb6db0ef7842ed342770216dcfc860402b46fb2625662b52e6e12b01b7b63e8732ee55c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7805d1590a658dc2819223082095b2067cc357ef6779dc7f0b3ab4f2c110fae5aa09f0f4badc24ec5fc87878cb227eb9db37027787b42bc56d0e2b3f4d6115", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17b79ccf3d29318ff1983750b90c31ff256d6a4f3b216913d501dfa609617b61b4dad445b07899b528c587fc9e9d10feecc4cb0061f08085223728acc227a95a"} build_url:https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/OdGA1a7aR5-XJfr4IpD4cw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbfabd8c1a35b93debf57116ee124eb02f5f8cf02a84e35043478fcacb6db0ef7842ed342770216dcfc860402b46fb2625662b52e6e12b01b7b63e8732ee55c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c7805d1590a658dc2819223082095b2067cc357ef6779dc7f0b3ab4f2c110fae5aa09f0f4badc24ec5fc87878cb227eb9db37027787b42bc56d0e2b3f4d6115", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17b79ccf3d29318ff1983750b90c31ff256d6a4f3b216913d501dfa609617b61b4dad445b07899b528c587fc9e9d10feecc4cb0061f08085223728acc227a95a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:11:04.018012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:11:04.018310) ========= 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.VJqpSaqyEs/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.YaMu9uCYo4/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.005268 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:11:04.066178) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:11:04.066464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:11:04.066773) ========= ========= Total master_lag: 0.33 =========