builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0384 starttime: 1470721476.04 results: success (0) buildid: 20160808203720 builduid: 15bd482f02a34fdaa0c02cf5af2eb9df revision: 3444b7c5c08f61d3759ac47ac98c0458a3958ac8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.036012) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.036766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.037049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.065441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.065846) ========= 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.duljB77ipj/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.0QLNCxz9ZI/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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-08 22:44:36-- 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% 19.0M=0.001s 2016-08-08 22:44:36 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128281 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.223516) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.223835) ========= 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.duljB77ipj/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.0QLNCxz9ZI/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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.025410 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.286080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:36.286459) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3444b7c5c08f61d3759ac47ac98c0458a3958ac8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3444b7c5c08f61d3759ac47ac98c0458a3958ac8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.duljB77ipj/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.0QLNCxz9ZI/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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-08 22:44:36,375 truncating revision to first 12 chars 2016-08-08 22:44:36,375 Setting DEBUG logging. 2016-08-08 22:44:36,375 attempt 1/10 2016-08-08 22:44:36,375 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3444b7c5c08f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 22:44:37,050 unpacking tar archive at: autoland-3444b7c5c08f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.946994 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:37.260298) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:37.263774) ========= script_repo_revision: 3444b7c5c08f61d3759ac47ac98c0458a3958ac8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:37.264283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:37.264591) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 22:44:37.282290) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-08 22:44:37.282589) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.duljB77ipj/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.0QLNCxz9ZI/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:44:37 INFO - MultiFileLogger online at 20160808 22:44:37 in /builds/slave/test 22:44:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:44:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:44:37 INFO - {'append_to_log': False, 22:44:37 INFO - 'base_work_dir': '/builds/slave/test', 22:44:37 INFO - 'blob_upload_branch': 'Autoland', 22:44:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:44:37 INFO - 'branch': 'Autoland', 22:44:37 INFO - 'buildbot_json_path': 'buildprops.json', 22:44:37 INFO - 'config_files': ('talos/mac_config.py',), 22:44:37 INFO - 'default_actions': ('clobber', 22:44:37 INFO - 'read-buildbot-config', 22:44:37 INFO - 'download-and-extract', 22:44:37 INFO - 'populate-webroot', 22:44:37 INFO - 'create-virtualenv', 22:44:37 INFO - 'install', 22:44:37 INFO - 'run-tests'), 22:44:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:44:37 INFO - 'download_minidump_stackwalk': True, 22:44:37 INFO - 'download_symbols': 'ondemand', 22:44:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:44:37 INFO - 'tooltool.py': '/tools/tooltool.py', 22:44:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:44:37 INFO - '/tools/misc-python/virtualenv.py')}, 22:44:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:44:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:44:37 INFO - 'installer_path': 'installer.exe', 22:44:37 INFO - 'log_level': 'info', 22:44:37 INFO - 'log_name': 'talos', 22:44:37 INFO - 'log_to_console': True, 22:44:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:44:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:44:37 INFO - 'opt_config_files': (), 22:44:37 INFO - 'pip_index': False, 22:44:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:44:37 INFO - 'cmd': ('bash', 22:44:37 INFO - '-c', 22:44:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:44:37 INFO - 'enabled': True, 22:44:37 INFO - 'halt_on_failure': False, 22:44:37 INFO - 'name': 'check_screen_resolution'},), 22:44:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:44:37 INFO - 'cmd': ('bash', 22:44:37 INFO - '-c', 22:44:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:44:37 INFO - 'enabled': True, 22:44:37 INFO - 'halt_on_failure': False, 22:44:37 INFO - 'name': 'check_screen_resolution'},), 22:44:37 INFO - 'run_cmd_checks_enabled': True, 22:44:37 INFO - 'sps_profile': False, 22:44:37 INFO - 'sps_profile_interval': 0, 22:44:37 INFO - 'suite': 'dromaeojs', 22:44:37 INFO - 'system_bits': '32', 22:44:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:44:37 INFO - 'title': 't-yosemite-r7-0384', 22:44:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:44:37 INFO - 'use_talos_json': True, 22:44:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:44:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:44:37 INFO - 'work_dir': 'build'} 22:44:37 INFO - [mozharness: 2016-08-09 05:44:37.475936Z] Running clobber step. 22:44:37 INFO - Running pre-action listener: _resource_record_pre_action 22:44:37 INFO - Running main action method: clobber 22:44:37 INFO - rmtree: /builds/slave/test/build 22:44:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:44:39 INFO - Running post-action listener: _resource_record_post_action 22:44:39 INFO - [mozharness: 2016-08-09 05:44:39.542709Z] Finished clobber step (success) 22:44:39 INFO - [mozharness: 2016-08-09 05:44:39.542907Z] Running read-buildbot-config step. 22:44:39 INFO - Running pre-action listener: _resource_record_pre_action 22:44:39 INFO - Running main action method: read_buildbot_config 22:44:39 INFO - Using buildbot properties: 22:44:39 INFO - { 22:44:39 INFO - "project": "", 22:44:39 INFO - "product": "firefox", 22:44:39 INFO - "script_repo_revision": "production", 22:44:39 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:44:39 INFO - "repository": "", 22:44:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:44:39 INFO - "buildid": "20160808203720", 22:44:39 INFO - "pgo_build": "False", 22:44:39 INFO - "basedir": "/builds/slave/test", 22:44:39 INFO - "buildnumber": 94, 22:44:39 INFO - "slavename": "t-yosemite-r7-0384", 22:44:39 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:44:39 INFO - "platform": "yosemite_r7", 22:44:39 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:44:39 INFO - "branch": "autoland", 22:44:39 INFO - "repo_path": "integration/autoland", 22:44:39 INFO - "revision": "3444b7c5c08f61d3759ac47ac98c0458a3958ac8", 22:44:39 INFO - "stage_platform": "macosx64", 22:44:39 INFO - "builduid": "15bd482f02a34fdaa0c02cf5af2eb9df", 22:44:39 INFO - "slavebuilddir": "test" 22:44:39 INFO - } 22:44:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:44:39 INFO - Running post-action listener: _resource_record_post_action 22:44:39 INFO - [mozharness: 2016-08-09 05:44:39.548066Z] Finished read-buildbot-config step (success) 22:44:39 INFO - [mozharness: 2016-08-09 05:44:39.548199Z] Running download-and-extract step. 22:44:39 INFO - Running pre-action listener: _resource_record_pre_action 22:44:39 INFO - Running main action method: download_and_extract 22:44:39 INFO - mkdir: /builds/slave/test/build/tests 22:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:39 INFO - trying https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:44:39 INFO - Downloading https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:40 INFO - Downloaded 1199 bytes. 22:44:40 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:44:40 INFO - Using the following test package requirements: 22:44:40 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:44:40 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:44:40 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:44:40 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'jsshell-mac.zip'], 22:44:40 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:44:40 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:44:40 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:44:40 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:44:40 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:44:40 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:44:40 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:44:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:40 INFO - trying https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:44:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:41 INFO - Downloaded 16324931 bytes. 22:44:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:44:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:44:44 INFO - Return code: 0 22:44:44 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 22:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:44 INFO - trying https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:44:44 INFO - Downloading https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:45 INFO - Downloaded 16324931 bytes. 22:44:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:44:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:44:48 INFO - Return code: 0 22:44:48 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')]} 22:44:48 INFO - trying https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:44:48 INFO - Downloading https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:49 INFO - Downloaded 14413723 bytes. 22:44:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:44:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:44:49 INFO - Return code: 0 22:44:49 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')]} 22:44:49 INFO - trying https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:44:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/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 22:44:53 INFO - Downloaded 111948717 bytes. 22:44:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:44:53 INFO - mkdir: /builds/slave/test/properties 22:44:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:44:53 INFO - Writing to file /builds/slave/test/properties/build_url 22:44:53 INFO - Contents: 22:44:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:44:53 INFO - Running post-action listener: _resource_record_post_action 22:44:53 INFO - Running post-action listener: set_extra_try_arguments 22:44:53 INFO - [mozharness: 2016-08-09 05:44:53.878907Z] Finished download-and-extract step (success) 22:44:53 INFO - [mozharness: 2016-08-09 05:44:53.879061Z] Running populate-webroot step. 22:44:53 INFO - Running pre-action listener: _resource_record_pre_action 22:44:53 INFO - Running main action method: populate_webroot 22:44:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:44:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:44:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:44:53 INFO - '--tppagecycles', 22:44:53 INFO - '7'], 22:44:53 INFO - 'tests': ['tsvgm']}}, 22:44:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:44:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:44:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:44:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:44:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:44:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:44:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['damp', 'tps']}, 22:44:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'tests': ['damp', 'tps']}, 22:44:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['dromaeo_dom']}, 22:44:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:44:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['basic_compositor_video']}, 22:44:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:44:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['a11yr', 22:44:53 INFO - 'ts_paint', 22:44:53 INFO - 'tpaint', 22:44:53 INFO - 'sessionrestore', 22:44:53 INFO - 'sessionrestore_no_auto_restore', 22:44:53 INFO - 'tabpaint']}, 22:44:53 INFO - 'other-e10s': {'tests': ['a11yr', 22:44:53 INFO - 'ts_paint', 22:44:53 INFO - 'tpaint', 22:44:53 INFO - 'sessionrestore', 22:44:53 INFO - 'sessionrestore_no_auto_restore', 22:44:53 INFO - 'tabpaint']}, 22:44:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:44:53 INFO - 'ts_paint', 22:44:53 INFO - 'tpaint', 22:44:53 INFO - 'sessionrestore', 22:44:53 INFO - 'sessionrestore_no_auto_restore', 22:44:53 INFO - 'tabpaint']}, 22:44:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:44:53 INFO - 'ts_paint', 22:44:53 INFO - 'tpaint', 22:44:53 INFO - 'sessionrestore', 22:44:53 INFO - 'sessionrestore_no_auto_restore', 22:44:53 INFO - 'tabpaint']}, 22:44:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['a11yr', 22:44:53 INFO - 'ts_paint', 22:44:53 INFO - 'tpaint', 22:44:53 INFO - 'sessionrestore', 22:44:53 INFO - 'sessionrestore_no_auto_restore', 22:44:53 INFO - 'tabpaint']}, 22:44:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['a11yr', 22:44:53 INFO - 'ts_paint', 22:44:53 INFO - 'tpaint', 22:44:53 INFO - 'sessionrestore', 22:44:53 INFO - 'sessionrestore_no_auto_restore', 22:44:53 INFO - 'tabpaint']}, 22:44:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['tsvgx', 22:44:53 INFO - 'tsvgr_opacity', 22:44:53 INFO - 'tart', 22:44:53 INFO - 'tscrollx', 22:44:53 INFO - 'cart']}, 22:44:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:44:53 INFO - 'tsvgr_opacity', 22:44:53 INFO - 'tart', 22:44:53 INFO - 'tscrollx', 22:44:53 INFO - 'cart']}, 22:44:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'talos_options': ['--disable-e10s'], 22:44:53 INFO - 'tests': ['tp5o']}, 22:44:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'tests': ['tp5o']}, 22:44:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'talos_options': ['--disable-e10s', 22:44:53 INFO - '--xperf_path', 22:44:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:53 INFO - 'tests': ['tp5n']}, 22:44:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:44:53 INFO - 'talos_options': ['--xperf_path', 22:44:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:53 INFO - 'tests': ['tp5n']}}, 22:44:53 INFO - 'talos.zip': {'path': '', 22:44:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:44:53 INFO - Running post-action listener: _resource_record_post_action 22:44:53 INFO - [mozharness: 2016-08-09 05:44:53.898582Z] Finished populate-webroot step (success) 22:44:53 INFO - [mozharness: 2016-08-09 05:44:53.898696Z] Running create-virtualenv step. 22:44:53 INFO - Running pre-action listener: _resource_record_pre_action 22:44:53 INFO - Running main action method: create_virtualenv 22:44:53 INFO - Creating virtualenv /builds/slave/test/build/venv 22:44:53 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 22:44:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:44:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:44:54 INFO - Using real prefix '/tools/python27' 22:44:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:44:54 INFO - Installing distribute.............................................................................................................................................................................................done. 22:44:57 INFO - Installing pip.................done. 22:44:57 INFO - Return code: 0 22:44:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:57 INFO - 'HOME': '/Users/cltbld', 22:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:57 INFO - 'LOGNAME': 'cltbld', 22:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:57 INFO - 'MOZ_NO_REMOTE': '1', 22:44:57 INFO - 'NO_EM_RESTART': '1', 22:44:57 INFO - 'PAGER': '/bin/cat', 22:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:57 INFO - 'PWD': '/builds/slave/test', 22:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:57 INFO - 'SHELL': '/bin/bash', 22:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:44:57 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:57 INFO - 'USER': 'cltbld', 22:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:57 INFO - 'XPC_FLAGS': '0x0', 22:44:57 INFO - 'XPC_SERVICE_NAME': '0', 22:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:57 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 22:44:57 INFO - Cleaning up... 22:44:57 INFO - Return code: 0 22:44:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:57 INFO - 'HOME': '/Users/cltbld', 22:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:57 INFO - 'LOGNAME': 'cltbld', 22:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:57 INFO - 'MOZ_NO_REMOTE': '1', 22:44:57 INFO - 'NO_EM_RESTART': '1', 22:44:57 INFO - 'PAGER': '/bin/cat', 22:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:57 INFO - 'PWD': '/builds/slave/test', 22:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:57 INFO - 'SHELL': '/bin/bash', 22:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:44:57 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:57 INFO - 'USER': 'cltbld', 22:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:57 INFO - 'XPC_FLAGS': '0x0', 22:44:57 INFO - 'XPC_SERVICE_NAME': '0', 22:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:58 INFO - Downloading/unpacking psutil>=3.1.1 22:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:45:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:01 INFO - Installing collected packages: psutil 22:45:01 INFO - Running setup.py install for psutil 22:45:01 INFO - building 'psutil._psutil_osx' extension 22:45:01 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 22:45:01 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 22:45:01 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 22:45:02 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 22:45:02 INFO - building 'psutil._psutil_posix' extension 22:45:02 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 22:45:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:45:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:45:02 INFO - ^ 22:45:02 INFO - 1 warning generated. 22:45:02 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 22:45:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:02 INFO - Successfully installed psutil 22:45:02 INFO - Cleaning up... 22:45:02 INFO - Return code: 0 22:45:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:02 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:02 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 22:45:02 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 22:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:02 INFO - 'HOME': '/Users/cltbld', 22:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:02 INFO - 'LOGNAME': 'cltbld', 22:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:02 INFO - 'MOZ_NO_REMOTE': '1', 22:45:02 INFO - 'NO_EM_RESTART': '1', 22:45:02 INFO - 'PAGER': '/bin/cat', 22:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:02 INFO - 'PWD': '/builds/slave/test', 22:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:02 INFO - 'SHELL': '/bin/bash', 22:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:02 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:02 INFO - 'USER': 'cltbld', 22:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:02 INFO - 'XPC_FLAGS': '0x0', 22:45:02 INFO - 'XPC_SERVICE_NAME': '0', 22:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:45:02 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 22:45:02 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 22:45:02 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 22:45:02 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 22:45:02 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 22:45:02 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 22:45:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:45:05 INFO - Installing collected packages: mozsystemmonitor 22:45:05 INFO - Running setup.py install for mozsystemmonitor 22:45:05 INFO - Successfully installed mozsystemmonitor 22:45:05 INFO - Cleaning up... 22:45:05 INFO - Return code: 0 22:45:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:45:05 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')]} 22:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:05 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')]} 22:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:05 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:05 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 22:45:05 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 22:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:05 INFO - 'HOME': '/Users/cltbld', 22:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:05 INFO - 'LOGNAME': 'cltbld', 22:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:05 INFO - 'MOZ_NO_REMOTE': '1', 22:45:05 INFO - 'NO_EM_RESTART': '1', 22:45:05 INFO - 'PAGER': '/bin/cat', 22:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:05 INFO - 'PWD': '/builds/slave/test', 22:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:05 INFO - 'SHELL': '/bin/bash', 22:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:05 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:05 INFO - 'USER': 'cltbld', 22:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:05 INFO - 'XPC_FLAGS': '0x0', 22:45:05 INFO - 'XPC_SERVICE_NAME': '0', 22:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:05 INFO - Downloading/unpacking jsonschema==2.5.1 22:45:05 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 22:45:05 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 22:45:05 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 22:45:05 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 22:45:05 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 22:45:05 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 22:45:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:45:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:45:08 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 22:45:08 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 22:45:08 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 22:45:08 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 22:45:08 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 22:45:08 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 22:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 22:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:45:08 INFO - warning: no files found matching '*.txt' 22:45:08 INFO - no previously-included directories found matching 'build' 22:45:08 INFO - no previously-included directories found matching 'dist' 22:45:08 INFO - no previously-included directories found matching '.git*' 22:45:08 INFO - Installing collected packages: jsonschema, functools32 22:45:08 INFO - Running setup.py install for functools32 22:45:08 INFO - warning: no files found matching '*.txt' 22:45:08 INFO - no previously-included directories found matching 'build' 22:45:08 INFO - no previously-included directories found matching 'dist' 22:45:08 INFO - no previously-included directories found matching '.git*' 22:45:08 INFO - Successfully installed jsonschema functools32 22:45:08 INFO - Cleaning up... 22:45:08 INFO - Return code: 0 22:45:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:08 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:08 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 22:45:08 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 22:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:08 INFO - 'HOME': '/Users/cltbld', 22:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:08 INFO - 'LOGNAME': 'cltbld', 22:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:08 INFO - 'MOZ_NO_REMOTE': '1', 22:45:08 INFO - 'NO_EM_RESTART': '1', 22:45:08 INFO - 'PAGER': '/bin/cat', 22:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:08 INFO - 'PWD': '/builds/slave/test', 22:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:08 INFO - 'SHELL': '/bin/bash', 22:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:08 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:08 INFO - 'USER': 'cltbld', 22:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:08 INFO - 'XPC_FLAGS': '0x0', 22:45:08 INFO - 'XPC_SERVICE_NAME': '0', 22:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:45:09 INFO - Cleaning up... 22:45:09 INFO - Return code: 0 22:45:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:09 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:09 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 22:45:09 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 22:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:09 INFO - 'HOME': '/Users/cltbld', 22:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:09 INFO - 'LOGNAME': 'cltbld', 22:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:09 INFO - 'MOZ_NO_REMOTE': '1', 22:45:09 INFO - 'NO_EM_RESTART': '1', 22:45:09 INFO - 'PAGER': '/bin/cat', 22:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:09 INFO - 'PWD': '/builds/slave/test', 22:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:09 INFO - 'SHELL': '/bin/bash', 22:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:09 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:09 INFO - 'USER': 'cltbld', 22:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:09 INFO - 'XPC_FLAGS': '0x0', 22:45:09 INFO - 'XPC_SERVICE_NAME': '0', 22:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:09 INFO - Downloading/unpacking blobuploader==1.2.4 22:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:11 INFO - Downloading blobuploader-1.2.4.tar.gz 22:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:45:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:45:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 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 22:45:12 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 22:45:13 INFO - Downloading docopt-0.6.1.tar.gz 22:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:45:13 INFO - Installing collected packages: blobuploader, requests, docopt 22:45:13 INFO - Running setup.py install for blobuploader 22:45:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:45:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:45:13 INFO - Running setup.py install for requests 22:45:13 INFO - Running setup.py install for docopt 22:45:14 INFO - Successfully installed blobuploader requests docopt 22:45:14 INFO - Cleaning up... 22:45:14 INFO - Return code: 0 22:45:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:14 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:14 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 22:45:14 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 22:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:14 INFO - 'HOME': '/Users/cltbld', 22:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:14 INFO - 'LOGNAME': 'cltbld', 22:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:14 INFO - 'MOZ_NO_REMOTE': '1', 22:45:14 INFO - 'NO_EM_RESTART': '1', 22:45:14 INFO - 'PAGER': '/bin/cat', 22:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:14 INFO - 'PWD': '/builds/slave/test', 22:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:14 INFO - 'SHELL': '/bin/bash', 22:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:14 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:14 INFO - 'USER': 'cltbld', 22:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:14 INFO - 'XPC_FLAGS': '0x0', 22:45:14 INFO - 'XPC_SERVICE_NAME': '0', 22:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:45:14 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-5KOGad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:45:14 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-RPvCuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:45:14 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-ImhcfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:45:14 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-ANIbpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:45:14 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-ft1ghB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:45:14 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-0xKGGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-e4kN8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-7KbiuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-Zj23rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-NrN_SX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-pTG24B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-ASvXl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-uhsy0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-fAfAj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-dj1did-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:45:15 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-6LDULS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:45:16 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-tAdKDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:45:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:45:16 INFO - Running setup.py install for manifestparser 22:45:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:45:16 INFO - Running setup.py install for mozcrash 22:45:16 INFO - Running setup.py install for mozdebug 22:45:16 INFO - Running setup.py install for mozdevice 22:45:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:45:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:45:16 INFO - Running setup.py install for mozfile 22:45:16 INFO - Running setup.py install for mozhttpd 22:45:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Running setup.py install for mozinfo 22:45:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Running setup.py install for mozInstall 22:45:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Running setup.py install for mozleak 22:45:17 INFO - Running setup.py install for mozlog 22:45:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Running setup.py install for moznetwork 22:45:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:45:17 INFO - Running setup.py install for mozprocess 22:45:17 INFO - Running setup.py install for mozprofile 22:45:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:45:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:45:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:45:18 INFO - Running setup.py install for mozrunner 22:45:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:45:18 INFO - Running setup.py install for mozscreenshot 22:45:18 INFO - Running setup.py install for moztest 22:45:18 INFO - Running setup.py install for mozversion 22:45:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:45:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:45:18 INFO - Cleaning up... 22:45:18 INFO - Return code: 0 22:45:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:18 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:18 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 22:45:18 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 22:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:18 INFO - 'HOME': '/Users/cltbld', 22:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:18 INFO - 'LOGNAME': 'cltbld', 22:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:18 INFO - 'MOZ_NO_REMOTE': '1', 22:45:18 INFO - 'NO_EM_RESTART': '1', 22:45:18 INFO - 'PAGER': '/bin/cat', 22:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:18 INFO - 'PWD': '/builds/slave/test', 22:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:18 INFO - 'SHELL': '/bin/bash', 22:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:18 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:18 INFO - 'USER': 'cltbld', 22:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:18 INFO - 'XPC_FLAGS': '0x0', 22:45:18 INFO - 'XPC_SERVICE_NAME': '0', 22:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-qM2q_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-WXpKLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-mdnHfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-hTXQMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-pCl8mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-W_6VsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-9ZOR18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-DRoilT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-d0QzQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:45:19 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)) 22:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:45:19 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-i9xgBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-Xsj6Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-pU54nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-92TE8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-T6162y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-uFdVq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-_pEH7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:45:20 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)) 22:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:45:20 INFO - Running setup.py (path:/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/pip-MDbaoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:45:20 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)) 22:45:20 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)) 22:45:20 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)) 22:45:20 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)) 22:45:20 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)) 22:45:20 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)) 22:45:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:23 INFO - Downloading blessings-1.6.tar.gz 22:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:45:23 INFO - Installing collected packages: blessings 22:45:23 INFO - Running setup.py install for blessings 22:45:23 INFO - Successfully installed blessings 22:45:23 INFO - Cleaning up... 22:45:23 INFO - Return code: 0 22:45:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:45:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:45:24 INFO - Reading from file tmpfile_stdout 22:45:24 INFO - Current package versions: 22:45:24 INFO - blessings == 1.6 22:45:24 INFO - blobuploader == 1.2.4 22:45:24 INFO - docopt == 0.6.1 22:45:24 INFO - functools32 == 3.2.3-2 22:45:24 INFO - jsonschema == 2.5.1 22:45:24 INFO - manifestparser == 1.1 22:45:24 INFO - mozInstall == 1.12 22:45:24 INFO - mozcrash == 0.17 22:45:24 INFO - mozdebug == 0.1 22:45:24 INFO - mozdevice == 0.48 22:45:24 INFO - mozfile == 1.2 22:45:24 INFO - mozhttpd == 0.7 22:45:24 INFO - mozinfo == 0.9 22:45:24 INFO - mozleak == 0.1 22:45:24 INFO - mozlog == 3.3 22:45:24 INFO - moznetwork == 0.27 22:45:24 INFO - mozprocess == 0.23 22:45:24 INFO - mozprofile == 0.28 22:45:24 INFO - mozrunner == 6.12 22:45:24 INFO - mozscreenshot == 0.1 22:45:24 INFO - mozsystemmonitor == 0.3 22:45:24 INFO - moztest == 0.7 22:45:24 INFO - mozversion == 1.4 22:45:24 INFO - psutil == 3.1.1 22:45:24 INFO - requests == 1.2.3 22:45:24 INFO - wsgiref == 0.1.2 22:45:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:24 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 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45:24 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 22:45:24 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 22:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:24 INFO - 'HOME': '/Users/cltbld', 22:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:24 INFO - 'LOGNAME': 'cltbld', 22:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:24 INFO - 'MOZ_NO_REMOTE': '1', 22:45:24 INFO - 'NO_EM_RESTART': '1', 22:45:24 INFO - 'PAGER': '/bin/cat', 22:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:24 INFO - 'PWD': '/builds/slave/test', 22:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:24 INFO - 'SHELL': '/bin/bash', 22:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:24 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:24 INFO - 'USER': 'cltbld', 22:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:24 INFO - 'XPC_FLAGS': '0x0', 22:45:24 INFO - 'XPC_SERVICE_NAME': '0', 22:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 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)) 22:45:24 INFO - Cleaning up... 22:45:24 INFO - Return code: 0 22:45:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c82ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8c1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd13bd51ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, '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.duljB77ipj/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.0QLNCxz9ZI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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 22:45: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'] in /builds/slave/test/build 22:45: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 22:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:24 INFO - 'HOME': '/Users/cltbld', 22:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:24 INFO - 'LOGNAME': 'cltbld', 22:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:24 INFO - 'MOZ_NO_REMOTE': '1', 22:45:24 INFO - 'NO_EM_RESTART': '1', 22:45:24 INFO - 'PAGER': '/bin/cat', 22:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:24 INFO - 'PWD': '/builds/slave/test', 22:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:24 INFO - 'SHELL': '/bin/bash', 22:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:24 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:24 INFO - 'USER': 'cltbld', 22:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:24 INFO - 'XPC_FLAGS': '0x0', 22:45:24 INFO - 'XPC_SERVICE_NAME': '0', 22:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:45:24 INFO - Cleaning up... 22:45:24 INFO - Return code: 0 22:45:24 INFO - Running post-action listener: _resource_record_post_action 22:45:24 INFO - Running post-action listener: _start_resource_monitoring 22:45:24 INFO - Starting resource monitoring. 22:45:24 INFO - [mozharness: 2016-08-09 05:45:24.663973Z] Finished create-virtualenv step (success) 22:45:24 INFO - [mozharness: 2016-08-09 05:45:24.664996Z] Running install step. 22:45:24 INFO - Running pre-action listener: _resource_record_pre_action 22:45:24 INFO - Running main action method: install 22:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:45:24 INFO - Reading from file tmpfile_stdout 22:45:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:45:25 INFO - Reading from file tmpfile_stdout 22:45:25 INFO - Output received: 22:45:25 INFO - Usage: mozinstall [options] installer 22:45:25 INFO - Options: 22:45:25 INFO - -h, --help show this help message and exit 22:45:25 INFO - -d DEST, --destination=DEST 22:45:25 INFO - Directory to install application into. [default: 22:45:25 INFO - "/builds/slave/test"] 22:45:25 INFO - --app=APP Application being installed. [default: firefox] 22:45:25 INFO - mkdir: /builds/slave/test/build/application 22:45:25 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'] 22:45:25 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 22:45:59 INFO - Reading from file tmpfile_stdout 22:45:59 INFO - Output received: 22:45:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:45:59 INFO - Running post-action listener: _resource_record_post_action 22:45:59 INFO - [mozharness: 2016-08-09 05:45:59.497464Z] Finished install step (success) 22:45:59 INFO - [mozharness: 2016-08-09 05:45:59.497595Z] Running run-tests step. 22:45:59 INFO - Running pre-action listener: _resource_record_pre_action 22:45:59 INFO - Running main action method: run_tests 22:45:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:45:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:45:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:45:59 INFO - 2016-08-08 22:45:59.512 screenresolution[1654:8370] starting screenresolution argv=screenresolution get 22:45:59 INFO - 2016-08-08 22:45:59.514 screenresolution[1654:8370] Display 0: 1600x1200x32@60 22:45:59 INFO - 2016-08-08 22:45:59.522 screenresolution[1655:8372] starting screenresolution argv=screenresolution list 22:45:59 INFO - Available Modes on Display 0 22:45:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:45:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:45:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:45:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:45:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:45:59 INFO - Intel Iris: 22:45:59 INFO - Chipset Model: Intel Iris 22:45:59 INFO - Type: GPU 22:45:59 INFO - Bus: Built-In 22:45:59 INFO - VRAM (Dynamic, Max): 1536 MB 22:45:59 INFO - Vendor: Intel (0x8086) 22:45:59 INFO - Device ID: 0x0a2e 22:45:59 INFO - Revision ID: 0x0009 22:45:59 INFO - Displays: 22:45:59 INFO - Display: 22:45:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:45:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:45:59 INFO - Display Serial Number: 1600x1200 60 22:45:59 INFO - Main Display: Yes 22:45:59 INFO - Mirror: Off 22:45:59 INFO - Online: Yes 22:45:59 INFO - Rotation: Supported 22:45:59 INFO - Return code: 0 22:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:45:59 INFO - Python 2.7.3 22:45:59 INFO - Return code: 0 22:45:59 INFO - grabbing minidump binary from tooltool 22:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:59 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 0x10c790d30>, '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 0x10c4f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c788b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:45:59 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 22:45:59 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 22:45:59 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 22:45:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:45:59 INFO - Return code: 0 22:45:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:45:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:45:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:45:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:45:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 22:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0384 --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 22:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duljB77ipj/Render', 22:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:59 INFO - 'HOME': '/Users/cltbld', 22:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:59 INFO - 'LOGNAME': 'cltbld', 22:45:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:45:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:59 INFO - 'MOZ_NO_REMOTE': '1', 22:45:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:45:59 INFO - 'NO_EM_RESTART': '1', 22:45:59 INFO - 'PAGER': '/bin/cat', 22:45:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:59 INFO - 'PWD': '/builds/slave/test', 22:45:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:59 INFO - 'SHELL': '/bin/bash', 22:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0QLNCxz9ZI/Listeners', 22:45:59 INFO - 'TMPDIR': '/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/', 22:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:59 INFO - 'USER': 'cltbld', 22:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:59 INFO - 'XPC_FLAGS': '0x0', 22:45:59 INFO - 'XPC_SERVICE_NAME': '0', 22:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 22:46:00 INFO - mozversion application_buildid: 20160808203720 22:46:00 INFO - mozversion application_changeset: 3444b7c5c08f61d3759ac47ac98c0458a3958ac8 22:46:00 INFO - mozversion application_display_name: Nightly 22:46:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:46:00 INFO - mozversion application_name: Firefox 22:46:00 INFO - mozversion application_remotingname: firefox 22:46:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:46:00 INFO - mozversion application_vendor: Mozilla 22:46:00 INFO - mozversion application_version: 51.0a1 22:46:00 INFO - mozversion platform_buildid: 20160808203720 22:46:00 INFO - mozversion platform_changeset: 3444b7c5c08f61d3759ac47ac98c0458a3958ac8 22:46:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:46:00 INFO - mozversion platform_version: 51.0a1 22:46:00 INFO - using testdate: 1470721560 22:46:00 INFO - actual date: 1470721560 22:46:00 INFO - starting webserver on 'localhost:49271' 22:46:00 INFO - SUITE-START | Running 2 tests 22:46:00 INFO - TEST-START | dromaeo_css 22:46:00 INFO - operating with platform_type : mac_ 22:46:00 INFO - Initialising browser for dromaeo_css test... 22:46:00 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmp9UHHTs/profile http://localhost:49271/getInfo.html) 22:46:02 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 22:46:02 INFO - PROCESS | 1665 | colorDepth:24 22:46:02 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 22:46:02 INFO - PROCESS | 1665 | __metrics 22:46:02 INFO - PROCESS | 1665 | 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" 22:46:02 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:46:03 INFO - TEST-INFO | 1665: exit 0 22:46:03 INFO - Browser initialized. 22:46:03 INFO - Running cycle 1/1 for dromaeo_css test... 22:46:03 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmp9UHHTs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:15 INFO - PROCESS | 1668 | RSS: Main: 243785728 22:46:15 INFO - PROCESS | 1668 | 22:47:07 INFO - PROCESS | 1668 | 1470721627124 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] 22:49:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 22:49:21 INFO - PROCESS | 1668 | RSS: Main: 378318848 22:49:21 INFO - PROCESS | 1668 | 22:52:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 22:52:24 INFO - PROCESS | 1668 | RSS: Main: 390926336 22:52:24 INFO - PROCESS | 1668 | 22:55:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 22:55:27 INFO - PROCESS | 1668 | RSS: Main: 353247232 22:55:27 INFO - PROCESS | 1668 | 22:58:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 22:58:30 INFO - PROCESS | 1668 | RSS: Main: 367599616 22:58:30 INFO - PROCESS | 1668 | 23:01:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 23:01:35 INFO - PROCESS | 1668 | RSS: Main: 351674368 23:01:35 INFO - PROCESS | 1668 | 23:04:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 23:04:39 INFO - PROCESS | 1668 | RSS: Main: 393523200 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | __start_tp_report 23:04:39 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 23:04:39 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 23:04:39 INFO - PROCESS | 1668 | |i|pagename|runs| 23:04:39 INFO - PROCESS | 1668 | |0;dojo.html;2940.735333819956;3302.5541551890487;3306.4048471275178;3311.983440082799;3381.56039714837;5473.452654734525;5557.8888422231585;5565.6104072714825;5569.3316801983765;5603.663780173188;5578.86052848679;5596.776128954847;5604.915926261099;5607.719614019293;5615.354234263061;5110.310108135392;5142.357205349332;5187.221916712486;5255.211718242256;5260.368755749316;5134.25553294772;5204.921926171091;5215.087359712054;5216;5218.739063046852;5122.359705036871;5170.715610641413;5172.948270517303;5179.249008893708;5210.921836172461;5203.713795741214;5204.921926171072;5225.712585807779;5245.4754524547625;5280.762365693553;4991.026749783794;5059.114654935372;5072.742245402156;5125.615578831603;5132.8460146195675;5484.396716361197;5516.558675306006;5542.11326187808;5596.636218645775;5614.578906581982;6147.016477363645;6170.475509581691;6170.938290617081;6172.59878107926;6178.41305076017;6109.663968481732;6119.969400153015;6189.845253868644;6215.2541694996435;6221.253449586078;5697.059985102412;5706.543476521868;5715.485606295453;5718.7426565804635;5724.169995350734;6219.533534984895;6225.844353891189;6232.0963460298;6235.314115447297;6242.344553821874;5869.530437564985;5980.252468441445;6016.127661489147;6057.804617411022;6095.2685677718955;6197.5351848611535;6201.906971395432;6209.813705588839;6217.285012223561;6227.595206311576;6087.117367981707;6134.355892631299;6156;6165;6177.413145751147;6032.909506357408;6099.451049405575;6117.877642447126;6189.412005859436;6223;4846.321514987904;4849.75751212438;4869.196582563908;4880.048390563806;4883.02339532095;4402.163588918095;4431.158079964796;4458.286674132123;4463.152001119777;4464.107178564235;5608.495235428831;5610.242617246619;5684.459976302244;5691.601587888808;5754.223179870748;6209.844753881099;6257.843553911189;6258.749650013949;6270.905936410957;6284.37156284377;4582.725036497821;5379.058664733656;5623.847419462329;5651.886962260732;5692.288463942007;5268.315119034501;5308.203769434615;5400.351957765093;5411.404745477994;5412;6145.617236121926;6207.131001659862;6214.2542894852;6221.253449586078;6273.686315684106;6217.253929528486;6230.470410015199;6231.781887633911;6232.065190221503;6246.843828904313;6267.62394256346;6287.182666253358;6290.559660823698;6293.5909165905905;6327.96836015817;5977.641341519523;6053.667048312385;6081.331053584191;6094.695265236632;6104.277264894319;6226.377362263737;6230.7819226328675;6240.469560087444;6243.8126856194385;6258.624482531062;4718.480967093548;5958.731857066356;6022.608530445507;6063.848403789941;6072.180255665606;5889.587728858939;5989;5989.700514974321;6024.969875150597;6080.726367313393;5897.321807992136;5915.497182739516;5979.923613749567;5984.222051133499;6004.189434426296;6061.696915154313;6081.5742897996715;6089.756409743738;6106.877862442815;6108.541859360477;5675;5798.652080875161;5876.941230587639;5894.086416605433;5899.233099697012 23:04:39 INFO - PROCESS | 1668 | |1;ext.html;501.85195367366623;531.704903778403;533.981310654127;541.3017207801938;551.6882961126963;33366.8331658342;34098.31803363934;34170.65829341706;37030.814845925735;42332.78833605835;46023.30965035527;46566.068678626405;46710.29934550985;46723.76638116805;46749.53250467494;8374.748757537283;9739.853902191473;9924.354916930377;9936.304458687895;9951;8896.955515222415;9116.544172791368;9842.507874606277;11952.223105498115;12131.090168237373;12076.21504602198;12162.331071791048;12189.817152742717;12205.816912746315;12211.08416868734;11976.04191664669;12107.091968102428;12107.455164517618;12120.757584848297;12132.636020919299;12046.939765301206;12049.216800907912;12071.879281207252;12080.577179798665;12108.939455302756;42692.573074269174;42962;43045;43152.352714709305;43235.35146972798;3057.541368794676;3318.4026875162463;3877.243937432202;4009.8997525062105;4411.139827733593;32811.67188328134;34104.14739631529;34146.65853341459;34400;34423.13942151441;2831.7168283171723;3260.005698262029;3361.5125806757883;3430.279641275335;3436.347094052122;463540;464522;464845;465382;466164;64446.71106577936;64818;64853.35146648568;64964;65045.349546504876;67831.66084169547;67835;67883;67942;67996.6600166996;17227.655446890993;17467.82532174662;17470.563235919202;17494.475165744792;17505.82494175042;13131.409086591126;13176.143550669176;13190.60428187156;13194.010449216346;13200.801987970188;16193.109378984269;16577.41979030728;18261.726074108898;18435.446936591925;18917.43247702571;7278.308560686833;7315.158756743042;7328.9267107329315;7385.113786345673;7443.166769113911;892.5135800988477;972.7616733900239;984.2568860510269;1010.1464262698008;1076.924615276923;28613.57079643807;31315.84342078275;31533.246401575114;31946.520802187984;32181.839090804864;1037.1495373793568;1037.4397825174321;1084.5661735305782;1130.9830352544718;1139.3448766959032;13175.2753598553;15608.3756649735;16077.437289694804;16085.758713619305;16261.430849920196;664402;670611;670876;670895;672941;54557.1816422754;60292.397076028676;61818.0727289091;61881.07178392238;62145.37854621396;1354.7967804829354;1375.6835927736677;1404.39610967283;1407.268220525321;1409.3305679802013;31862.840685796422;32931.34137317193;33040.1739956503;33044;33958.66041339555;56771.71614141903;57260;57275.140872886106;57376.71311643415;57429.42570574241;590292;594531;594920;595875;597600;22455.43861403478;28510.714892850807;43117.784411077744;43484;43622.909427264574;40729.59270407258;49852;50384;51408;51528.74235628798;41208.38187427251;41359.586404136775;41632.583674162874;42847.57152428436;42974.92562685833;39946.60053399545;41659;41907.53323633658;42417.36373954331;42680.57319426766;33419.66580334165;38824.41763373495;39145.41281880831;39595.2080958385;40038.39942400924;32616.836915815267;34366.656333436345;34848.82575587207;35747.64252357443;35810.8209458951;25403;25950;26176.083837065613;26277.605835912094;26663.600045999712 23:04:39 INFO - PROCESS | 1668 | |2;jquery.html;343.597990351289;358.34065319811543;378.502269515587;383.4229484625638;407.20594840061887;15747.370105195792;16293.511194664165;20358.287459938903;20638.968051597396;20819.895900520496;17535.473935781916;19728;20549.486262843435;22160;22178.556428871452;5253.6322457428;5353.250544923714;5415.079436495786;5534.529564986981;5535.25274087997;3889;5220.738963051851;5308.46915308468;5317.2821669074565;5320.600954928376;5803.332616749065;6071.271447426293;6103.694815259241;6132.2028136342215;6157.661328626924;5849.795257166022;6058.212432383762;6104.4505994460715;6129.049997250433;6142.723577439026;5925.20009798676;5925.762969481216;5926.822195334147;5928.436798504178;5931.614445061057;13565.728685426237;13620;13727;13895.513657022057;13951.023428360018;2239.8992045357836;2241.9215327463626;2450.375154335633;2473.8515689058536;2854.7145285471493;10144.746381340452;10246.53890574926;10337.689869303933;10367.688969330931;14981.475648352256;1796.7035439152523;1830.4966134313038;1843.9354622588146;1884.4723477426342;1909.6849019911697;123917;124376.37811810973;130721;134757.30485390336;137800.31099844436;10897.83653245202;11407.885921140682;11665.24175928562;11735.589254376056;11872.703182420335;14729.484468043567;14785.630359241106;14796.926015369854;14802.777958330633;14806.62983425423;7133.7146514139895;8408;8698.260647845003;8790.472571645721;8822.558872056372;6813.693383797741;8603.315635496783;8603.784905377415;8613.526256055851;8849.380543362031;3893.688504919656;4515.300128480025;4630.6758526902795;4643.744594047293;4674.135284972291;1882.1812511557516;1919.376202734117;1931.4978105692621;1937.167018182195;1937.2251099560008;709.1951679240835;767.631536862298;881.1937077574077;889.8042430665244;953.8378475659155;18677;21368.679469807965;21706.372022098407;21897.343079707633;22108;893.6872094766779;947.1381043250609;955.6846240740539;966.7244835221928;977.2328721953232;10798.244122911477;11429.714257143638;11604.88395116055;11605.477753500943;11628.360440175702;507683;610097;615684;624977;625841;126292;126766;128482.35758821147;129455;136655;979.5543027922279;987.4420952162149;1028.4189432970347;1030.443560477334;1032.2361452525076;15305.617359565655;15310.846891530942;18676.533086672942;19025.90487047556;19426.708599370737;50725.23912141247;52927.73536132295;54183;54530.72734636301;54588.454115458335;75487;76513;76920.2307976913;77373.6787896364;80740.59629701814;6033.849153771191;8062.234087761653;8295.668173273243;8302.377321700777;8580.184882436159;11695.239809412362;12822.294773787531;13068.8039679403;13434.126781759154;13439.529616463376;9756.07317304855;10125.341852779546;10144.385799654146;11155.27490713101;11566.710832229262;10900.182486313717;11096.278741881752;11128.998390144925;11514.481848316678;11571.537138514703;11456.713582160513;11484.540618375171;11796.4691588877;11809;11924.284542927453;7052.506324557232;7368.189499155196;7505.024346834876;7788.610569471391;8537.274331681629;9959.502024898582;11340.829887551858;11681.415929203336;11751.941240293743;11838.644840654793 23:04:39 INFO - PROCESS | 1668 | |3;mootools.html;1506.9246537673116;1547.3887814313346;1730.126286225456;1857.4706208730515;1888.8394486468649;8319.542425166614;8867.423617464876;8881.067487913773;8886.51124188171;8889.733308000763;7742.07095148583;7980.52360313341;7988.680452781882;8086;8665.306775457964;2129.818965387944;2167.804897559219;2277.1346888182466;2352.3648614873973;2357.457784709518;2353.2704861492907;2353.8823058847074;2357.54027964547;2365.5268946210826;2370.668106465096;2303.9308820735323;2336.6495025746103;2352.741198468167;2353.6587194856734;2361.25620429565;2314.0859360552486;2323.94190145246;2337.1002164166835;2343.800776933963;2350.15394457995;2301.7813307735737;2328.348062542474;2329.77867102625;2332.043862016582;2332.6120958029946;8732.170443807829;8781.56092195394;8839.77900552485;8841.911580884173;8865.822683546294;5148.61385396093;5309.177077552986;5317.12267475866;5317.893642127175;5318.468153184689;3417.1457135716073;3561.465780132975;3622.6920711739535;3636.5636123665063;3647.6534729200685;1053.0890779475699;1085.365061439059;1090.1170052257617;1108.9500972456258;1109.5617231193633;11434.714132146679;11529.769404611945;11539.942300288445;11553.249038812535;11583.18917675763;4414.84548040817;4649.209634362099;4746.857594272177;4760.309755085494;4767.809287628525;9889.159421449114;9889.406635601887;9892.752681183028;9898.604055837686;9908.950455247677;5279.155335146358;5281.0758117329315;5284.656497327662;5287.894242115136;5288.153895376721;3590.622984586581;4633.166030114612;4844.2007068833955;4844.539768721966;4845.248986407112;3508.9122771930906;3611.909702257412;3698.8890333290046;3724.7578907370935;3823.636754508317;1115.2917897135214;1124.1681155944543;1124.6176300057855;1130.7568872692411;1137.6871360375962;839.7858546070713;850.171083193881;856.8971723393232;860.1398601398602;968.6367612145445;5732.484076433057;5792.21805056312;5852.034414321674;5995.280566332068;6142.508599312045;859.3383095016802;911.7036962987056;960.2174228004154;975.8145952269047;1015.0914931136591;3868.9553820468877;3964.4053391990856;3964.7819369934355;4059.2084543513733;4122.896927576835;9769.853452198222;10629.30909490896;10751.892481075089;10755.139588833032;10756.247062705692;10018.849717254247;10680.98530639588;10682.94658526718;10702.304350217211;10768.3538987661;6595.571287866273;8141.348692104618;8146.185381461806;8147.266745992889;8149.388795840336;5835.649861008353;5919.230500034968;6072.908906366496;6096.664683442454;6137.693115344304;9501.144896959308;9869.950650246703;9878.012198780065;9892.752681183028;9902.207823374258;8701.433741926514;10186.898131018595;10198.235132365251;10215.336003159771;10221.131203847459;7450.925490745023;7580;7602.657880395506;7608.885866712115;7615.314621684075;7281.05346304977;7875.291223789886;7972;8075.475094118863;8172.550509721785;7317.963410182915;7329.596872171868;7365.558066516026;7446.367058800062;7469.178390376946;7203.71185152588;7261.927380726336;7273.054502914587;7280.381167600814;7292.453066019963;6881.41507971828;6882.277360877236;6966.5471744336455;6972.755953541602;6974.302569742985;4853.320535125014;4885.509919474594;4963.379577552806;4992.301077849032;5108.10608143566;4939.061578300111;4970.900581988268;4981.103401387785;5061.063703214917;5156.767945442389 23:04:39 INFO - PROCESS | 1668 | |4;prototype.html;300.8601000534752;301.13574042498027;314.7277604871786;319.041280950743;322.1527382982755;4177.558742233924;4358.8910277243085;5210.062188806013;5274.022241659378;5308.708021058845;4777.59390451812;5069.898602027957;5224.45143259958;5233;5280.973595132029;3658.122050707824;4022.8735953932946;4027.335489644205;4035.244668569167;4072.5927407259333;3919.804009799513;4032.9514326275084;4091.8772436826944;4094.34490481523;4094.3653733671326;3985.880423587297;3998.920021599566;4012.5523430707244;4038.5961403859524;4056.9188616227657;3172.5241213817994;3954.9011274718073;4014.2774300625874;4045.4943132108488;4062.0657248832936;3485.599156097056;3508.5438892944007;3508.701760350373;3518.7185025197923;3563;4695.779097434657;4938.604911607063;5076.670016448919;5111.488851114858;5216.808940245068;3488;4540.977295113504;4578.779292043357;4584.518625544337;4644.907101857944;5422.701751403672;5434.347878254595;5451.48210919962;5453.918191227135;5531.2809334786625;3682.920889268824;3820.465134881119;3841.5582208046153;3870.4000879863383;3872.399778034381;4753.500882407332;5816.592838501264;5820.825375238707;5850.444207800295;5948.226730525004;3928.980355098206;3956.5252169739815;3965.643092121723;3970.2456533258587;3992.7604343739467;3337.6495467975997;3929.842806287774;3974.344233201547;4022.1955608878343;4037.8788636340955;3545.982270088633;4326.134773045404;4507.391502147234;4563.748993805306;4604.217283061887;5449.5640348772;5459.69971651563;5467.371252305956;5473.576870013825;5548.667079975215;1152.567787079845;1280.2958372895057;1309.2733532889338;1332.7734285171543;1354.3296068446182;816.5386556595623;904.4754042655244;938.8122375524922;938.8920274168559;946.195733626412;4352.368906508538;5063.367079115111;5079.526937188248;5102.719350435762;5165.889333793252;4452.599266066005;5607.383187849333;5618.8314350568735;5630.719558578782;5691.516221121168;4301.612605004253;4775.785089670973;5008.609163702376;5059.2916991621205;5120.948790512047;2161.9243326483497;2166.696662467256;2183.7334346079238;2458.508298340339;2551.34940590152;4147.387146097999;4450.887278180455;4570.565796249351;4983.50494851543;4985.700857948535;4028.7784171870244;4500.639948804088;4532.410786597721;4551.408316918845;4744.074905393484;4260.616544510947;4438.648820140773;4691.765411729468;4847.80608775652;5008.4240312364545;3993.7803420812133;4632.837850675345;4662.111844702901;4747.575727281871;4852.587530059985;4094.3653733671326;4183.39340795589;4252.022034931922;4314.395984562101;4676.579107880307;4490.461144662661;4892.339534162985;4932.101141060646;4944;4971.569616964283;3128.540696484649;3318.469044952796;3384.881529146468;3388.5425467561563;3442.3631628148873;4215.451991241119;4625.861224163281;4800.951990480051;4829.5653391194965;5077.695338279715;5058.6206034548295;5311.654742441729;5342.305500284938;5351.812686556108;5470.647621522624;4583.335416364675;4696.835610753607;4833.782479788348;5043.773030213576;5379.381371142369;5399.487048730364;5419.701916394479;5420.457954204706;5425.294711687456;5443.346798384219;4330.718503297276;5523.8066667666435;5609.4390560943575;5688.886222275614;5709.685967271674;5514.779408823602;5532.2254884315425;5577.330720313588;5603.5797315202335;5723.627964182315 23:04:39 INFO - PROCESS | 1668 | |5;yui.html;719.3849258883654;722.3583810440457;770.1605250277197;820.7291593774055;823.1891586787016;15058.17180055098;16395.4261600844;16410.343586256535;16418.91790541047;16423;14764.778528322056;14871.033382830108;14886.851131488682;14894.10635361879;14908.7018259635;687.5977553131424;711.7757906259511;851.0935853316214;857.1685465098844;882.4572887674067;863.8056437301589;883.5361435246515;884.2881480408269;885.1856292211181;888.5468411110348;949.178960199426;950.235060776075;951.7335146159052;953.6280850468323;954.0554850697794;741.6254791330398;765.938724902012;769.465221670939;946.3233787841692;947.0009140356921;569.4277251362396;667.1993607670814;683.6274230544366;685.6880119545594;685.9108315918899;14789.852101478955;14806.111633302036;14830.258487075604;14846.480373186887;14855;13959.511417100453;13999.370028348749;14274.3576539056;14322.928385358111;14348.282585870666;13097.083204175711;13284;13349.86650133496;13563.45746170152;13685.931570342183;4481.798319075617;4598;4619.16854966106;4673.228917228653;4701.341812146268;125158.37420812837;126753;127123.36438317841;127232.36383818115;127709;11021.944890275658;11410.42947852604;11561.07511399086;11760.88239117615;11823.994960428288;22618.982145803147;22650.546989060127;22652.320430387113;22659;22676.65985010226;7942.285194332538;8128.512289262663;8199.42604017725;8292.958535207285;8303.4602750821;4264.829406823692;4298.677599180074;4437.423134992495;4464.888377790517;4465.307877279027;3490.89527314181;3661.450782382664;3661.6338366163172;3663.7984910829628;3665.4318580620047;641.592588706175;650.8210242183437;661.0777964739162;664.6178447392768;664.8969409741497;503.8688145114242;551.892380985712;637.3721883944311;647.1069923505627;649.6199723161909;7925.326347260548;8898.644054237888;9087.273018158532;9212.493312867857;9266.629334826532;584.4652143288928;592.2922108080802;624.8968920128219;631.1252246019334;640.6572483721187;4349.934750978738;4360.49854266757;4502.909941801146;4553.726776393427;4591.632669386441;103180;116993;153778;160773.39226607818;166506;106468.9353106459;114864.42567787107;117187;137037;143218;78733.60633197027;102822;104391;108586.45706771573;109524.90475095306;9653;9794.804103918024;9964.302498825013;10053.346532475365;10066.396016239049;59853.700731496065;61129.38870611237;61185;63586;64436;89632;91763;96863;100664;104740;13353.465861365437;13355.933220333836;13409.530666426628;13478.056536042383;13866;13439;14725.55823325302;14771.593166075723;15007.624809379853;15094.773578396553;12168.452419641315;13215.537456189371;14057.929710351384;14069.507567235098;14416.927915360775;12758.035208960055;13297.736715012059;13629.522966696119;13927;14390.784138238145;11570.59502917394;13357.198568085945;13511.932440338129;13646.317684115556;13940.163590184622;11175.582227167835;11189.776204476027;11201.43992800373;11260.549578017115;11365.886341136813;11242.606508772154;11336.716582085513;11400;11609.709757256136;11798.056155507711 23:04:39 INFO - PROCESS | 1668 | __end_tp_report 23:04:39 INFO - PROCESS | 1668 | __start_cc_report 23:04:39 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1389 23:04:39 INFO - PROCESS | 1668 | __end_cc_report 23:04:39 INFO - PROCESS | 1668 | __startTimestamp1470722679041__endTimestamp 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | ------- Summary: start ------- 23:04:39 INFO - PROCESS | 1668 | Number of tests: 6 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5674.38 Median:5897.32 stddev:650.33 (11.0%) stddev-sans-first:615.95 23:04:39 INFO - PROCESS | 1668 | Values: 2940.7 3302.6 3306.4 3312.0 3381.6 5473.5 5557.9 5565.6 5569.3 5603.7 5578.9 5596.8 5604.9 5607.7 5615.4 5110.3 5142.4 5187.2 5255.2 5260.4 5134.3 5204.9 5215.1 5216.0 5218.7 5122.4 5170.7 5172.9 5179.2 5210.9 5203.7 5204.9 5225.7 5245.5 5280.8 4991.0 5059.1 5072.7 5125.6 5132.8 5484.4 5516.6 5542.1 5596.6 5614.6 6147.0 6170.5 6170.9 6172.6 6178.4 6109.7 6120.0 6189.8 6215.3 6221.3 5697.1 5706.5 5715.5 5718.7 5724.2 6219.5 6225.8 6232.1 6235.3 6242.3 5869.5 5980.3 6016.1 6057.8 6095.3 6197.5 6201.9 6209.8 6217.3 6227.6 6087.1 6134.4 6156.0 6165.0 6177.4 6032.9 6099.5 6117.9 6189.4 6223.0 4846.3 4849.8 4869.2 4880.0 4883.0 4402.2 4431.2 4458.3 4463.2 4464.1 5608.5 5610.2 5684.5 5691.6 5754.2 6209.8 6257.8 6258.7 6270.9 6284.4 4582.7 5379.1 5623.8 5651.9 5692.3 5268.3 5308.2 5400.4 5411.4 5412.0 6145.6 6207.1 6214.3 6221.3 6273.7 6217.3 6230.5 6231.8 6232.1 6246.8 6267.6 6287.2 6290.6 6293.6 6328.0 5977.6 6053.7 6081.3 6094.7 6104.3 6226.4 6230.8 6240.5 6243.8 6258.6 4718.5 5958.7 6022.6 6063.8 6072.2 5889.6 5989.0 5989.7 6025.0 6080.7 5897.3 5915.5 5979.9 5984.2 6004.2 6061.7 6081.6 6089.8 6106.9 6108.5 5675.0 5798.7 5876.9 5894.1 5899.2 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:72486.06 Median:39370.31 stddev:155538.37 (395.1%) stddev-sans-first:155880.80 23:04:39 INFO - PROCESS | 1668 | Values: 501.9 531.7 534.0 541.3 551.7 33366.8 34098.3 34170.7 37030.8 42332.8 46023.3 46566.1 46710.3 46723.8 46749.5 8374.7 9739.9 9924.4 9936.3 9951.0 8897.0 9116.5 9842.5 11952.2 12131.1 12076.2 12162.3 12189.8 12205.8 12211.1 11976.0 12107.1 12107.5 12120.8 12132.6 12046.9 12049.2 12071.9 12080.6 12108.9 42692.6 42962.0 43045.0 43152.4 43235.4 3057.5 3318.4 3877.2 4009.9 4411.1 32811.7 34104.1 34146.7 34400.0 34423.1 2831.7 3260.0 3361.5 3430.3 3436.3 463540.0 464522.0 464845.0 465382.0 466164.0 64446.7 64818.0 64853.4 64964.0 65045.3 67831.7 67835.0 67883.0 67942.0 67996.7 17227.7 17467.8 17470.6 17494.5 17505.8 13131.4 13176.1 13190.6 13194.0 13200.8 16193.1 16577.4 18261.7 18435.4 18917.4 7278.3 7315.2 7328.9 7385.1 7443.2 892.5 972.8 984.3 1010.1 1076.9 28613.6 31315.8 31533.2 31946.5 32181.8 1037.1 1037.4 1084.6 1131.0 1139.3 13175.3 15608.4 16077.4 16085.8 16261.4 664402.0 670611.0 670876.0 670895.0 672941.0 54557.2 60292.4 61818.1 61881.1 62145.4 1354.8 1375.7 1404.4 1407.3 1409.3 31862.8 32931.3 33040.2 33044.0 33958.7 56771.7 57260.0 57275.1 57376.7 57429.4 590292.0 594531.0 594920.0 595875.0 597600.0 22455.4 28510.7 43117.8 43484.0 43622.9 40729.6 49852.0 50384.0 51408.0 51528.7 41208.4 41359.6 41632.6 42847.6 42974.9 39946.6 41659.0 41907.5 42417.4 42680.6 33419.7 38824.4 39145.4 39595.2 40038.4 32616.8 34366.7 34848.8 35747.6 35810.8 25403.0 25950.0 26176.1 26277.6 26663.6 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35007.98 Median:12209.23 stddev:100315.29 (821.6%) stddev-sans-first:100562.93 23:04:39 INFO - PROCESS | 1668 | Values: 343.6 358.3 378.5 383.4 407.2 15747.4 16293.5 20358.3 20639.0 20819.9 17535.5 19728.0 20549.5 22160.0 22178.6 5253.6 5353.3 5415.1 5534.5 5535.3 3889.0 5220.7 5308.5 5317.3 5320.6 5803.3 6071.3 6103.7 6132.2 6157.7 5849.8 6058.2 6104.5 6129.0 6142.7 5925.2 5925.8 5926.8 5928.4 5931.6 13565.7 13620.0 13727.0 13895.5 13951.0 2239.9 2241.9 2450.4 2473.9 2854.7 10144.7 10246.5 10337.7 10367.7 14981.5 1796.7 1830.5 1843.9 1884.5 1909.7 123917.0 124376.4 130721.0 134757.3 137800.3 10897.8 11407.9 11665.2 11735.6 11872.7 14729.5 14785.6 14796.9 14802.8 14806.6 7133.7 8408.0 8698.3 8790.5 8822.6 6813.7 8603.3 8603.8 8613.5 8849.4 3893.7 4515.3 4630.7 4643.7 4674.1 1882.2 1919.4 1931.5 1937.2 1937.2 709.2 767.6 881.2 889.8 953.8 18677.0 21368.7 21706.4 21897.3 22108.0 893.7 947.1 955.7 966.7 977.2 10798.2 11429.7 11604.9 11605.5 11628.4 507683.0 610097.0 615684.0 624977.0 625841.0 126292.0 126766.0 128482.4 129455.0 136655.0 979.6 987.4 1028.4 1030.4 1032.2 15305.6 15310.8 18676.5 19025.9 19426.7 50725.2 52927.7 54183.0 54530.7 54588.5 75487.0 76513.0 76920.2 77373.7 80740.6 6033.8 8062.2 8295.7 8302.4 8580.2 11695.2 12822.3 13068.8 13434.1 13439.5 9756.1 10125.3 10144.4 11155.3 11566.7 10900.2 11096.3 11129.0 11514.5 11571.5 11456.7 11484.5 11796.5 11809.0 11924.3 7052.5 7368.2 7505.0 7788.6 8537.3 9959.5 11340.8 11681.4 11751.9 11838.6 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5640.83 Median:5026.68 stddev:3151.53 (62.7%) stddev-sans-first:3145.06 23:04:39 INFO - PROCESS | 1668 | Values: 1506.9 1547.4 1730.1 1857.5 1888.8 8319.5 8867.4 8881.1 8886.5 8889.7 7742.1 7980.5 7988.7 8086.0 8665.3 2129.8 2167.8 2277.1 2352.4 2357.5 2353.3 2353.9 2357.5 2365.5 2370.7 2303.9 2336.6 2352.7 2353.7 2361.3 2314.1 2323.9 2337.1 2343.8 2350.2 2301.8 2328.3 2329.8 2332.0 2332.6 8732.2 8781.6 8839.8 8841.9 8865.8 5148.6 5309.2 5317.1 5317.9 5318.5 3417.1 3561.5 3622.7 3636.6 3647.7 1053.1 1085.4 1090.1 1109.0 1109.6 11434.7 11529.8 11539.9 11553.2 11583.2 4414.8 4649.2 4746.9 4760.3 4767.8 9889.2 9889.4 9892.8 9898.6 9909.0 5279.2 5281.1 5284.7 5287.9 5288.2 3590.6 4633.2 4844.2 4844.5 4845.2 3508.9 3611.9 3698.9 3724.8 3823.6 1115.3 1124.2 1124.6 1130.8 1137.7 839.8 850.2 856.9 860.1 968.6 5732.5 5792.2 5852.0 5995.3 6142.5 859.3 911.7 960.2 975.8 1015.1 3869.0 3964.4 3964.8 4059.2 4122.9 9769.9 10629.3 10751.9 10755.1 10756.2 10018.8 10681.0 10682.9 10702.3 10768.4 6595.6 8141.3 8146.2 8147.3 8149.4 5835.6 5919.2 6072.9 6096.7 6137.7 9501.1 9870.0 9878.0 9892.8 9902.2 8701.4 10186.9 10198.2 10215.3 10221.1 7450.9 7580.0 7602.7 7608.9 7615.3 7281.1 7875.3 7972.0 8075.5 8172.6 7318.0 7329.6 7365.6 7446.4 7469.2 7203.7 7261.9 7273.1 7280.4 7292.5 6881.4 6882.3 6966.5 6972.8 6974.3 4853.3 4885.5 4963.4 4992.3 5108.1 4939.1 4970.9 4981.1 5061.1 5156.8 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4261.54 Median:4594.37 stddev:1290.04 (28.1%) stddev-sans-first:1258.94 23:04:39 INFO - PROCESS | 1668 | Values: 300.9 301.1 314.7 319.0 322.2 4177.6 4358.9 5210.1 5274.0 5308.7 4777.6 5069.9 5224.5 5233.0 5281.0 3658.1 4022.9 4027.3 4035.2 4072.6 3919.8 4033.0 4091.9 4094.3 4094.4 3985.9 3998.9 4012.6 4038.6 4056.9 3172.5 3954.9 4014.3 4045.5 4062.1 3485.6 3508.5 3508.7 3518.7 3563.0 4695.8 4938.6 5076.7 5111.5 5216.8 3488.0 4541.0 4578.8 4584.5 4644.9 5422.7 5434.3 5451.5 5453.9 5531.3 3682.9 3820.5 3841.6 3870.4 3872.4 4753.5 5816.6 5820.8 5850.4 5948.2 3929.0 3956.5 3965.6 3970.2 3992.8 3337.6 3929.8 3974.3 4022.2 4037.9 3546.0 4326.1 4507.4 4563.7 4604.2 5449.6 5459.7 5467.4 5473.6 5548.7 1152.6 1280.3 1309.3 1332.8 1354.3 816.5 904.5 938.8 938.9 946.2 4352.4 5063.4 5079.5 5102.7 5165.9 4452.6 5607.4 5618.8 5630.7 5691.5 4301.6 4775.8 5008.6 5059.3 5120.9 2161.9 2166.7 2183.7 2458.5 2551.3 4147.4 4450.9 4570.6 4983.5 4985.7 4028.8 4500.6 4532.4 4551.4 4744.1 4260.6 4438.6 4691.8 4847.8 5008.4 3993.8 4632.8 4662.1 4747.6 4852.6 4094.4 4183.4 4252.0 4314.4 4676.6 4490.5 4892.3 4932.1 4944.0 4971.6 3128.5 3318.5 3384.9 3388.5 3442.4 4215.5 4625.9 4801.0 4829.6 5077.7 5058.6 5311.7 5342.3 5351.8 5470.6 4583.3 4696.8 4833.8 5043.8 5379.4 5399.5 5419.7 5420.5 5425.3 5443.3 4330.7 5523.8 5609.4 5688.9 5709.7 5514.8 5532.2 5577.3 5603.6 5723.6 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:24999.58 Median:22655.66 stddev:39614.46 (174.9%) stddev-sans-first:39683.64 23:04:39 INFO - PROCESS | 1668 | Values: 719.4 722.4 770.2 820.7 823.2 15058.2 16395.4 16410.3 16418.9 16423.0 14764.8 14871.0 14886.9 14894.1 14908.7 687.6 711.8 851.1 857.2 882.5 863.8 883.5 884.3 885.2 888.5 949.2 950.2 951.7 953.6 954.1 741.6 765.9 769.5 946.3 947.0 569.4 667.2 683.6 685.7 685.9 14789.9 14806.1 14830.3 14846.5 14855.0 13959.5 13999.4 14274.4 14322.9 14348.3 13097.1 13284.0 13349.9 13563.5 13685.9 4481.8 4598.0 4619.2 4673.2 4701.3 125158.4 126753.0 127123.4 127232.4 127709.0 11021.9 11410.4 11561.1 11760.9 11824.0 22619.0 22650.5 22652.3 22659.0 22676.7 7942.3 8128.5 8199.4 8293.0 8303.5 4264.8 4298.7 4437.4 4464.9 4465.3 3490.9 3661.5 3661.6 3663.8 3665.4 641.6 650.8 661.1 664.6 664.9 503.9 551.9 637.4 647.1 649.6 7925.3 8898.6 9087.3 9212.5 9266.6 584.5 592.3 624.9 631.1 640.7 4349.9 4360.5 4502.9 4553.7 4591.6 103180.0 116993.0 153778.0 160773.4 166506.0 106468.9 114864.4 117187.0 137037.0 143218.0 78733.6 102822.0 104391.0 108586.5 109524.9 9653.0 9794.8 9964.3 10053.3 10066.4 59853.7 61129.4 61185.0 63586.0 64436.0 89632.0 91763.0 96863.0 100664.0 104740.0 13353.5 13355.9 13409.5 13478.1 13866.0 13439.0 14725.6 14771.6 15007.6 15094.8 12168.5 13215.5 14057.9 14069.5 14416.9 12758.0 13297.7 13629.5 13927.0 14390.8 11570.6 13357.2 13511.9 13646.3 13940.2 11175.6 11189.8 11201.4 11260.5 11365.9 11242.6 11336.7 11400.0 11609.7 11798.1 23:04:39 INFO - PROCESS | 1668 | -------- Summary: end -------- 23:04:39 INFO - PROCESS | 1668 | 23:04:39 INFO - TEST-INFO | 1668: exit 0 23:04:39 INFO - TEST-OK | dromaeo_css | took 1119555ms 23:04:39 INFO - TEST-START | kraken 23:04:39 INFO - operating with platform_type : mac_ 23:04:39 INFO - Initialising browser for kraken test... 23:04:39 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmpoHQX9h/profile http://localhost:49271/getInfo.html) 23:04:42 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 23:04:42 INFO - PROCESS | 1676 | colorDepth:24 23:04:42 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 23:04:42 INFO - PROCESS | 1676 | __metrics 23:04:42 INFO - PROCESS | 1676 | 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" 23:04:42 INFO - PROCESS | 1676 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:04:42 INFO - TEST-INFO | 1676: exit 0 23:04:42 INFO - Browser initialized. 23:04:42 INFO - Running cycle 1/1 for kraken test... 23:04:42 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/tmpoHQX9h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:04:54 INFO - PROCESS | 1677 | RSS: Main: 244101120 23:04:54 INFO - PROCESS | 1677 | 23:04:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 23:04:58 INFO - PROCESS | 1677 | RSS: Main: 294842368 23:04:58 INFO - PROCESS | 1677 | 23:05:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 23:05:01 INFO - PROCESS | 1677 | RSS: Main: 302415872 23:05:01 INFO - PROCESS | 1677 | 23:05:05 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 23:05:05 INFO - PROCESS | 1677 | RSS: Main: 398856192 23:05:05 INFO - PROCESS | 1677 | 23:05:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 23:05:08 INFO - PROCESS | 1677 | RSS: Main: 300937216 23:05:08 INFO - PROCESS | 1677 | 23:05:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:11 INFO - PROCESS | 1677 | RSS: Main: 305164288 23:05:11 INFO - PROCESS | 1677 | 23:05:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:14 INFO - PROCESS | 1677 | RSS: Main: 342884352 23:05:14 INFO - PROCESS | 1677 | 23:05:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:17 INFO - PROCESS | 1677 | RSS: Main: 342999040 23:05:17 INFO - PROCESS | 1677 | 23:05:20 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 23:05:21 INFO - PROCESS | 1677 | RSS: Main: 341729280 23:05:21 INFO - PROCESS | 1677 | 23:05:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:23 INFO - PROCESS | 1677 | RSS: Main: 313339904 23:05:23 INFO - PROCESS | 1677 | 23:05:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:26 INFO - PROCESS | 1677 | RSS: Main: 320684032 23:05:26 INFO - PROCESS | 1677 | 23:05:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:29 INFO - PROCESS | 1677 | RSS: Main: 323817472 23:05:29 INFO - PROCESS | 1677 | 23:05:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:05:32 INFO - PROCESS | 1677 | RSS: Main: 332562432 23:05:32 INFO - PROCESS | 1677 | 23:05:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:05:36 INFO - PROCESS | 1677 | RSS: Main: 334807040 23:05:36 INFO - PROCESS | 1677 | 23:05:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 23:05:38 INFO - PROCESS | 1677 | RSS: Main: 377495552 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | __start_tp_report 23:05:38 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 23:05:38 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 23:05:38 INFO - PROCESS | 1677 | |i|pagename|runs| 23:05:38 INFO - PROCESS | 1677 | |0;ai-astar;101;112;112;111;109;106;110;116;158;129 23:05:38 INFO - PROCESS | 1677 | |1;audio-beat-detection;145;106;109;168;110;106;131;106;142;107 23:05:38 INFO - PROCESS | 1677 | |2;audio-dft;185;181;182;182;182;183;185;180;183;183 23:05:38 INFO - PROCESS | 1677 | |3;audio-fft;76;58;62;88;58;56;59;66;82;60 23:05:38 INFO - PROCESS | 1677 | |4;audio-oscillator;85;69;101;70;78;70;97;69;68;121 23:05:38 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;87;87;86;88;83;87;84;86;84 23:05:38 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;92;93;93;93;92;93;105;149;122 23:05:38 INFO - PROCESS | 1677 | |7;imaging-desaturate;85;87;85;86;84;84;90;86;84;84 23:05:38 INFO - PROCESS | 1677 | |8;json-parse-financial;55;55;53;55;53;51;58;80;80;80 23:05:38 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;68;75;71;76;74;69;71;72;71;71 23:05:38 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;82;83;83;82;85;82;85;85;86;82 23:05:38 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;138;139;140;136;136;137;133;160;228;151 23:05:38 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;163;165;165;166;164;164;163;164;163;166 23:05:38 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;61;66;85;65;66;65;67;66;66;67 23:05:38 INFO - PROCESS | 1677 | __end_tp_report 23:05:38 INFO - PROCESS | 1677 | __start_cc_report 23:05:38 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2053 23:05:38 INFO - PROCESS | 1677 | __end_cc_report 23:05:38 INFO - PROCESS | 1677 | __startTimestamp1470722738173__endTimestamp 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | ------- Summary: start ------- 23:05:38 INFO - PROCESS | 1677 | Number of tests: 14 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:116.40 Median:112.00 stddev:16.32 (14.6%) stddev-sans-first:16.34 23:05:38 INFO - PROCESS | 1677 | Values: 101.0 112.0 112.0 111.0 109.0 106.0 110.0 116.0 158.0 129.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:120.50 stddev:22.17 (18.4%) stddev-sans-first:22.03 23:05:38 INFO - PROCESS | 1677 | Values: 145.0 106.0 109.0 168.0 110.0 106.0 131.0 106.0 142.0 107.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.58 (0.9%) stddev-sans-first:1.41 23:05:38 INFO - PROCESS | 1677 | Values: 185.0 181.0 182.0 182.0 182.0 183.0 185.0 180.0 183.0 183.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:66.50 Median:64.00 stddev:11.38 (17.8%) stddev-sans-first:11.54 23:05:38 INFO - PROCESS | 1677 | Values: 76.0 58.0 62.0 88.0 58.0 56.0 59.0 66.0 82.0 60.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:70.00 stddev:18.10 (25.9%) stddev-sans-first:19.18 23:05:38 INFO - PROCESS | 1677 | Values: 85.0 69.0 101.0 70.0 78.0 70.0 97.0 69.0 68.0 121.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 23:05:38 INFO - PROCESS | 1677 | Values: 88.0 87.0 87.0 86.0 88.0 83.0 87.0 84.0 86.0 84.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:93.00 stddev:18.92 (20.3%) stddev-sans-first:19.75 23:05:38 INFO - PROCESS | 1677 | Values: 93.0 92.0 93.0 93.0 93.0 92.0 93.0 105.0 149.0 122.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.50 stddev:1.90 (2.2%) stddev-sans-first:2.01 23:05:38 INFO - PROCESS | 1677 | Values: 85.0 87.0 85.0 86.0 84.0 84.0 90.0 86.0 84.0 84.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:56.50 stddev:12.55 (22.2%) stddev-sans-first:13.06 23:05:38 INFO - PROCESS | 1677 | Values: 55.0 55.0 53.0 55.0 53.0 51.0 58.0 80.0 80.0 80.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:2.53 (3.5%) stddev-sans-first:2.28 23:05:38 INFO - PROCESS | 1677 | Values: 68.0 75.0 71.0 76.0 74.0 69.0 71.0 72.0 71.0 71.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.58 (1.9%) stddev-sans-first:1.58 23:05:38 INFO - PROCESS | 1677 | Values: 82.0 83.0 83.0 82.0 85.0 82.0 85.0 85.0 86.0 82.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:139.50 stddev:28.67 (20.6%) stddev-sans-first:30.09 23:05:38 INFO - PROCESS | 1677 | Values: 138.0 139.0 140.0 136.0 136.0 137.0 133.0 160.0 228.0 151.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:164.50 stddev:1.16 (0.7%) stddev-sans-first:1.13 23:05:38 INFO - PROCESS | 1677 | Values: 163.0 165.0 165.0 166.0 164.0 164.0 163.0 164.0 163.0 166.0 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:66.00 stddev:6.42 (9.7%) stddev-sans-first:6.37 23:05:38 INFO - PROCESS | 1677 | Values: 61.0 66.0 85.0 65.0 66.0 65.0 67.0 66.0 66.0 67.0 23:05:38 INFO - PROCESS | 1677 | -------- Summary: end -------- 23:05:38 INFO - PROCESS | 1677 | 23:05:38 INFO - TEST-INFO | 1677: exit 0 23:05:39 INFO - TEST-OK | kraken | took 59360ms 23:05:39 INFO - SUITE-END | took 1178s 23:05:39 INFO - Completed test suite (00:19:39) 23:05:39 INFO - javascript benchmark 23:05:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2940.735333819956, 3302.5541551890487, 3306.4048471275178, 3311.983440082799, 3381.56039714837, 5473.452654734525, 5557.8888422231585, 5565.6104072714825, 5569.3316801983765, 5603.663780173188, 5578.86052848679, 5596.776128954847, 5604.915926261099, 5607.719614019293, 5615.354234263061, 5110.310108135392, 5142.357205349332, 5187.221916712486, 5255.211718242256, 5260.368755749316, 5134.25553294772, 5204.921926171091, 5215.087359712054, 5216.0, 5218.739063046852, 5122.359705036871, 5170.715610641413, 5172.948270517303, 5179.249008893708, 5210.921836172461, 5203.713795741214, 5204.921926171072, 5225.712585807779, 5245.4754524547625, 5280.762365693553, 4991.026749783794, 5059.114654935372, 5072.742245402156, 5125.615578831603, 5132.8460146195675, 5484.396716361197, 5516.558675306006, 5542.11326187808, 5596.636218645775, 5614.578906581982, 6147.016477363645, 6170.475509581691, 6170.938290617081, 6172.59878107926, 6178.41305076017, 6109.663968481732, 6119.969400153015, 6189.845253868644, 6215.2541694996435, 6221.253449586078, 5697.059985102412, 5706.543476521868, 5715.485606295453, 5718.7426565804635, 5724.169995350734, 6219.533534984895, 6225.844353891189, 6232.0963460298, 6235.314115447297, 6242.344553821874, 5869.530437564985, 5980.252468441445, 6016.127661489147, 6057.804617411022, 6095.2685677718955, 6197.5351848611535, 6201.906971395432, 6209.813705588839, 6217.285012223561, 6227.595206311576, 6087.117367981707, 6134.355892631299, 6156.0, 6165.0, 6177.413145751147, 6032.909506357408, 6099.451049405575, 6117.877642447126, 6189.412005859436, 6223.0, 4846.321514987904, 4849.75751212438, 4869.196582563908, 4880.048390563806, 4883.02339532095, 4402.163588918095, 4431.158079964796, 4458.286674132123, 4463.152001119777, 4464.107178564235, 5608.495235428831, 5610.242617246619, 5684.459976302244, 5691.601587888808, 5754.223179870748, 6209.844753881099, 6257.843553911189, 6258.749650013949, 6270.905936410957, 6284.37156284377, 4582.725036497821, 5379.058664733656, 5623.847419462329, 5651.886962260732, 5692.288463942007, 5268.315119034501, 5308.203769434615, 5400.351957765093, 5411.404745477994, 5412.0, 6145.617236121926, 6207.131001659862, 6214.2542894852, 6221.253449586078, 6273.686315684106, 6217.253929528486, 6230.470410015199, 6231.781887633911, 6232.065190221503, 6246.843828904313, 6267.62394256346, 6287.182666253358, 6290.559660823698, 6293.5909165905905, 6327.96836015817, 5977.641341519523, 6053.667048312385, 6081.331053584191, 6094.695265236632, 6104.277264894319, 6226.377362263737, 6230.7819226328675, 6240.469560087444, 6243.8126856194385, 6258.624482531062, 4718.480967093548, 5958.731857066356, 6022.608530445507, 6063.848403789941, 6072.180255665606, 5889.587728858939, 5989.0, 5989.700514974321, 6024.969875150597, 6080.726367313393, 5897.321807992136, 5915.497182739516, 5979.923613749567, 5984.222051133499, 6004.189434426296, 6061.696915154313, 6081.5742897996715, 6089.756409743738, 6106.877862442815, 6108.541859360477, 5675.0, 5798.652080875161, 5876.941230587639, 5894.086416605433, 5899.233099697012], "value": 5631.678862835032, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.85195367366623, 531.704903778403, 533.981310654127, 541.3017207801938, 551.6882961126963, 33366.8331658342, 34098.31803363934, 34170.65829341706, 37030.814845925735, 42332.78833605835, 46023.30965035527, 46566.068678626405, 46710.29934550985, 46723.76638116805, 46749.53250467494, 8374.748757537283, 9739.853902191473, 9924.354916930377, 9936.304458687895, 9951.0, 8896.955515222415, 9116.544172791368, 9842.507874606277, 11952.223105498115, 12131.090168237373, 12076.21504602198, 12162.331071791048, 12189.817152742717, 12205.816912746315, 12211.08416868734, 11976.04191664669, 12107.091968102428, 12107.455164517618, 12120.757584848297, 12132.636020919299, 12046.939765301206, 12049.216800907912, 12071.879281207252, 12080.577179798665, 12108.939455302756, 42692.573074269174, 42962.0, 43045.0, 43152.352714709305, 43235.35146972798, 3057.541368794676, 3318.4026875162463, 3877.243937432202, 4009.8997525062105, 4411.139827733593, 32811.67188328134, 34104.14739631529, 34146.65853341459, 34400.0, 34423.13942151441, 2831.7168283171723, 3260.005698262029, 3361.5125806757883, 3430.279641275335, 3436.347094052122, 463540.0, 464522.0, 464845.0, 465382.0, 466164.0, 64446.71106577936, 64818.0, 64853.35146648568, 64964.0, 65045.349546504876, 67831.66084169547, 67835.0, 67883.0, 67942.0, 67996.6600166996, 17227.655446890993, 17467.82532174662, 17470.563235919202, 17494.475165744792, 17505.82494175042, 13131.409086591126, 13176.143550669176, 13190.60428187156, 13194.010449216346, 13200.801987970188, 16193.109378984269, 16577.41979030728, 18261.726074108898, 18435.446936591925, 18917.43247702571, 7278.308560686833, 7315.158756743042, 7328.9267107329315, 7385.113786345673, 7443.166769113911, 892.5135800988477, 972.7616733900239, 984.2568860510269, 1010.1464262698008, 1076.924615276923, 28613.57079643807, 31315.84342078275, 31533.246401575114, 31946.520802187984, 32181.839090804864, 1037.1495373793568, 1037.4397825174321, 1084.5661735305782, 1130.9830352544718, 1139.3448766959032, 13175.2753598553, 15608.3756649735, 16077.437289694804, 16085.758713619305, 16261.430849920196, 664402.0, 670611.0, 670876.0, 670895.0, 672941.0, 54557.1816422754, 60292.397076028676, 61818.0727289091, 61881.07178392238, 62145.37854621396, 1354.7967804829354, 1375.6835927736677, 1404.39610967283, 1407.268220525321, 1409.3305679802013, 31862.840685796422, 32931.34137317193, 33040.1739956503, 33044.0, 33958.66041339555, 56771.71614141903, 57260.0, 57275.140872886106, 57376.71311643415, 57429.42570574241, 590292.0, 594531.0, 594920.0, 595875.0, 597600.0, 22455.43861403478, 28510.714892850807, 43117.784411077744, 43484.0, 43622.909427264574, 40729.59270407258, 49852.0, 50384.0, 51408.0, 51528.74235628798, 41208.38187427251, 41359.586404136775, 41632.583674162874, 42847.57152428436, 42974.92562685833, 39946.60053399545, 41659.0, 41907.53323633658, 42417.36373954331, 42680.57319426766, 33419.66580334165, 38824.41763373495, 39145.41281880831, 39595.2080958385, 40038.39942400924, 32616.836915815267, 34366.656333436345, 34848.82575587207, 35747.64252357443, 35810.8209458951, 25403.0, 25950.0, 26176.083837065613, 26277.605835912094, 26663.600045999712], "value": 21241.867120427945, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.597990351289, 358.34065319811543, 378.502269515587, 383.4229484625638, 407.20594840061887, 15747.370105195792, 16293.511194664165, 20358.287459938903, 20638.968051597396, 20819.895900520496, 17535.473935781916, 19728.0, 20549.486262843435, 22160.0, 22178.556428871452, 5253.6322457428, 5353.250544923714, 5415.079436495786, 5534.529564986981, 5535.25274087997, 3889.0, 5220.738963051851, 5308.46915308468, 5317.2821669074565, 5320.600954928376, 5803.332616749065, 6071.271447426293, 6103.694815259241, 6132.2028136342215, 6157.661328626924, 5849.795257166022, 6058.212432383762, 6104.4505994460715, 6129.049997250433, 6142.723577439026, 5925.20009798676, 5925.762969481216, 5926.822195334147, 5928.436798504178, 5931.614445061057, 13565.728685426237, 13620.0, 13727.0, 13895.513657022057, 13951.023428360018, 2239.8992045357836, 2241.9215327463626, 2450.375154335633, 2473.8515689058536, 2854.7145285471493, 10144.746381340452, 10246.53890574926, 10337.689869303933, 10367.688969330931, 14981.475648352256, 1796.7035439152523, 1830.4966134313038, 1843.9354622588146, 1884.4723477426342, 1909.6849019911697, 123917.0, 124376.37811810973, 130721.0, 134757.30485390336, 137800.31099844436, 10897.83653245202, 11407.885921140682, 11665.24175928562, 11735.589254376056, 11872.703182420335, 14729.484468043567, 14785.630359241106, 14796.926015369854, 14802.777958330633, 14806.62983425423, 7133.7146514139895, 8408.0, 8698.260647845003, 8790.472571645721, 8822.558872056372, 6813.693383797741, 8603.315635496783, 8603.784905377415, 8613.526256055851, 8849.380543362031, 3893.688504919656, 4515.300128480025, 4630.6758526902795, 4643.744594047293, 4674.135284972291, 1882.1812511557516, 1919.376202734117, 1931.4978105692621, 1937.167018182195, 1937.2251099560008, 709.1951679240835, 767.631536862298, 881.1937077574077, 889.8042430665244, 953.8378475659155, 18677.0, 21368.679469807965, 21706.372022098407, 21897.343079707633, 22108.0, 893.6872094766779, 947.1381043250609, 955.6846240740539, 966.7244835221928, 977.2328721953232, 10798.244122911477, 11429.714257143638, 11604.88395116055, 11605.477753500943, 11628.360440175702, 507683.0, 610097.0, 615684.0, 624977.0, 625841.0, 126292.0, 126766.0, 128482.35758821147, 129455.0, 136655.0, 979.5543027922279, 987.4420952162149, 1028.4189432970347, 1030.443560477334, 1032.2361452525076, 15305.617359565655, 15310.846891530942, 18676.533086672942, 19025.90487047556, 19426.708599370737, 50725.23912141247, 52927.73536132295, 54183.0, 54530.72734636301, 54588.454115458335, 75487.0, 76513.0, 76920.2307976913, 77373.6787896364, 80740.59629701814, 6033.849153771191, 8062.234087761653, 8295.668173273243, 8302.377321700777, 8580.184882436159, 11695.239809412362, 12822.294773787531, 13068.8039679403, 13434.126781759154, 13439.529616463376, 9756.07317304855, 10125.341852779546, 10144.385799654146, 11155.27490713101, 11566.710832229262, 10900.182486313717, 11096.278741881752, 11128.998390144925, 11514.481848316678, 11571.537138514703, 11456.713582160513, 11484.540618375171, 11796.4691588877, 11809.0, 11924.284542927453, 7052.506324557232, 7368.189499155196, 7505.024346834876, 7788.610569471391, 8537.274331681629, 9959.502024898582, 11340.829887551858, 11681.415929203336, 11751.941240293743, 11838.644840654793], "value": 9392.82759276534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1506.9246537673116, 1547.3887814313346, 1730.126286225456, 1857.4706208730515, 1888.8394486468649, 8319.542425166614, 8867.423617464876, 8881.067487913773, 8886.51124188171, 8889.733308000763, 7742.07095148583, 7980.52360313341, 7988.680452781882, 8086.0, 8665.306775457964, 2129.818965387944, 2167.804897559219, 2277.1346888182466, 2352.3648614873973, 2357.457784709518, 2353.2704861492907, 2353.8823058847074, 2357.54027964547, 2365.5268946210826, 2370.668106465096, 2303.9308820735323, 2336.6495025746103, 2352.741198468167, 2353.6587194856734, 2361.25620429565, 2314.0859360552486, 2323.94190145246, 2337.1002164166835, 2343.800776933963, 2350.15394457995, 2301.7813307735737, 2328.348062542474, 2329.77867102625, 2332.043862016582, 2332.6120958029946, 8732.170443807829, 8781.56092195394, 8839.77900552485, 8841.911580884173, 8865.822683546294, 5148.61385396093, 5309.177077552986, 5317.12267475866, 5317.893642127175, 5318.468153184689, 3417.1457135716073, 3561.465780132975, 3622.6920711739535, 3636.5636123665063, 3647.6534729200685, 1053.0890779475699, 1085.365061439059, 1090.1170052257617, 1108.9500972456258, 1109.5617231193633, 11434.714132146679, 11529.769404611945, 11539.942300288445, 11553.249038812535, 11583.18917675763, 4414.84548040817, 4649.209634362099, 4746.857594272177, 4760.309755085494, 4767.809287628525, 9889.159421449114, 9889.406635601887, 9892.752681183028, 9898.604055837686, 9908.950455247677, 5279.155335146358, 5281.0758117329315, 5284.656497327662, 5287.894242115136, 5288.153895376721, 3590.622984586581, 4633.166030114612, 4844.2007068833955, 4844.539768721966, 4845.248986407112, 3508.9122771930906, 3611.909702257412, 3698.8890333290046, 3724.7578907370935, 3823.636754508317, 1115.2917897135214, 1124.1681155944543, 1124.6176300057855, 1130.7568872692411, 1137.6871360375962, 839.7858546070713, 850.171083193881, 856.8971723393232, 860.1398601398602, 968.6367612145445, 5732.484076433057, 5792.21805056312, 5852.034414321674, 5995.280566332068, 6142.508599312045, 859.3383095016802, 911.7036962987056, 960.2174228004154, 975.8145952269047, 1015.0914931136591, 3868.9553820468877, 3964.4053391990856, 3964.7819369934355, 4059.2084543513733, 4122.896927576835, 9769.853452198222, 10629.30909490896, 10751.892481075089, 10755.139588833032, 10756.247062705692, 10018.849717254247, 10680.98530639588, 10682.94658526718, 10702.304350217211, 10768.3538987661, 6595.571287866273, 8141.348692104618, 8146.185381461806, 8147.266745992889, 8149.388795840336, 5835.649861008353, 5919.230500034968, 6072.908906366496, 6096.664683442454, 6137.693115344304, 9501.144896959308, 9869.950650246703, 9878.012198780065, 9892.752681183028, 9902.207823374258, 8701.433741926514, 10186.898131018595, 10198.235132365251, 10215.336003159771, 10221.131203847459, 7450.925490745023, 7580.0, 7602.657880395506, 7608.885866712115, 7615.314621684075, 7281.05346304977, 7875.291223789886, 7972.0, 8075.475094118863, 8172.550509721785, 7317.963410182915, 7329.596872171868, 7365.558066516026, 7446.367058800062, 7469.178390376946, 7203.71185152588, 7261.927380726336, 7273.054502914587, 7280.381167600814, 7292.453066019963, 6881.41507971828, 6882.277360877236, 6966.5471744336455, 6972.755953541602, 6974.302569742985, 4853.320535125014, 4885.509919474594, 4963.379577552806, 4992.301077849032, 5108.10608143566, 4939.061578300111, 4970.900581988268, 4981.103401387785, 5061.063703214917, 5156.767945442389], "value": 4535.127569600464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8601000534752, 301.13574042498027, 314.7277604871786, 319.041280950743, 322.1527382982755, 4177.558742233924, 4358.8910277243085, 5210.062188806013, 5274.022241659378, 5308.708021058845, 4777.59390451812, 5069.898602027957, 5224.45143259958, 5233.0, 5280.973595132029, 3658.122050707824, 4022.8735953932946, 4027.335489644205, 4035.244668569167, 4072.5927407259333, 3919.804009799513, 4032.9514326275084, 4091.8772436826944, 4094.34490481523, 4094.3653733671326, 3985.880423587297, 3998.920021599566, 4012.5523430707244, 4038.5961403859524, 4056.9188616227657, 3172.5241213817994, 3954.9011274718073, 4014.2774300625874, 4045.4943132108488, 4062.0657248832936, 3485.599156097056, 3508.5438892944007, 3508.701760350373, 3518.7185025197923, 3563.0, 4695.779097434657, 4938.604911607063, 5076.670016448919, 5111.488851114858, 5216.808940245068, 3488.0, 4540.977295113504, 4578.779292043357, 4584.518625544337, 4644.907101857944, 5422.701751403672, 5434.347878254595, 5451.48210919962, 5453.918191227135, 5531.2809334786625, 3682.920889268824, 3820.465134881119, 3841.5582208046153, 3870.4000879863383, 3872.399778034381, 4753.500882407332, 5816.592838501264, 5820.825375238707, 5850.444207800295, 5948.226730525004, 3928.980355098206, 3956.5252169739815, 3965.643092121723, 3970.2456533258587, 3992.7604343739467, 3337.6495467975997, 3929.842806287774, 3974.344233201547, 4022.1955608878343, 4037.8788636340955, 3545.982270088633, 4326.134773045404, 4507.391502147234, 4563.748993805306, 4604.217283061887, 5449.5640348772, 5459.69971651563, 5467.371252305956, 5473.576870013825, 5548.667079975215, 1152.567787079845, 1280.2958372895057, 1309.2733532889338, 1332.7734285171543, 1354.3296068446182, 816.5386556595623, 904.4754042655244, 938.8122375524922, 938.8920274168559, 946.195733626412, 4352.368906508538, 5063.367079115111, 5079.526937188248, 5102.719350435762, 5165.889333793252, 4452.599266066005, 5607.383187849333, 5618.8314350568735, 5630.719558578782, 5691.516221121168, 4301.612605004253, 4775.785089670973, 5008.609163702376, 5059.2916991621205, 5120.948790512047, 2161.9243326483497, 2166.696662467256, 2183.7334346079238, 2458.508298340339, 2551.34940590152, 4147.387146097999, 4450.887278180455, 4570.565796249351, 4983.50494851543, 4985.700857948535, 4028.7784171870244, 4500.639948804088, 4532.410786597721, 4551.408316918845, 4744.074905393484, 4260.616544510947, 4438.648820140773, 4691.765411729468, 4847.80608775652, 5008.4240312364545, 3993.7803420812133, 4632.837850675345, 4662.111844702901, 4747.575727281871, 4852.587530059985, 4094.3653733671326, 4183.39340795589, 4252.022034931922, 4314.395984562101, 4676.579107880307, 4490.461144662661, 4892.339534162985, 4932.101141060646, 4944.0, 4971.569616964283, 3128.540696484649, 3318.469044952796, 3384.881529146468, 3388.5425467561563, 3442.3631628148873, 4215.451991241119, 4625.861224163281, 4800.951990480051, 4829.5653391194965, 5077.695338279715, 5058.6206034548295, 5311.654742441729, 5342.305500284938, 5351.812686556108, 5470.647621522624, 4583.335416364675, 4696.835610753607, 4833.782479788348, 5043.773030213576, 5379.381371142369, 5399.487048730364, 5419.701916394479, 5420.457954204706, 5425.294711687456, 5443.346798384219, 4330.718503297276, 5523.8066667666435, 5609.4390560943575, 5688.886222275614, 5709.685967271674, 5514.779408823602, 5532.2254884315425, 5577.330720313588, 5603.5797315202335, 5723.627964182315], "value": 3863.0950651561557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.3849258883654, 722.3583810440457, 770.1605250277197, 820.7291593774055, 823.1891586787016, 15058.17180055098, 16395.4261600844, 16410.343586256535, 16418.91790541047, 16423.0, 14764.778528322056, 14871.033382830108, 14886.851131488682, 14894.10635361879, 14908.7018259635, 687.5977553131424, 711.7757906259511, 851.0935853316214, 857.1685465098844, 882.4572887674067, 863.8056437301589, 883.5361435246515, 884.2881480408269, 885.1856292211181, 888.5468411110348, 949.178960199426, 950.235060776075, 951.7335146159052, 953.6280850468323, 954.0554850697794, 741.6254791330398, 765.938724902012, 769.465221670939, 946.3233787841692, 947.0009140356921, 569.4277251362396, 667.1993607670814, 683.6274230544366, 685.6880119545594, 685.9108315918899, 14789.852101478955, 14806.111633302036, 14830.258487075604, 14846.480373186887, 14855.0, 13959.511417100453, 13999.370028348749, 14274.3576539056, 14322.928385358111, 14348.282585870666, 13097.083204175711, 13284.0, 13349.86650133496, 13563.45746170152, 13685.931570342183, 4481.798319075617, 4598.0, 4619.16854966106, 4673.228917228653, 4701.341812146268, 125158.37420812837, 126753.0, 127123.36438317841, 127232.36383818115, 127709.0, 11021.944890275658, 11410.42947852604, 11561.07511399086, 11760.88239117615, 11823.994960428288, 22618.982145803147, 22650.546989060127, 22652.320430387113, 22659.0, 22676.65985010226, 7942.285194332538, 8128.512289262663, 8199.42604017725, 8292.958535207285, 8303.4602750821, 4264.829406823692, 4298.677599180074, 4437.423134992495, 4464.888377790517, 4465.307877279027, 3490.89527314181, 3661.450782382664, 3661.6338366163172, 3663.7984910829628, 3665.4318580620047, 641.592588706175, 650.8210242183437, 661.0777964739162, 664.6178447392768, 664.8969409741497, 503.8688145114242, 551.892380985712, 637.3721883944311, 647.1069923505627, 649.6199723161909, 7925.326347260548, 8898.644054237888, 9087.273018158532, 9212.493312867857, 9266.629334826532, 584.4652143288928, 592.2922108080802, 624.8968920128219, 631.1252246019334, 640.6572483721187, 4349.934750978738, 4360.49854266757, 4502.909941801146, 4553.726776393427, 4591.632669386441, 103180.0, 116993.0, 153778.0, 160773.39226607818, 166506.0, 106468.9353106459, 114864.42567787107, 117187.0, 137037.0, 143218.0, 78733.60633197027, 102822.0, 104391.0, 108586.45706771573, 109524.90475095306, 9653.0, 9794.804103918024, 9964.302498825013, 10053.346532475365, 10066.396016239049, 59853.700731496065, 61129.38870611237, 61185.0, 63586.0, 64436.0, 89632.0, 91763.0, 96863.0, 100664.0, 104740.0, 13353.465861365437, 13355.933220333836, 13409.530666426628, 13478.056536042383, 13866.0, 13439.0, 14725.55823325302, 14771.593166075723, 15007.624809379853, 15094.773578396553, 12168.452419641315, 13215.537456189371, 14057.929710351384, 14069.507567235098, 14416.927915360775, 12758.035208960055, 13297.736715012059, 13629.522966696119, 13927.0, 14390.784138238145, 11570.59502917394, 13357.198568085945, 13511.932440338129, 13646.317684115556, 13940.163590184622, 11175.582227167835, 11189.776204476027, 11201.43992800373, 11260.549578017115, 11365.886341136813, 11242.606508772154, 11336.716582085513, 11400.0, 11609.709757256136, 11798.056155507711], "value": 7930.884032416371, "unit": "score"}], "name": "dromaeo_css", "value": 7338.165611992042, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 111.0, 109.0, 106.0, 110.0, 116.0, 158.0, 129.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 106.0, 109.0, 168.0, 110.0, 106.0, 131.0, 106.0, 142.0, 107.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 182.0, 182.0, 182.0, 183.0, 185.0, 180.0, 183.0, 183.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 62.0, 88.0, 58.0, 56.0, 59.0, 66.0, 82.0, 60.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 101.0, 70.0, 78.0, 70.0, 97.0, 69.0, 68.0, 121.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 86.0, 88.0, 83.0, 87.0, 84.0, 86.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 105.0, 149.0, 122.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 85.0, 86.0, 84.0, 84.0, 90.0, 86.0, 84.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 55.0, 53.0, 51.0, 58.0, 80.0, 80.0, 80.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 71.0, 76.0, 74.0, 69.0, 71.0, 72.0, 71.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 82.0, 85.0, 82.0, 85.0, 85.0, 86.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 140.0, 136.0, 136.0, 137.0, 133.0, 160.0, 228.0, 151.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 165.0, 166.0, 164.0, 164.0, 163.0, 164.0, 163.0, 166.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 85.0, 65.0, 66.0, 65.0, 67.0, 66.0, 66.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1444.1, "alertThreshold": 2.0}]} 23:05:39 INFO - Return code: 0 23:05:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:05:39 INFO - # TBPL SUCCESS # 23:05:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:05:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:05:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:05:39 INFO - 2016-08-08 23:05:39.473 screenresolution[1681:10624] starting screenresolution argv=screenresolution get 23:05:39 INFO - 2016-08-08 23:05:39.476 screenresolution[1681:10624] Display 0: 1600x1200x32@60 23:05:39 INFO - 2016-08-08 23:05:39.482 screenresolution[1682:10627] starting screenresolution argv=screenresolution list 23:05:39 INFO - Available Modes on Display 0 23:05:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:05:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:05:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:05:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:05:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:05:39 INFO - Intel Iris: 23:05:39 INFO - Chipset Model: Intel Iris 23:05:39 INFO - Type: GPU 23:05:39 INFO - Bus: Built-In 23:05:39 INFO - VRAM (Dynamic, Max): 1536 MB 23:05:39 INFO - Vendor: Intel (0x8086) 23:05:39 INFO - Device ID: 0x0a2e 23:05:39 INFO - Revision ID: 0x0009 23:05:39 INFO - Displays: 23:05:39 INFO - Display: 23:05:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:05:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:05:39 INFO - Display Serial Number: 1600x1200 60 23:05:39 INFO - Main Display: Yes 23:05:39 INFO - Mirror: Off 23:05:39 INFO - Online: Yes 23:05:39 INFO - Rotation: Supported 23:05:39 INFO - Return code: 0 23:05:39 INFO - Running post-action listener: _resource_record_post_action 23:05:39 INFO - [mozharness: 2016-08-09 06:05:39.721068Z] Finished run-tests step (success) 23:05:39 INFO - Running post-run listener: _resource_record_post_run 23:05:39 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 31894528; Write bytes: 705945600; Read time: 198; Write time: 1987 23:05:39 INFO - TinderboxPrint: CPU usage
53.7% 23:05:39 INFO - TinderboxPrint: I/O read bytes / time
31,894,528 / 198 23:05:39 INFO - TinderboxPrint: I/O write bytes / time
705,945,600 / 1,987 23:05:39 INFO - TinderboxPrint: CPU idle
2,250.4 (46.3%) 23:05:39 INFO - TinderboxPrint: CPU system
970.9 (20.0%) 23:05:39 INFO - TinderboxPrint: CPU user
1,636.3 (33.7%) 23:05:39 INFO - TinderboxPrint: Swap in / out
435,142,656 / 0 23:05:39 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390203904; Write bytes: 384583168; Read time: 29419; Write time: 826 23:05:39 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30972928; Write bytes: 296040960; Read time: 188; Write time: 1101 23:05:40 INFO - Running post-run listener: _upload_blobber_files 23:05:40 INFO - Blob upload gear active. 23:05:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:05:40 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 23:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:05:40 INFO - (blobuploader) - INFO - Open directory for files ... 23:05:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:05:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:05:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:41 INFO - (blobuploader) - INFO - Done attempting. 23:05:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:05:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:05:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:42 INFO - (blobuploader) - INFO - Done attempting. 23:05:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:05:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:45 INFO - (blobuploader) - INFO - Done attempting. 23:05:45 INFO - (blobuploader) - INFO - Iteration through files over. 23:05:45 INFO - Return code: 0 23:05:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:05:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:05:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fba73926b2dd6e4c1bb4db21652ec1b2c72d959b3e15a62b3742de06d2f585bfd95acf22b46bab84a1677846644e72c9fe769f9e371e8c79f12f57f2dc4e9653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b670986d3f1b6465c865d9efc772aae7f25ad5a128cec56abccdf3f0afdd8af7ac5071355021bd76eef058ef754f6974068905c2dad231c751a639f1e9ec109e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3cd8e2a960e5b779ab1f2dea8b387304c28adce2096d53637229d33a32f6301453ccf17fb43f9b3d3ac5ebd55b1c13060b21e6db8c97b860af0672064ad69a1a"} 23:05:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:05:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:05:45 INFO - Contents: 23:05:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fba73926b2dd6e4c1bb4db21652ec1b2c72d959b3e15a62b3742de06d2f585bfd95acf22b46bab84a1677846644e72c9fe769f9e371e8c79f12f57f2dc4e9653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b670986d3f1b6465c865d9efc772aae7f25ad5a128cec56abccdf3f0afdd8af7ac5071355021bd76eef058ef754f6974068905c2dad231c751a639f1e9ec109e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3cd8e2a960e5b779ab1f2dea8b387304c28adce2096d53637229d33a32f6301453ccf17fb43f9b3d3ac5ebd55b1c13060b21e6db8c97b860af0672064ad69a1a"} 23:05:45 INFO - Running post-run listener: copy_logs_to_upload_dir 23:05:45 INFO - Copying logs to upload dir... 23:05:45 INFO - mkdir: /builds/slave/test/build/upload/logs 23:05:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.186661 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-08 23:05:45.507706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 23:05:45.510910) ========= 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.duljB77ipj/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.0QLNCxz9ZI/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fba73926b2dd6e4c1bb4db21652ec1b2c72d959b3e15a62b3742de06d2f585bfd95acf22b46bab84a1677846644e72c9fe769f9e371e8c79f12f57f2dc4e9653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b670986d3f1b6465c865d9efc772aae7f25ad5a128cec56abccdf3f0afdd8af7ac5071355021bd76eef058ef754f6974068905c2dad231c751a639f1e9ec109e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3cd8e2a960e5b779ab1f2dea8b387304c28adce2096d53637229d33a32f6301453ccf17fb43f9b3d3ac5ebd55b1c13060b21e6db8c97b860af0672064ad69a1a"} build_url:https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011485 build_url: 'https://queue.taskcluster.net/v1/task/ajLAl90gT02q4zxgh8Ti5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fba73926b2dd6e4c1bb4db21652ec1b2c72d959b3e15a62b3742de06d2f585bfd95acf22b46bab84a1677846644e72c9fe769f9e371e8c79f12f57f2dc4e9653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b670986d3f1b6465c865d9efc772aae7f25ad5a128cec56abccdf3f0afdd8af7ac5071355021bd76eef058ef754f6974068905c2dad231c751a639f1e9ec109e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3cd8e2a960e5b779ab1f2dea8b387304c28adce2096d53637229d33a32f6301453ccf17fb43f9b3d3ac5ebd55b1c13060b21e6db8c97b860af0672064ad69a1a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 23:05:45.543997) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 23:05:45.544339) ========= 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.duljB77ipj/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.0QLNCxz9ZI/Listeners TMPDIR=/var/folders/h3/hfjysk0d4wl2f0m0l6zfjwlr00000w/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.005211 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 23:05:45.592532) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 23:05:45.592842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 23:05:45.593200) ========= ========= Total master_lag: 0.20 =========