builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0067 starttime: 1470764877.33 results: success (0) buildid: 20160809084020 builduid: 1e7d689181c84f68adafdfd3df5701c9 revision: d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.325501) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.325981) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.326298) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.355722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.356093) ========= 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.nd3poAWQaA/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.P2CEEXcm6t/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-09 10:47:57-- 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% 12.4M=0.001s 2016-08-09 10:47:57 (12.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.209858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.589189) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.589561) ========= 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.nd3poAWQaA/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.P2CEEXcm6t/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.028510 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:57.642433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 10:47:57.644877) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9 --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 d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nd3poAWQaA/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.P2CEEXcm6t/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-09 10:47:57,721 truncating revision to first 12 chars 2016-08-09 10:47:57,721 Setting DEBUG logging. 2016-08-09 10:47:57,721 attempt 1/10 2016-08-09 10:47:57,721 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d4b23db9ec3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 10:47:58,549 unpacking tar archive at: autoland-d4b23db9ec3a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.103174 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 10:47:58.774914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:58.775287) ========= script_repo_revision: d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:58.775741) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:58.776050) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 10:47:58.798499) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-09 10:47:58.798942) ========= /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.nd3poAWQaA/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.P2CEEXcm6t/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:47:58 INFO - MultiFileLogger online at 20160809 10:47:58 in /builds/slave/test 10:47:58 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 10:47:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:47:59 INFO - {'append_to_log': False, 10:47:59 INFO - 'base_work_dir': '/builds/slave/test', 10:47:59 INFO - 'blob_upload_branch': 'Autoland', 10:47:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:47:59 INFO - 'branch': 'Autoland', 10:47:59 INFO - 'buildbot_json_path': 'buildprops.json', 10:47:59 INFO - 'config_files': ('talos/mac_config.py',), 10:47:59 INFO - 'default_actions': ('clobber', 10:47:59 INFO - 'read-buildbot-config', 10:47:59 INFO - 'download-and-extract', 10:47:59 INFO - 'populate-webroot', 10:47:59 INFO - 'create-virtualenv', 10:47:59 INFO - 'install', 10:47:59 INFO - 'run-tests'), 10:47:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:47:59 INFO - 'download_minidump_stackwalk': True, 10:47:59 INFO - 'download_symbols': 'ondemand', 10:47:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:47:59 INFO - 'tooltool.py': '/tools/tooltool.py', 10:47:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:47:59 INFO - '/tools/misc-python/virtualenv.py')}, 10:47:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:47:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:47:59 INFO - 'installer_path': 'installer.exe', 10:47:59 INFO - 'log_level': 'info', 10:47:59 INFO - 'log_name': 'talos', 10:47:59 INFO - 'log_to_console': True, 10:47:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:47:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:47:59 INFO - 'opt_config_files': (), 10:47:59 INFO - 'pip_index': False, 10:47:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:59 INFO - 'cmd': ('bash', 10:47:59 INFO - '-c', 10:47:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:59 INFO - 'enabled': True, 10:47:59 INFO - 'halt_on_failure': False, 10:47:59 INFO - 'name': 'check_screen_resolution'},), 10:47:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:59 INFO - 'cmd': ('bash', 10:47:59 INFO - '-c', 10:47:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:59 INFO - 'enabled': True, 10:47:59 INFO - 'halt_on_failure': False, 10:47:59 INFO - 'name': 'check_screen_resolution'},), 10:47:59 INFO - 'run_cmd_checks_enabled': True, 10:47:59 INFO - 'sps_profile': False, 10:47:59 INFO - 'sps_profile_interval': 0, 10:47:59 INFO - 'suite': 'dromaeojs', 10:47:59 INFO - 'system_bits': '32', 10:47:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:47:59 INFO - 'title': 't-yosemite-r7-0067', 10:47:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:47:59 INFO - 'use_talos_json': True, 10:47:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:47:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:47:59 INFO - 'work_dir': 'build'} 10:47:59 INFO - [mozharness: 2016-08-09 17:47:59.007138Z] Running clobber step. 10:47:59 INFO - Running pre-action listener: _resource_record_pre_action 10:47:59 INFO - Running main action method: clobber 10:47:59 INFO - rmtree: /builds/slave/test/build 10:47:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:48:01 INFO - Running post-action listener: _resource_record_post_action 10:48:01 INFO - [mozharness: 2016-08-09 17:48:01.235865Z] Finished clobber step (success) 10:48:01 INFO - [mozharness: 2016-08-09 17:48:01.236124Z] Running read-buildbot-config step. 10:48:01 INFO - Running pre-action listener: _resource_record_pre_action 10:48:01 INFO - Running main action method: read_buildbot_config 10:48:01 INFO - Using buildbot properties: 10:48:01 INFO - { 10:48:01 INFO - "project": "", 10:48:01 INFO - "product": "firefox", 10:48:01 INFO - "script_repo_revision": "production", 10:48:01 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:48:01 INFO - "repository": "", 10:48:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:48:01 INFO - "buildid": "20160809084020", 10:48:01 INFO - "pgo_build": "False", 10:48:01 INFO - "basedir": "/builds/slave/test", 10:48:01 INFO - "buildnumber": 103, 10:48:01 INFO - "slavename": "t-yosemite-r7-0067", 10:48:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:48:01 INFO - "platform": "yosemite_r7", 10:48:01 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:48:01 INFO - "branch": "autoland", 10:48:01 INFO - "repo_path": "integration/autoland", 10:48:01 INFO - "revision": "d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9", 10:48:01 INFO - "stage_platform": "macosx64", 10:48:01 INFO - "builduid": "1e7d689181c84f68adafdfd3df5701c9", 10:48:01 INFO - "slavebuilddir": "test" 10:48:01 INFO - } 10:48:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:48:01 INFO - Running post-action listener: _resource_record_post_action 10:48:01 INFO - [mozharness: 2016-08-09 17:48:01.241475Z] Finished read-buildbot-config step (success) 10:48:01 INFO - [mozharness: 2016-08-09 17:48:01.241680Z] Running download-and-extract step. 10:48:01 INFO - Running pre-action listener: _resource_record_pre_action 10:48:01 INFO - Running main action method: download_and_extract 10:48:01 INFO - mkdir: /builds/slave/test/build/tests 10:48:01 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')]} 10:48:01 INFO - trying https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:48:01 INFO - Downloading https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:01 INFO - Downloaded 1199 bytes. 10:48:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:48:01 INFO - Using the following test package requirements: 10:48:01 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:48:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:48:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:48:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'jsshell-mac.zip'], 10:48:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:48:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:48:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:48:01 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:48:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:48:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:48:01 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:48:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:48:01 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')]} 10:48:01 INFO - trying https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:48:01 INFO - Downloading https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:02 INFO - Downloaded 16326432 bytes. 10:48:02 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'] 10:48:02 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 10:48:05 INFO - Return code: 0 10:48:05 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 10:48: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')]} 10:48:05 INFO - trying https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:48:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:06 INFO - Downloaded 16326432 bytes. 10:48:06 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'] 10:48:06 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 10:48:10 INFO - Return code: 0 10:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:10 INFO - trying https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:48:10 INFO - Downloading https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:11 INFO - Downloaded 14413723 bytes. 10:48:11 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'] 10:48:11 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 10:48:11 INFO - Return code: 0 10:48:11 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')]} 10:48:11 INFO - trying https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:48:11 INFO - Downloading https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:48:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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 10:48:14 INFO - Downloaded 110903937 bytes. 10:48:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:48:14 INFO - mkdir: /builds/slave/test/properties 10:48:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:48:14 INFO - Writing to file /builds/slave/test/properties/build_url 10:48:14 INFO - Contents: 10:48:14 INFO - build_url:https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:48:15 INFO - Running post-action listener: _resource_record_post_action 10:48:15 INFO - Running post-action listener: set_extra_try_arguments 10:48:15 INFO - [mozharness: 2016-08-09 17:48:15.392360Z] Finished download-and-extract step (success) 10:48:15 INFO - [mozharness: 2016-08-09 17:48:15.392518Z] Running populate-webroot step. 10:48:15 INFO - Running pre-action listener: _resource_record_pre_action 10:48:15 INFO - Running main action method: populate_webroot 10:48:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:48:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:48:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:48:15 INFO - '--tppagecycles', 10:48:15 INFO - '7'], 10:48:15 INFO - 'tests': ['tsvgm']}}, 10:48:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:48:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:48:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:48:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:48:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['damp', 'tps']}, 10:48:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'tests': ['damp', 'tps']}, 10:48:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['dromaeo_dom']}, 10:48:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:48:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['basic_compositor_video']}, 10:48:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:48:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['a11yr', 10:48:15 INFO - 'ts_paint', 10:48:15 INFO - 'tpaint', 10:48:15 INFO - 'sessionrestore', 10:48:15 INFO - 'sessionrestore_no_auto_restore', 10:48:15 INFO - 'tabpaint']}, 10:48:15 INFO - 'other-e10s': {'tests': ['a11yr', 10:48:15 INFO - 'ts_paint', 10:48:15 INFO - 'tpaint', 10:48:15 INFO - 'sessionrestore', 10:48:15 INFO - 'sessionrestore_no_auto_restore', 10:48:15 INFO - 'tabpaint']}, 10:48:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:48:15 INFO - 'ts_paint', 10:48:15 INFO - 'tpaint', 10:48:15 INFO - 'sessionrestore', 10:48:15 INFO - 'sessionrestore_no_auto_restore', 10:48:15 INFO - 'tabpaint']}, 10:48:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:48:15 INFO - 'ts_paint', 10:48:15 INFO - 'tpaint', 10:48:15 INFO - 'sessionrestore', 10:48:15 INFO - 'sessionrestore_no_auto_restore', 10:48:15 INFO - 'tabpaint']}, 10:48:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['a11yr', 10:48:15 INFO - 'ts_paint', 10:48:15 INFO - 'tpaint', 10:48:15 INFO - 'sessionrestore', 10:48:15 INFO - 'sessionrestore_no_auto_restore', 10:48:15 INFO - 'tabpaint']}, 10:48:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['a11yr', 10:48:15 INFO - 'ts_paint', 10:48:15 INFO - 'tpaint', 10:48:15 INFO - 'sessionrestore', 10:48:15 INFO - 'sessionrestore_no_auto_restore', 10:48:15 INFO - 'tabpaint']}, 10:48:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['tsvgx', 10:48:15 INFO - 'tsvgr_opacity', 10:48:15 INFO - 'tart', 10:48:15 INFO - 'tscrollx', 10:48:15 INFO - 'cart']}, 10:48:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:48:15 INFO - 'tsvgr_opacity', 10:48:15 INFO - 'tart', 10:48:15 INFO - 'tscrollx', 10:48:15 INFO - 'cart']}, 10:48:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'talos_options': ['--disable-e10s'], 10:48:15 INFO - 'tests': ['tp5o']}, 10:48:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'tests': ['tp5o']}, 10:48:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'talos_options': ['--disable-e10s', 10:48:15 INFO - '--xperf_path', 10:48:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:15 INFO - 'tests': ['tp5n']}, 10:48:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:15 INFO - 'talos_options': ['--xperf_path', 10:48:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:15 INFO - 'tests': ['tp5n']}}, 10:48:15 INFO - 'talos.zip': {'path': '', 10:48:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:48:15 INFO - Running post-action listener: _resource_record_post_action 10:48:15 INFO - [mozharness: 2016-08-09 17:48:15.412074Z] Finished populate-webroot step (success) 10:48:15 INFO - [mozharness: 2016-08-09 17:48:15.412186Z] Running create-virtualenv step. 10:48:15 INFO - Running pre-action listener: _resource_record_pre_action 10:48:15 INFO - Running main action method: create_virtualenv 10:48:15 INFO - Creating virtualenv /builds/slave/test/build/venv 10:48:15 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 10:48:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:48:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:48:15 INFO - Using real prefix '/tools/python27' 10:48:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:48:16 INFO - Installing distribute.............................................................................................................................................................................................done. 10:48:19 INFO - Installing pip.................done. 10:48:19 INFO - Return code: 0 10:48:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:48:19 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')]} 10:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:19 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')]} 10:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:19 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:19 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 10:48:19 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 10:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:19 INFO - 'HOME': '/Users/cltbld', 10:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:19 INFO - 'LOGNAME': 'cltbld', 10:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:19 INFO - 'MOZ_NO_REMOTE': '1', 10:48:19 INFO - 'NO_EM_RESTART': '1', 10:48:19 INFO - 'PAGER': '/bin/cat', 10:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:19 INFO - 'PWD': '/builds/slave/test', 10:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:19 INFO - 'SHELL': '/bin/bash', 10:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:19 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:19 INFO - 'USER': 'cltbld', 10:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:19 INFO - 'XPC_FLAGS': '0x0', 10:48:19 INFO - 'XPC_SERVICE_NAME': '0', 10:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:19 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 10:48:19 INFO - Cleaning up... 10:48:19 INFO - Return code: 0 10:48:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:48:19 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')]} 10:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:19 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')]} 10:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:19 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:19 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 10:48:19 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 10:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:19 INFO - 'HOME': '/Users/cltbld', 10:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:19 INFO - 'LOGNAME': 'cltbld', 10:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:19 INFO - 'MOZ_NO_REMOTE': '1', 10:48:19 INFO - 'NO_EM_RESTART': '1', 10:48:19 INFO - 'PAGER': '/bin/cat', 10:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:19 INFO - 'PWD': '/builds/slave/test', 10:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:19 INFO - 'SHELL': '/bin/bash', 10:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:19 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:19 INFO - 'USER': 'cltbld', 10:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:19 INFO - 'XPC_FLAGS': '0x0', 10:48:19 INFO - 'XPC_SERVICE_NAME': '0', 10:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:19 INFO - Downloading/unpacking psutil>=3.1.1 10:48:19 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 10:48:19 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 10:48:19 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 10:48:19 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 10:48:19 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 10:48:19 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 10:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:48:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:23 INFO - Installing collected packages: psutil 10:48:23 INFO - Running setup.py install for psutil 10:48:23 INFO - building 'psutil._psutil_osx' extension 10:48:23 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 10:48:23 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 10:48:23 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 10:48:23 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 10:48:23 INFO - building 'psutil._psutil_posix' extension 10:48:23 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 10:48:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:48:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:48:24 INFO - ^ 10:48:24 INFO - 1 warning generated. 10:48:24 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 10:48:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:24 INFO - Successfully installed psutil 10:48:24 INFO - Cleaning up... 10:48:24 INFO - Return code: 0 10:48:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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')]} 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:48: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 mozsystemmonitor==0.3 10:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:24 INFO - 'HOME': '/Users/cltbld', 10:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:24 INFO - 'LOGNAME': 'cltbld', 10:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:24 INFO - 'MOZ_NO_REMOTE': '1', 10:48:24 INFO - 'NO_EM_RESTART': '1', 10:48:24 INFO - 'PAGER': '/bin/cat', 10:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:24 INFO - 'PWD': '/builds/slave/test', 10:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:24 INFO - 'SHELL': '/bin/bash', 10:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:24 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:24 INFO - 'USER': 'cltbld', 10:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:24 INFO - 'XPC_FLAGS': '0x0', 10:48:24 INFO - 'XPC_SERVICE_NAME': '0', 10:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:48:27 INFO - Installing collected packages: mozsystemmonitor 10:48:27 INFO - Running setup.py install for mozsystemmonitor 10:48:27 INFO - Successfully installed mozsystemmonitor 10:48:27 INFO - Cleaning up... 10:48:27 INFO - Return code: 0 10:48:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:27 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:27 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 10:48:27 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 10:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:27 INFO - 'HOME': '/Users/cltbld', 10:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:27 INFO - 'LOGNAME': 'cltbld', 10:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:27 INFO - 'MOZ_NO_REMOTE': '1', 10:48:27 INFO - 'NO_EM_RESTART': '1', 10:48:27 INFO - 'PAGER': '/bin/cat', 10:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:27 INFO - 'PWD': '/builds/slave/test', 10:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:27 INFO - 'SHELL': '/bin/bash', 10:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:27 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:27 INFO - 'USER': 'cltbld', 10:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:27 INFO - 'XPC_FLAGS': '0x0', 10:48:27 INFO - 'XPC_SERVICE_NAME': '0', 10:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:27 INFO - Downloading/unpacking jsonschema==2.5.1 10:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:48:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:48:29 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 10:48:29 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 10:48:29 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 10:48:29 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 10:48:29 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 10:48:29 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 10:48:30 INFO - Downloading functools32-3.2.3-2.tar.gz 10:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:48:30 INFO - warning: no files found matching '*.txt' 10:48:30 INFO - no previously-included directories found matching 'build' 10:48:30 INFO - no previously-included directories found matching 'dist' 10:48:30 INFO - no previously-included directories found matching '.git*' 10:48:30 INFO - Installing collected packages: jsonschema, functools32 10:48:30 INFO - Running setup.py install for functools32 10:48:30 INFO - warning: no files found matching '*.txt' 10:48:30 INFO - no previously-included directories found matching 'build' 10:48:30 INFO - no previously-included directories found matching 'dist' 10:48:30 INFO - no previously-included directories found matching '.git*' 10:48:30 INFO - Successfully installed jsonschema functools32 10:48:30 INFO - Cleaning up... 10:48:30 INFO - Return code: 0 10:48:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:30 INFO - 'HOME': '/Users/cltbld', 10:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:30 INFO - 'LOGNAME': 'cltbld', 10:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:30 INFO - 'MOZ_NO_REMOTE': '1', 10:48:30 INFO - 'NO_EM_RESTART': '1', 10:48:30 INFO - 'PAGER': '/bin/cat', 10:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:30 INFO - 'PWD': '/builds/slave/test', 10:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:30 INFO - 'SHELL': '/bin/bash', 10:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:30 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:30 INFO - 'USER': 'cltbld', 10:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:30 INFO - 'XPC_FLAGS': '0x0', 10:48:30 INFO - 'XPC_SERVICE_NAME': '0', 10:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:48:31 INFO - Cleaning up... 10:48:31 INFO - Return code: 0 10:48:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:31 INFO - 'HOME': '/Users/cltbld', 10:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:31 INFO - 'LOGNAME': 'cltbld', 10:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:31 INFO - 'MOZ_NO_REMOTE': '1', 10:48:31 INFO - 'NO_EM_RESTART': '1', 10:48:31 INFO - 'PAGER': '/bin/cat', 10:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:31 INFO - 'PWD': '/builds/slave/test', 10:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:31 INFO - 'SHELL': '/bin/bash', 10:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:31 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:31 INFO - 'USER': 'cltbld', 10:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:31 INFO - 'XPC_FLAGS': '0x0', 10:48:31 INFO - 'XPC_SERVICE_NAME': '0', 10:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:31 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:33 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:34 INFO - Downloading docopt-0.6.1.tar.gz 10:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:34 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:34 INFO - Running setup.py install for blobuploader 10:48:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:35 INFO - Running setup.py install for requests 10:48:35 INFO - Running setup.py install for docopt 10:48:35 INFO - Successfully installed blobuploader requests docopt 10:48:35 INFO - Cleaning up... 10:48:35 INFO - Return code: 0 10:48:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:35 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:35 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 10:48:35 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 10:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:35 INFO - 'HOME': '/Users/cltbld', 10:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:35 INFO - 'LOGNAME': 'cltbld', 10:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:35 INFO - 'MOZ_NO_REMOTE': '1', 10:48:35 INFO - 'NO_EM_RESTART': '1', 10:48:35 INFO - 'PAGER': '/bin/cat', 10:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:35 INFO - 'PWD': '/builds/slave/test', 10:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:35 INFO - 'SHELL': '/bin/bash', 10:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:35 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:35 INFO - 'USER': 'cltbld', 10:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:35 INFO - 'XPC_FLAGS': '0x0', 10:48:35 INFO - 'XPC_SERVICE_NAME': '0', 10:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-zpD_YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-kGTUxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-D0Qk1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-_4y7zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-FlcG2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-UyR5VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-AUVvyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-uYUrD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:36 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-JbRDz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-yX_1aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-sSJcHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-kSo9cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-33TEL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-dwKIAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-jE9Uxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-WQmj29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-C1Wb9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:37 INFO - Running setup.py install for manifestparser 10:48:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:37 INFO - Running setup.py install for mozcrash 10:48:38 INFO - Running setup.py install for mozdebug 10:48:38 INFO - Running setup.py install for mozdevice 10:48:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Running setup.py install for mozfile 10:48:38 INFO - Running setup.py install for mozhttpd 10:48:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Running setup.py install for mozinfo 10:48:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Running setup.py install for mozInstall 10:48:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Running setup.py install for mozleak 10:48:39 INFO - Running setup.py install for mozlog 10:48:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Running setup.py install for moznetwork 10:48:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Running setup.py install for mozprocess 10:48:39 INFO - Running setup.py install for mozprofile 10:48:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Running setup.py install for mozrunner 10:48:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:39 INFO - Running setup.py install for mozscreenshot 10:48:40 INFO - Running setup.py install for moztest 10:48:40 INFO - Running setup.py install for mozversion 10:48:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:40 INFO - Cleaning up... 10:48:40 INFO - Return code: 0 10:48:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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')]} 10:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:40 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:40 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 10:48:40 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 10:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:40 INFO - 'HOME': '/Users/cltbld', 10:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:40 INFO - 'LOGNAME': 'cltbld', 10:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:40 INFO - 'MOZ_NO_REMOTE': '1', 10:48:40 INFO - 'NO_EM_RESTART': '1', 10:48:40 INFO - 'PAGER': '/bin/cat', 10:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:40 INFO - 'PWD': '/builds/slave/test', 10:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:40 INFO - 'SHELL': '/bin/bash', 10:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:40 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:40 INFO - 'USER': 'cltbld', 10:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:40 INFO - 'XPC_FLAGS': '0x0', 10:48:40 INFO - 'XPC_SERVICE_NAME': '0', 10:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:40 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-_8Ht4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:40 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)) 10:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:40 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-jvn6sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:40 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)) 10:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:40 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-otU0lN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-5l2ZMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-QQrmJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-3Zk9CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-v6hqO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ARwDQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ThlPhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-QLfy8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-svrYQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:41 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-W9cPZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:41 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)) 10:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:42 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-1YhYwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:42 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)) 10:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:42 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-SdNu2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:42 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)) 10:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:42 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-i5NleO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:42 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)) 10:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:42 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-3O8oaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:42 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)) 10:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:42 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-X5O6gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:42 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)) 10:48:42 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)) 10:48:42 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)) 10:48:42 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)) 10:48:42 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)) 10:48:42 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)) 10:48:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48:42 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 10:48:42 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 10:48:42 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 10:48:42 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 10:48:42 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 10:48:42 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 10:48:44 INFO - Downloading blessings-1.6.tar.gz 10:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:48:45 INFO - Installing collected packages: blessings 10:48:45 INFO - Running setup.py install for blessings 10:48:45 INFO - Successfully installed blessings 10:48:45 INFO - Cleaning up... 10:48:45 INFO - Return code: 0 10:48:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:45 INFO - Reading from file tmpfile_stdout 10:48:45 INFO - Current package versions: 10:48:45 INFO - blessings == 1.6 10:48:45 INFO - blobuploader == 1.2.4 10:48:45 INFO - docopt == 0.6.1 10:48:45 INFO - functools32 == 3.2.3-2 10:48:45 INFO - jsonschema == 2.5.1 10:48:45 INFO - manifestparser == 1.1 10:48:45 INFO - mozInstall == 1.12 10:48:45 INFO - mozcrash == 0.17 10:48:45 INFO - mozdebug == 0.1 10:48:45 INFO - mozdevice == 0.48 10:48:45 INFO - mozfile == 1.2 10:48:45 INFO - mozhttpd == 0.7 10:48:45 INFO - mozinfo == 0.9 10:48:45 INFO - mozleak == 0.1 10:48:45 INFO - mozlog == 3.3 10:48:45 INFO - moznetwork == 0.27 10:48:45 INFO - mozprocess == 0.23 10:48:45 INFO - mozprofile == 0.28 10:48:45 INFO - mozrunner == 6.12 10:48:45 INFO - mozscreenshot == 0.1 10:48:45 INFO - mozsystemmonitor == 0.3 10:48:45 INFO - moztest == 0.7 10:48:45 INFO - mozversion == 1.4 10:48:45 INFO - psutil == 3.1.1 10:48:45 INFO - requests == 1.2.3 10:48:45 INFO - wsgiref == 0.1.2 10:48:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:45 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')]} 10:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:45 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')]} 10:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:45 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:45 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 10:48:45 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 10:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:45 INFO - 'HOME': '/Users/cltbld', 10:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:45 INFO - 'LOGNAME': 'cltbld', 10:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:45 INFO - 'MOZ_NO_REMOTE': '1', 10:48:45 INFO - 'NO_EM_RESTART': '1', 10:48:45 INFO - 'PAGER': '/bin/cat', 10:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:45 INFO - 'PWD': '/builds/slave/test', 10:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:45 INFO - 'SHELL': '/bin/bash', 10:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:45 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:45 INFO - 'USER': 'cltbld', 10:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:45 INFO - 'XPC_FLAGS': '0x0', 10:48:45 INFO - 'XPC_SERVICE_NAME': '0', 10:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 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)) 10:48:45 INFO - Cleaning up... 10:48:45 INFO - Return code: 0 10:48:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:48:45 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')]} 10:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:45 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')]} 10:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:45 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 0x1021f8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10228d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab5349bd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, '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.nd3poAWQaA/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.P2CEEXcm6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:48:45 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 10:48:45 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 10:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:45 INFO - 'HOME': '/Users/cltbld', 10:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:45 INFO - 'LOGNAME': 'cltbld', 10:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:45 INFO - 'MOZ_NO_REMOTE': '1', 10:48:45 INFO - 'NO_EM_RESTART': '1', 10:48:45 INFO - 'PAGER': '/bin/cat', 10:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:45 INFO - 'PWD': '/builds/slave/test', 10:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:45 INFO - 'SHELL': '/bin/bash', 10:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:48:45 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:45 INFO - 'USER': 'cltbld', 10:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:45 INFO - 'XPC_FLAGS': '0x0', 10:48:45 INFO - 'XPC_SERVICE_NAME': '0', 10:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:48:46 INFO - Cleaning up... 10:48:46 INFO - Return code: 0 10:48:46 INFO - Running post-action listener: _resource_record_post_action 10:48:46 INFO - Running post-action listener: _start_resource_monitoring 10:48:46 INFO - Starting resource monitoring. 10:48:46 INFO - [mozharness: 2016-08-09 17:48:46.207504Z] Finished create-virtualenv step (success) 10:48:46 INFO - [mozharness: 2016-08-09 17:48:46.208457Z] Running install step. 10:48:46 INFO - Running pre-action listener: _resource_record_pre_action 10:48:46 INFO - Running main action method: install 10:48:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:46 INFO - Reading from file tmpfile_stdout 10:48:46 INFO - Detecting whether we're running mozinstall >=1.0... 10:48:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:48:46 INFO - Reading from file tmpfile_stdout 10:48:46 INFO - Output received: 10:48:46 INFO - Usage: mozinstall [options] installer 10:48:46 INFO - Options: 10:48:46 INFO - -h, --help show this help message and exit 10:48:46 INFO - -d DEST, --destination=DEST 10:48:46 INFO - Directory to install application into. [default: 10:48:46 INFO - "/builds/slave/test"] 10:48:46 INFO - --app=APP Application being installed. [default: firefox] 10:48:46 INFO - mkdir: /builds/slave/test/build/application 10:48:46 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'] 10:48:46 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 10:49:21 INFO - Reading from file tmpfile_stdout 10:49:21 INFO - Output received: 10:49:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:49:21 INFO - Running post-action listener: _resource_record_post_action 10:49:21 INFO - [mozharness: 2016-08-09 17:49:21.059269Z] Finished install step (success) 10:49:21 INFO - [mozharness: 2016-08-09 17:49:21.059413Z] Running run-tests step. 10:49:21 INFO - Running pre-action listener: _resource_record_pre_action 10:49:21 INFO - Running main action method: run_tests 10:49:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:21 INFO - 2016-08-09 10:49:21.072 screenresolution[1646:7493] starting screenresolution argv=screenresolution get 10:49:21 INFO - 2016-08-09 10:49:21.074 screenresolution[1646:7493] Display 0: 1600x1200x32@60 10:49:21 INFO - 2016-08-09 10:49:21.079 screenresolution[1647:7495] starting screenresolution argv=screenresolution list 10:49:21 INFO - Available Modes on Display 0 10:49:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:21 INFO - Intel Iris: 10:49:21 INFO - Chipset Model: Intel Iris 10:49:21 INFO - Type: GPU 10:49:21 INFO - Bus: Built-In 10:49:21 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:21 INFO - Vendor: Intel (0x8086) 10:49:21 INFO - Device ID: 0x0a2e 10:49:21 INFO - Revision ID: 0x0009 10:49:21 INFO - Displays: 10:49:21 INFO - Display: 10:49:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:21 INFO - Display Serial Number: 1600x1200 60 10:49:21 INFO - Main Display: Yes 10:49:21 INFO - Mirror: Off 10:49:21 INFO - Online: Yes 10:49:21 INFO - Rotation: Supported 10:49:21 INFO - Return code: 0 10:49:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:49:21 INFO - Python 2.7.3 10:49:21 INFO - Return code: 0 10:49:21 INFO - grabbing minidump binary from tooltool 10:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:21 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 0x10215cd30>, '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 0x101ec1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102154b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:49:21 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 10:49:21 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 10:49:21 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 10:49:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:49:21 INFO - Return code: 0 10:49:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:49:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:49:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:49:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:49:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:49:21 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/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 10:49:21 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/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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 10:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd3poAWQaA/Render', 10:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:21 INFO - 'HOME': '/Users/cltbld', 10:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:21 INFO - 'LOGNAME': 'cltbld', 10:49:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:49:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:21 INFO - 'MOZ_NO_REMOTE': '1', 10:49:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:49:21 INFO - 'NO_EM_RESTART': '1', 10:49:21 INFO - 'PAGER': '/bin/cat', 10:49:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:21 INFO - 'PWD': '/builds/slave/test', 10:49:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:21 INFO - 'SHELL': '/bin/bash', 10:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2CEEXcm6t/Listeners', 10:49:21 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:21 INFO - 'USER': 'cltbld', 10:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:21 INFO - 'XPC_FLAGS': '0x0', 10:49:21 INFO - 'XPC_SERVICE_NAME': '0', 10:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:21 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/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 10:49:21 INFO - mozversion application_buildid: 20160809084020 10:49:21 INFO - mozversion application_changeset: d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9 10:49:21 INFO - mozversion application_display_name: Nightly 10:49:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:49:21 INFO - mozversion application_name: Firefox 10:49:21 INFO - mozversion application_remotingname: firefox 10:49:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:49:21 INFO - mozversion application_vendor: Mozilla 10:49:21 INFO - mozversion application_version: 51.0a1 10:49:21 INFO - mozversion platform_buildid: 20160809084020 10:49:21 INFO - mozversion platform_changeset: d4b23db9ec3ac94a36e6bd09f15868b4b89e23c9 10:49:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:49:21 INFO - mozversion platform_version: 51.0a1 10:49:21 INFO - using testdate: 1470764961 10:49:21 INFO - actual date: 1470764961 10:49:21 INFO - starting webserver on 'localhost:49273' 10:49:21 INFO - SUITE-START | Running 2 tests 10:49:21 INFO - TEST-START | dromaeo_css 10:49:21 INFO - operating with platform_type : mac_ 10:49:21 INFO - Initialising browser for dromaeo_css test... 10:49:21 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpayNaNW/profile http://localhost:49273/getInfo.html) 10:49:24 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 10:49:24 INFO - PROCESS | 1657 | colorDepth:24 10:49:24 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 10:49:24 INFO - PROCESS | 1657 | __metrics 10:49:24 INFO - PROCESS | 1657 | 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" 10:49:24 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:49:25 INFO - TEST-INFO | 1657: exit 0 10:49:25 INFO - Browser initialized. 10:49:25 INFO - Running cycle 1/1 for dromaeo_css test... 10:49:25 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpayNaNW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:37 INFO - PROCESS | 1659 | RSS: Main: 243445760 10:49:37 INFO - PROCESS | 1659 | 10:50:28 INFO - PROCESS | 1659 | 1470765028869 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] 10:52:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 10:52:42 INFO - PROCESS | 1659 | RSS: Main: 423870464 10:52:42 INFO - PROCESS | 1659 | 10:55:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 10:55:46 INFO - PROCESS | 1659 | RSS: Main: 385306624 10:55:46 INFO - PROCESS | 1659 | 10:58:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 10:58:48 INFO - PROCESS | 1659 | RSS: Main: 353050624 10:58:48 INFO - PROCESS | 1659 | 11:01:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 11:01:51 INFO - PROCESS | 1659 | RSS: Main: 359571456 11:01:51 INFO - PROCESS | 1659 | 11:04:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 11:04:55 INFO - PROCESS | 1659 | RSS: Main: 344326144 11:04:55 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:07:59 INFO - PROCESS | 1659 | RSS: Main: 549838848 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | __start_tp_report 11:07:59 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 11:07:59 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 11:07:59 INFO - PROCESS | 1659 | |i|pagename|runs| 11:07:59 INFO - PROCESS | 1659 | |0;dojo.html;2933.2080338308656;3399.439092549729;3405.3019131078117;3415.4193787056206;3426.725861931046;5599.048161812491;5707.65754054757;5724.227229324045;5758.712064396779;5799.971000144993;5725.169850371693;5730.341010783751;5848.152017957394;5876.294844618641;5885.11723241514;5145.176771716526;5243.056249875021;5243.764030618631;5252.317198764155;5364.812231571896;4473.642108631302;5365.087935051031;5371.892562148755;5393.703346315951;5429.755660995245;5169;5218.895622087575;5225.320708307896;5294.549963253128;5376.38171610262;5262.8684282892855;5264.236685680593;5265.157574788015;5265.315508983846;5267.525922666979;5169.276301317781;5217.452167522393;5219.5041471060185;5230.8692282692855;5296.179092240709;5745.138229265602;5848;5860.912086318709;5865.354810970833;5883.38224486427;6058.727357268934;6330.936690633127;6336.746530138789;6341.778037768656;6360.141380913564;6204.7828326008375;6211.409916057967;6279.246490421133;6290.874182516324;6342.5243106767175;5078.882645817914;5854.2389489366105;5880.705964701748;5901.468867801918;5926.762929482858;6374.84062898422;6394.8721025579225;6398.29618741938;6401.3598640135615;6427.100205971168;5996.580239383288;6053.51571874249;6057.939420605826;6068.787592434243;6092.695365231721;6228.844278892973;6230.937690623126;6231.096491008869;6240.095186198067;6241.001439769706;5132.307138536325;5249.737513124329;6148.508119350441;6246.562740608114;6248.4064013918605;6272.560920735505;6302.810915672537;6316.936830631635;6330.05049242608;6340.936590634127;4916.336294600254;5034.0938631046765;5047.116754567936;5095.490450954875;5117.692938423706;4440.644748420119;4475.5300693426725;4505.842295519641;4542.523035081335;4580.427446569179;5767.106098554731;5784.016717158093;5787;5787;5844.415558444121;6317.3366796486625;6340.9365906340345;6365.8726825464155;6366.427021568081;6389.9680501597195;5643.463870932255;5711.914321285184;5712.914306285409;5755.453231942959;5791.82624521265;5485.6982865942755;5494.478024587658;5495.340559132929;5505.256790333334;5546.112621980464;6360.141380913517;6362.681865906594;6367.904481432783;6373;6374.553781235454;6116.518381401602;6242.687865606792;6278.215223097113;6337.96831015842;6339.619622822645;6352.9047064293145;6406.263279722892;6418.614883106842;6420.903686444613;6423.678816059271;6270.655113968775;6274.341194174728;6295.3704629538515;6314.810555683337;6315.052742088724;6145.4161854623735;6216.347283535164;6240.251169859646;6294.748210071546;6347.238331400262;6113.694315284307;6134.171886795404;6138.508919286357;6142.109394137914;6142.938570614416;6135.1717518136265;6157.6613286269685;6180.907286390618;6246.250449946036;6254.749810007549;6019.465036415686;6061.545384096122;6064.824020680126;6080.620016038326;6081.829337643192;5993.460588547052;6215.471684906834;6232.968835155795;6243.250809902841;6250.624962502265;5769.394213607511;5873.177755114373;5945.821625351246;5958.612690175125;5969.074793407029 11:07:59 INFO - PROCESS | 1659 | |1;ext.html;476.38546275304856;501.1530513432299;523.7538356972223;534.8422215446443;569.0411656359034;30933.690663093363;32787;40552.18895622082;41439.37840932381;41618;36114.45828312571;42911.78544107275;43308.783456082754;43339.916502087464;43509.56490435095;9670.4197748135;9833.606655733762;9841.114299713025;9850.655227067055;9861.753456163617;8707.869381959275;8902.510361930092;11708.063354931584;11920.880791192107;11939.104567157454;11947.820782688266;12015.639530814089;12061;12082.516699332016;12135.635930922042;8658.264047555966;11923.105767067462;11971.940140299286;11990.460429280703;11994.460249288715;11486.253393529394;11785.882141178565;11800.114991375596;12150.210236334698;12170.878291217063;40584.59415405867;40951.7952410239;41037;41050.68967758372;41063.384049239285;3468.843902024415;3504.4918486819524;3519.665631764978;3659.2132691471197;3771.1891943232336;31251.218719531968;31638.146942815652;32320.838395808107;32350.838245808856;32383.676163238302;3190.2502911815936;3329.400707872582;3334.883279085245;3346;3368.949465758016;504847;504958;507532;507962.92037079524;508260;60002.39997600055;60819.78360432855;61166;61248.38751612427;61270.69364653148;65220.673896630215;66051;66100.33899661039;66109;66147;15990.280437380346;16131.919340403458;16162;16170.27233774483;16202.351905923866;11372.20394572372;11592.072634189413;12504.562340318227;12542.24746515212;12558.623241302776;17184.656306874043;17478.912605437145;17576.560585985197;17725.379611713528;17732.93602383861;5433.510984011458;6371.649559274284;6439.162908821917;6517.608943463408;6553.148090748172;888.8622263549031;891.4027601507006;1018.4144117132678;1064.920130990179;1081.534939975818;29708.55437168444;30267.545986810215;31207.063788086394;31242.37515249682;31427.685723142476;1081.324172392255;1145.5131569083105;1146.5585749486509;1157.8610566732045;1230.2065167966612;15740.606484837741;15797.526074217792;15857.286422111032;16180.919095404448;16246.350145994264;652489;659676;664794;666476;666562.3422009184;59350.70324648349;59939;59972.700136499036;60034.69982650146;60060.39939600635;1251.7559075980096;1290.5934630591335;1351.202790353696;1389.3608939887765;1405.8523854995283;30128.3974320508;30921;31695.52456713197;32528.674713252563;32669.019929402155;54162.458375417315;57485.425145748006;57546.42453575411;57557.42442575688;57572.71213643905;588973;594164;594738;595836;596567;26696.599551006362;26946.056888008825;27275.454490910466;40772.96135193169;41124;47852;48348;48780.2682959763;49546.00907981748;49627.25559116544;39027.77986072586;39277.625283114954;39321.60678393179;40510.59489405068;40606.79696601498;30726.385472290876;38411.80794096012;38990.41514377343;39218.80390598029;40495.392569112075;37922;38566;38787.61212387953;38898;38929.80535097307;28571.571426428203;29308.120756377342;32964.67035329712;35128.648713512535;35350.050992861536;25338.74661253364;25589.488210236064;25914.087006666345;25928.254895650683;25930.22209333723 11:07:59 INFO - PROCESS | 1659 | |2;jquery.html;351.6747009016659;370.47207728986194;371.76578755384105;376.85491085931915;399.50261923904725;15450.69098618027;16332.510024699248;20381.694274585858;20854.68717969228;21007.47481312968;20657.896710516427;20886.582268354625;21099.367018989455;21250.787492125073;21526.246581369654;5770.509506691915;5997.940020599793;6072.757089716428;6253.968730156365;6254.499640028786;6105.81682549522;6142.539309551801;6143.7235324410285;6162.414570615807;6165.352637972993;3875.5736868944396;4007.4071930788973;4081.244969680604;4293.914121717564;6779.932200678004;6722.226943901441;6730.730770769163;6731.899021514681;6738.494612904052;6739.393454589062;5192.688438693691;5478.123500239983;5511.669299842022;5536.363318218376;5541.168824676291;12805.295708736016;12815.871841281562;12904.161229520145;12937.094403391766;13094.017948653795;2195.1658369819365;2201.570693714726;2312.7687231276736;2362.783166669167;2478.6529885816;9784.55969481375;9847.704568862946;9868.50657467131;10384.117350025257;10388;1812.764340635709;1815.1650240889207;1826;1851.574137948266;1865.8507319414414;115073.42463287714;121069;124785.37607311906;126356.36821815924;127363;9060.95469522648;11400.061989461812;11571.826422603499;11718.765624687458;11742.88257117418;14418.206998615176;14430.494932677306;14447.205403702897;14450.060746051473;14464.27678616065;8294.253517183482;8390.077091519928;8420.284275836622;8420.410571260076;8631.956840215758;8317.459365141247;8366.330693544502;8483.618237179342;8508.596081646583;8561.914380856111;3776.9244615107546;4399;4481.574250446202;4484.932726009112;4574.74838883858;1858.6840237159715;1908.6850669639632;1920.2511020701938;1958.265650381107;1978.910949007298;836.9748907532784;893.9418937769025;917.4907926100944;952.1906379577304;970.1414248390084;20924.895375523025;21683;21784.34646960594;21813.563728725338;21856.78143218579;890.7372325163963;905.2893478619252;914.9085091490798;918.8392031394479;935.8923723771853;10456.581736730597;10940.85121062293;11638.825417618742;11648.592299269485;11723.76552468946;598551;601785;602831;621856;626194;132058;132467.67532324552;134521;135297.32351338377;140932;946.6592026870321;950.2255661635745;966.3332300712486;966.777641142527;994.2493417469908;18847.90576047111;19511.707324389863;19515.707264390763;19539.609207816047;20592.69110963307;37099;56432.71783641056;56905;57923.13115303358;58067.128993064296;77913.61043194748;78504.21495784969;82908.58545707233;83666.16333836828;84144;4606.848287928018;5264.710440925785;7861.2531809478005;7981.521108733495;8462.873056904275;10348.637797677045;12741.171823831792;13100.803487947498;13632.182069075887;13684.452621895012;11218.887811122111;11370.54517819278;11494.850514948439;11717.06263498901;11903.642890713292;10697.786044279226;11319.377434241196;11496.597619083292;11816.940915295369;11888.702782430162;11284.943575282072;11598.246114002563;11833.290002599872;12007.159498834997;12046.096542759504;8067.596620169085;8103.635336409993;8419.410641255054;8473.788155296166;8477.279431248413;10508.369497830156;11405.600803972153;11713.355765432641;11734.530618775154;11789.646310610353 11:07:59 INFO - PROCESS | 1659 | |3;mootools.html;1376.4700590272741;1533.8849586281024;1568.435363269223;1770.2211027148062;1917.261854186138;7794.298513133816;8593.48439093654;8829.779255518615;8853.645854165825;8939.821203575924;8715.607797649105;8819.470831750099;8854.778630534223;8857.025727170007;8857.51283679399;2327.0691723310683;2354.5637161331574;2355.4229213842546;2356.057576969209;2357.422431504284;2300.493891343902;2334.4630734930893;2347.295811256625;2348.3189874936247;2349.001674288429;2310.745817960023;2323.94190145246;2331.6385960176117;2339.076064954342;2340.063974410241;2290.404494831339;2312.190733243368;2321.3616255529696;2321.5472982768283;2334;2284.9428764281024;2323.0591610397814;2327.8603283803027;2329.7670232976734;2332.207049603126;8595.269402100828;8796.384253102286;8867.113288671146;8871.112888711143;8877.334199934967;5168.457311982225;5333.626646134772;5378.946210537883;5382.03123437785;5393.541548968343;3445.603755568117;3570.8571657133684;3645.434957581579;3657.3233951719017;3664.5602527696847;1062.2404980439023;1067.4555976452066;1069.7379142110155;1089.319175515303;1091.7871015152048;11425.800290969411;11488.36813975231;11617.709557261136;11631.302121872715;11635.069194464422;4572.885677858014;4631.235846085358;4807.062622788522;4826.686265392738;4827.300041493963;10064.245181611408;10074.546645400975;10083.294169408071;10090.44502552352;10118.595256189818;5364.034473794676;5365.114756065205;5365.302510673588;5366.195070739421;5366.892662146735;4382.802773875183;4539.432570928634;4707.199776038082;4815.879603009953;4905.166121759309;3448.189675426273;3908.2965066288343;3910.354791459434;4041.393790931325;4058;1250.8373911391461;1260.1619922751452;1261.6025951825145;1274.815151803002;1279.0918447901909;791.841631673656;883.2668884825707;887.9067697891758;909.9226565741853;933.3793027636546;4387.780610969438;5680.829575112753;5929.229200204032;5945.672987985702;6085.330613632497;424.8661671573444;435.1992334105262;435.4578549705637;437.89271628451235;440.9757463339547;3903.6877049835944;3915.5105611798526;4012;4024.2353952748886;4030.0730831908827;10041;10395.84406233907;10578.418186999788;10707.571697132184;10713.196510261761;10643.94678026605;10646.680599582025;10663.413512256746;10673.252872298865;10678.946605266923;8012.759617211493;8075.676972921135;8127.715529956423;8244.381671374791;8258.339332853357;5002;5667.093265077733;5675.716214189357;5775.971120144372;5857.765689372548;9089.227415669742;9833.754156146153;9892.653757118467;9899.95050024774;10029.348092373972;9879.06148915852;9955.50222488887;10041.246906481896;10191.84712229332;10297.433641149808;6973.965130174317;7199.388052015637;7393.038904942323;7588.013558237394;7662.157162711986;8335.166483351859;8472.3645726572;8523.659053637784;8540.70107546233;8551.187637174458;7335.303146201102;7445.9627701861145;7541.811454713676;7630.580318082337;7698;7263.400564864765;7339.8532029360185;7374.078240219973;7531.322181003517;7586.696532138873;6860.622665753432;7018.929810701828;7022.473314501535;7034.331738484836;7042.718291268292;4923.778429970588;4943.65394422387;5070.619703522177;5084.872878178076;5139.408967968582;3853.3256680081436;4436.689431739747;4612.262038073892;4624.121416930772;4742.88142796419 11:07:59 INFO - PROCESS | 1659 | |4;prototype.html;277.74031280752496;294.6832155432909;295.93045634275944;305.93077195202767;311.5560326534688;5350.625456218061;5367.570594352453;5391.703456309906;5456.91814622781;5477.370102438221;4600.941783389822;4982.053409852126;5206.7656955437005;5220.4779522047775;5243;3403.2512847173584;3915.9804200978956;4008.0781420273356;4014.498187726534;4048.4332193492933;4059.4722686050773;4074;4117.814698338567;4119.8352065917325;4145.5647157048525;3839.4240863870505;4044.534878488982;4066.5323487798837;4094.7338423002557;4152.875413737593;4075.0219947212754;4083.8774836754797;4086.223617512663;4100.835966561334;4127.318992366256;3281.8011932005616;3318.7013168814924;3567.3935430976794;3585.8565657373674;3593.694535964446;4829.9275510867365;5258.947410525884;5269;5290.4974027467715;5305.575553955674;3921.9213816677207;4280.1653677532895;4595.264757638762;4608.308753686973;4680.180968330528;5379.569634429236;5403.51368376844;5598;5609;5618.297712785902;3328.6837750413915;3931.842726290945;3937.6849852012056;3937.9212415751526;3985.202959408101;5404.946035523075;5808.547863034241;5853.736581853785;5888.793892213752;5903.79336723217;3811.4282857571584;3846.2692088503095;3852.3049858062363;3853.5953724859046;3872.9612703873163;3497.2480916603054;3823.5411750590106;3924.4505769192338;3933.3903244997073;3935.7244992850797;3853.4797802296894;3934.3311637021775;4349.999500114991;4447.488538818013;4552.839026048337;4643.303504474356;5463.644863083887;5540.889182216333;5597.160425936061;5644.887102258014;1151.3379806611233;1314.7962065879804;1389.5483967710331;1394.2122700674088;1398.6013986013936;802.7832485228955;856.39624065034;893.8972018217858;917.6925729880431;946.5551190940247;4356.803943822472;4852.514748525119;5214.687118772886;5257.605679574047;5263.894442223065;5044.848654540369;5690.317161940511;5695.572832037614;5695.886082278331;5729.226554415184;4400.251556132192;5154.30416893722;5183.98912212115;5232.090287045201;5345.786168553292;2900.94115647787;2976.3154474470994;3018.607581014454;3021.2204771610172;3043.25440267136;4953.291114565469;5258.553022993012;5529.225908372831;5627.43725627434;5871.500922421556;4418.823247070145;4909.239067944474;5580.832575022756;5724.16999535065;5833.741368800799;5302.655327403706;5398.462722520409;5822.621529600562;5848.678322692208;5855.268091488564;5479.287692600096;5648.15277708347;5658.85852853682;5679.261695979496;5697.202391665253;4755.310479980314;5279.9472005280995;5306.6550674206055;5582.86042848932;5594.272744543184;5174.14626586609;5832.3292821326095;5839.766409343579;5849.853753656193;5888.705564721832;3364.9327013459106;3673.687736542424;3966.6231707987695;3995.92008159841;4004.699647526389;4750.643701722453;4766.117383633376;4925.827596034265;5671.262735844314;5709.800156994485;5261.552768014609;5590.832275031756;5609.775608975595;5613.635113717595;5666.518345940642;5086.872828179326;5461.453854614507;5514.944850551604;5531.621402796836;5547.528460080777;4757.549861821096;5160.394001029456;5380.973095134499;5420.945790542044;5460.399356070909;4913.582345500529;5037.244413338029;5324.622131646987;5573.024720673785;5606.495415412633;4660.7669616519715;4953.23224900141;5527.226188333717;5539.476643922792;5559.6664200148125 11:07:59 INFO - PROCESS | 1659 | |5;yui.html;719.2160545005943;725.7967769024668;756.3117563017655;764.6711913877034;806.8184658451847;15488;16589;16598.585035374123;16613.086280254596;16645.084520351375;14384.496542621011;14492;14498.637534061654;14508.782368264458;14526.854731452682;673.737242475435;695.0720787748362;830.7466222802058;834.3533761334953;859.5358506406533;835.4987007795305;848.5333066813259;849.218718778725;849.7705619482736;851.1658574596898;900.1133883125149;900.5902314446911;901.7204666553357;902.3367824648877;903.4804987132393;600.432591201315;877.2850127146355;896.7264984179823;899.7930475990559;904.8371293167228;665.2449469851705;665.5208250059998;665.7303791964263;665.866826634675;667.302668711198;14698.55904322872;14744.778828317583;14766.778498322534;14771;14776.778348324784;13676.31618419085;13915.652108697263;14060.296985150702;14069.577912662637;14094.365753541115;12732.618021459279;12761.744765104739;12879;13070;13095.345232738326;4545.09098180362;4599.126166028444;4705.811767529295;4767.76161191939;4776.140294746944;114587.28119078137;117028;118141.40929295385;124593;125527.99999999908;10965.067969222706;11075.944620277009;11080.113590912708;11430.25703329281;11440.256383335223;21920.671189932164;21924.671129932743;21925.23261685865;21927.342179734635;21928.671069933964;7871.757724900285;7880.881786773203;7952.1650226726515;8076.071251806117;8147.063087744985;4381.18947994622;4437.095356086623;4481.5070342262325;4526.140033393644;4655.4180727409075;3855.8650447234213;4079.347304431277;4090.897727556835;4096.692748043849;4107.178564287155;601.5157797972674;684.8356394465293;715.3883429667663;730.521508411991;733.252082875464;598.7844376024627;625.5589809184514;650.3853858104046;661.2527843536773;687.669918439152;8964.238039766562;9260.907390926139;9520.381175223589;9597.760055998517;9758.219342452587;539.4309004000817;577.3043482603453;591.4115455122181;690.4028015766326;699.3111784891878;3802.866899658499;3899.1616802387057;4443.577860103285;4728.40894888139;4829.782659780319;157077;161294;162848;164680;165484;137550;140004;140975;141001;142475.86286205714;91502.2223111043;102293.48853255835;108456.4577177109;115549.26676099865;117955;8870.556472176495;9426.764330891783;9672.85490717654;9769.218462523124;9878.703638890845;62687.6865615669;63317.68341158265;63364;64664;65349.346506534326;75675;92339.69150771285;96167;99924.50037749765;101759;13389.263590502616;13413.329333533089;13419;13491.05562610608;13691.520796772065;14999.925120182386;15007.849921501082;15269.236538173269;15367.910384571305;15472.381104755683;14013.649658758613;14159.929200354345;14186.219757913414;14407.351669174703;14658;13967;14167.64580885486;14193.858061419254;14418.639534011734;14631.92684036573;13571.93214033963;13818.378172982038;13955.651108722363;14277.357518911465;14384.42462301538;10488.423136727553;11289.604863829727;11376.943115284701;11377.317360958368;12099.67259003781;9109;10300.278980471594;11377.658670239905;11611.419429028683;11647.184697071123 11:07:59 INFO - PROCESS | 1659 | __end_tp_report 11:07:59 INFO - PROCESS | 1659 | __start_cc_report 11:07:59 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1826 11:07:59 INFO - PROCESS | 1659 | __end_cc_report 11:07:59 INFO - PROCESS | 1659 | __startTimestamp1470766079410__endTimestamp 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | ------- Summary: start ------- 11:07:59 INFO - PROCESS | 1659 | Number of tests: 6 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5786.87 Median:5993.46 stddev:657.34 (11.0%) stddev-sans-first:620.07 11:07:59 INFO - PROCESS | 1659 | Values: 2933.2 3399.4 3405.3 3415.4 3426.7 5599.0 5707.7 5724.2 5758.7 5800.0 5725.2 5730.3 5848.2 5876.3 5885.1 5145.2 5243.1 5243.8 5252.3 5364.8 4473.6 5365.1 5371.9 5393.7 5429.8 5169.0 5218.9 5225.3 5294.5 5376.4 5262.9 5264.2 5265.2 5265.3 5267.5 5169.3 5217.5 5219.5 5230.9 5296.2 5745.1 5848.0 5860.9 5865.4 5883.4 6058.7 6330.9 6336.7 6341.8 6360.1 6204.8 6211.4 6279.2 6290.9 6342.5 5078.9 5854.2 5880.7 5901.5 5926.8 6374.8 6394.9 6398.3 6401.4 6427.1 5996.6 6053.5 6057.9 6068.8 6092.7 6228.8 6230.9 6231.1 6240.1 6241.0 5132.3 5249.7 6148.5 6246.6 6248.4 6272.6 6302.8 6316.9 6330.1 6340.9 4916.3 5034.1 5047.1 5095.5 5117.7 4440.6 4475.5 4505.8 4542.5 4580.4 5767.1 5784.0 5787.0 5787.0 5844.4 6317.3 6340.9 6365.9 6366.4 6390.0 5643.5 5711.9 5712.9 5755.5 5791.8 5485.7 5494.5 5495.3 5505.3 5546.1 6360.1 6362.7 6367.9 6373.0 6374.6 6116.5 6242.7 6278.2 6338.0 6339.6 6352.9 6406.3 6418.6 6420.9 6423.7 6270.7 6274.3 6295.4 6314.8 6315.1 6145.4 6216.3 6240.3 6294.7 6347.2 6113.7 6134.2 6138.5 6142.1 6142.9 6135.2 6157.7 6180.9 6246.3 6254.7 6019.5 6061.5 6064.8 6080.6 6081.8 5993.5 6215.5 6233.0 6243.3 6250.6 5769.4 5873.2 5945.8 5958.6 5969.1 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:72523.58 Median:3715.20 stddev:157907.23 (4250.3%) stddev-sans-first:158257.54 11:07:59 INFO - PROCESS | 1659 | Values: 476.4 501.2 523.8 534.8 569.0 30933.7 32787.0 40552.2 41439.4 41618.0 36114.5 42911.8 43308.8 43339.9 43509.6 9670.4 9833.6 9841.1 9850.7 9861.8 8707.9 8902.5 11708.1 11920.9 11939.1 11947.8 12015.6 12061.0 12082.5 12135.6 8658.3 11923.1 11971.9 11990.5 11994.5 11486.3 11785.9 11800.1 12150.2 12170.9 40584.6 40951.8 41037.0 41050.7 41063.4 3468.8 3504.5 3519.7 3659.2 3771.2 31251.2 31638.1 32320.8 32350.8 32383.7 3190.3 3329.4 3334.9 3346.0 3368.9 504847.0 504958.0 507532.0 507962.9 508260.0 60002.4 60819.8 61166.0 61248.4 61270.7 65220.7 66051.0 66100.3 66109.0 66147.0 15990.3 16131.9 16162.0 16170.3 16202.4 11372.2 11592.1 12504.6 12542.2 12558.6 17184.7 17478.9 17576.6 17725.4 17732.9 5433.5 6371.6 6439.2 6517.6 6553.1 888.9 891.4 1018.4 1064.9 1081.5 29708.6 30267.5 31207.1 31242.4 31427.7 1081.3 1145.5 1146.6 1157.9 1230.2 15740.6 15797.5 15857.3 16180.9 16246.4 652489.0 659676.0 664794.0 666476.0 666562.3 59350.7 59939.0 59972.7 60034.7 60060.4 1251.8 1290.6 1351.2 1389.4 1405.9 30128.4 30921.0 31695.5 32528.7 32669.0 54162.5 57485.4 57546.4 57557.4 57572.7 588973.0 594164.0 594738.0 595836.0 596567.0 26696.6 26946.1 27275.5 40773.0 41124.0 47852.0 48348.0 48780.3 49546.0 49627.3 39027.8 39277.6 39321.6 40510.6 40606.8 30726.4 38411.8 38990.4 39218.8 40495.4 37922.0 38566.0 38787.6 38898.0 38929.8 28571.6 29308.1 32964.7 35128.6 35350.1 25338.7 25589.5 25914.1 25928.3 25930.2 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35480.87 Median:1415.16 stddev:102166.36 (7219.4%) stddev-sans-first:102418.91 11:07:59 INFO - PROCESS | 1659 | Values: 351.7 370.5 371.8 376.9 399.5 15450.7 16332.5 20381.7 20854.7 21007.5 20657.9 20886.6 21099.4 21250.8 21526.2 5770.5 5997.9 6072.8 6254.0 6254.5 6105.8 6142.5 6143.7 6162.4 6165.4 3875.6 4007.4 4081.2 4293.9 6779.9 6722.2 6730.7 6731.9 6738.5 6739.4 5192.7 5478.1 5511.7 5536.4 5541.2 12805.3 12815.9 12904.2 12937.1 13094.0 2195.2 2201.6 2312.8 2362.8 2478.7 9784.6 9847.7 9868.5 10384.1 10388.0 1812.8 1815.2 1826.0 1851.6 1865.9 115073.4 121069.0 124785.4 126356.4 127363.0 9061.0 11400.1 11571.8 11718.8 11742.9 14418.2 14430.5 14447.2 14450.1 14464.3 8294.3 8390.1 8420.3 8420.4 8632.0 8317.5 8366.3 8483.6 8508.6 8561.9 3776.9 4399.0 4481.6 4484.9 4574.7 1858.7 1908.7 1920.3 1958.3 1978.9 837.0 893.9 917.5 952.2 970.1 20924.9 21683.0 21784.3 21813.6 21856.8 890.7 905.3 914.9 918.8 935.9 10456.6 10940.9 11638.8 11648.6 11723.8 598551.0 601785.0 602831.0 621856.0 626194.0 132058.0 132467.7 134521.0 135297.3 140932.0 946.7 950.2 966.3 966.8 994.2 18847.9 19511.7 19515.7 19539.6 20592.7 37099.0 56432.7 56905.0 57923.1 58067.1 77913.6 78504.2 82908.6 83666.2 84144.0 4606.8 5264.7 7861.3 7981.5 8462.9 10348.6 12741.2 13100.8 13632.2 13684.5 11218.9 11370.5 11494.9 11717.1 11903.6 10697.8 11319.4 11496.6 11816.9 11888.7 11284.9 11598.2 11833.3 12007.2 12046.1 8067.6 8103.6 8419.4 8473.8 8477.3 10508.4 11405.6 11713.4 11734.5 11789.6 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5665.39 Median:4618.19 stddev:3216.54 (69.6%) stddev-sans-first:3209.42 11:07:59 INFO - PROCESS | 1659 | Values: 1376.5 1533.9 1568.4 1770.2 1917.3 7794.3 8593.5 8829.8 8853.6 8939.8 8715.6 8819.5 8854.8 8857.0 8857.5 2327.1 2354.6 2355.4 2356.1 2357.4 2300.5 2334.5 2347.3 2348.3 2349.0 2310.7 2323.9 2331.6 2339.1 2340.1 2290.4 2312.2 2321.4 2321.5 2334.0 2284.9 2323.1 2327.9 2329.8 2332.2 8595.3 8796.4 8867.1 8871.1 8877.3 5168.5 5333.6 5378.9 5382.0 5393.5 3445.6 3570.9 3645.4 3657.3 3664.6 1062.2 1067.5 1069.7 1089.3 1091.8 11425.8 11488.4 11617.7 11631.3 11635.1 4572.9 4631.2 4807.1 4826.7 4827.3 10064.2 10074.5 10083.3 10090.4 10118.6 5364.0 5365.1 5365.3 5366.2 5366.9 4382.8 4539.4 4707.2 4815.9 4905.2 3448.2 3908.3 3910.4 4041.4 4058.0 1250.8 1260.2 1261.6 1274.8 1279.1 791.8 883.3 887.9 909.9 933.4 4387.8 5680.8 5929.2 5945.7 6085.3 424.9 435.2 435.5 437.9 441.0 3903.7 3915.5 4012.0 4024.2 4030.1 10041.0 10395.8 10578.4 10707.6 10713.2 10643.9 10646.7 10663.4 10673.3 10678.9 8012.8 8075.7 8127.7 8244.4 8258.3 5002.0 5667.1 5675.7 5776.0 5857.8 9089.2 9833.8 9892.7 9900.0 10029.3 9879.1 9955.5 10041.2 10191.8 10297.4 6974.0 7199.4 7393.0 7588.0 7662.2 8335.2 8472.4 8523.7 8540.7 8551.2 7335.3 7446.0 7541.8 7630.6 7698.0 7263.4 7339.9 7374.1 7531.3 7586.7 6860.6 7018.9 7022.5 7034.3 7042.7 4923.8 4943.7 5070.6 5084.9 5139.4 3853.3 4436.7 4612.3 4624.1 4742.9 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4498.07 Median:5120.59 stddev:1360.21 (26.6%) stddev-sans-first:1326.63 11:07:59 INFO - PROCESS | 1659 | Values: 277.7 294.7 295.9 305.9 311.6 5350.6 5367.6 5391.7 5456.9 5477.4 4600.9 4982.1 5206.8 5220.5 5243.0 3403.3 3916.0 4008.1 4014.5 4048.4 4059.5 4074.0 4117.8 4119.8 4145.6 3839.4 4044.5 4066.5 4094.7 4152.9 4075.0 4083.9 4086.2 4100.8 4127.3 3281.8 3318.7 3567.4 3585.9 3593.7 4829.9 5258.9 5269.0 5290.5 5305.6 3921.9 4280.2 4595.3 4608.3 4680.2 5379.6 5403.5 5598.0 5609.0 5618.3 3328.7 3931.8 3937.7 3937.9 3985.2 5404.9 5808.5 5853.7 5888.8 5903.8 3811.4 3846.3 3852.3 3853.6 3873.0 3497.2 3823.5 3924.5 3933.4 3935.7 3853.5 3934.3 4350.0 4447.5 4552.8 4643.3 5463.6 5540.9 5597.2 5644.9 1151.3 1314.8 1389.5 1394.2 1398.6 802.8 856.4 893.9 917.7 946.6 4356.8 4852.5 5214.7 5257.6 5263.9 5044.8 5690.3 5695.6 5695.9 5729.2 4400.3 5154.3 5184.0 5232.1 5345.8 2900.9 2976.3 3018.6 3021.2 3043.3 4953.3 5258.6 5529.2 5627.4 5871.5 4418.8 4909.2 5580.8 5724.2 5833.7 5302.7 5398.5 5822.6 5848.7 5855.3 5479.3 5648.2 5658.9 5679.3 5697.2 4755.3 5279.9 5306.7 5582.9 5594.3 5174.1 5832.3 5839.8 5849.9 5888.7 3364.9 3673.7 3966.6 3995.9 4004.7 4750.6 4766.1 4925.8 5671.3 5709.8 5261.6 5590.8 5609.8 5613.6 5666.5 5086.9 5461.5 5514.9 5531.6 5547.5 4757.5 5160.4 5381.0 5420.9 5460.4 4913.6 5037.2 5324.6 5573.0 5606.5 4660.8 4953.2 5527.2 5539.5 5559.7 11:07:59 INFO - PROCESS | 1659 | 11:07:59 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:26030.66 Median:3877.51 stddev:42199.75 (1088.3%) stddev-sans-first:42275.34 11:07:59 INFO - PROCESS | 1659 | Values: 719.2 725.8 756.3 764.7 806.8 15488.0 16589.0 16598.6 16613.1 16645.1 14384.5 14492.0 14498.6 14508.8 14526.9 673.7 695.1 830.7 834.4 859.5 835.5 848.5 849.2 849.8 851.2 900.1 900.6 901.7 902.3 903.5 600.4 877.3 896.7 899.8 904.8 665.2 665.5 665.7 665.9 667.3 14698.6 14744.8 14766.8 14771.0 14776.8 13676.3 13915.7 14060.3 14069.6 14094.4 12732.6 12761.7 12879.0 13070.0 13095.3 4545.1 4599.1 4705.8 4767.8 4776.1 114587.3 117028.0 118141.4 124593.0 125528.0 10965.1 11075.9 11080.1 11430.3 11440.3 21920.7 21924.7 21925.2 21927.3 21928.7 7871.8 7880.9 7952.2 8076.1 8147.1 4381.2 4437.1 4481.5 4526.1 4655.4 3855.9 4079.3 4090.9 4096.7 4107.2 601.5 684.8 715.4 730.5 733.3 598.8 625.6 650.4 661.3 687.7 8964.2 9260.9 9520.4 9597.8 9758.2 539.4 577.3 591.4 690.4 699.3 3802.9 3899.2 4443.6 4728.4 4829.8 157077.0 161294.0 162848.0 164680.0 165484.0 137550.0 140004.0 140975.0 141001.0 142475.9 91502.2 102293.5 108456.5 115549.3 117955.0 8870.6 9426.8 9672.9 9769.2 9878.7 62687.7 63317.7 63364.0 64664.0 65349.3 75675.0 92339.7 96167.0 99924.5 101759.0 13389.3 13413.3 13419.0 13491.1 13691.5 14999.9 15007.8 15269.2 15367.9 15472.4 14013.6 14159.9 14186.2 14407.4 14658.0 13967.0 14167.6 14193.9 14418.6 14631.9 13571.9 13818.4 13955.7 14277.4 14384.4 10488.4 11289.6 11376.9 11377.3 12099.7 9109.0 10300.3 11377.7 11611.4 11647.2 11:07:59 INFO - PROCESS | 1659 | -------- Summary: end -------- 11:07:59 INFO - PROCESS | 1659 | 11:08:00 INFO - TEST-INFO | 1659: exit 0 11:08:00 INFO - TEST-OK | dromaeo_css | took 1118468ms 11:08:00 INFO - TEST-START | kraken 11:08:00 INFO - operating with platform_type : mac_ 11:08:00 INFO - Initialising browser for kraken test... 11:08:00 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpdSxyX0/profile http://localhost:49273/getInfo.html) 11:08:02 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 11:08:02 INFO - PROCESS | 1669 | colorDepth:24 11:08:02 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 11:08:02 INFO - PROCESS | 1669 | __metrics 11:08:02 INFO - PROCESS | 1669 | 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" 11:08:02 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:08:03 INFO - TEST-INFO | 1669: exit 0 11:08:03 INFO - Browser initialized. 11:08:03 INFO - Running cycle 1/1 for kraken test... 11:08:03 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpdSxyX0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:15 INFO - PROCESS | 1670 | RSS: Main: 243171328 11:08:15 INFO - PROCESS | 1670 | 11:08:17 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:08:18 INFO - PROCESS | 1670 | RSS: Main: 292843520 11:08:18 INFO - PROCESS | 1670 | 11:08:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:08:22 INFO - PROCESS | 1670 | RSS: Main: 299245568 11:08:22 INFO - PROCESS | 1670 | 11:08:25 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:08:26 INFO - PROCESS | 1670 | RSS: Main: 395247616 11:08:26 INFO - PROCESS | 1670 | 11:08:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:08:28 INFO - PROCESS | 1670 | RSS: Main: 296767488 11:08:28 INFO - PROCESS | 1670 | 11:08:30 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:08:31 INFO - PROCESS | 1670 | RSS: Main: 301023232 11:08:31 INFO - PROCESS | 1670 | 11:08:34 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:08:34 INFO - PROCESS | 1670 | RSS: Main: 338382848 11:08:34 INFO - PROCESS | 1670 | 11:08:37 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:08:38 INFO - PROCESS | 1670 | RSS: Main: 340238336 11:08:38 INFO - PROCESS | 1670 | 11:08:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:08:41 INFO - PROCESS | 1670 | RSS: Main: 341254144 11:08:41 INFO - PROCESS | 1670 | 11:08:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:08:43 INFO - PROCESS | 1670 | RSS: Main: 313475072 11:08:43 INFO - PROCESS | 1670 | 11:08:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:08:46 INFO - PROCESS | 1670 | RSS: Main: 320561152 11:08:46 INFO - PROCESS | 1670 | 11:08:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:08:49 INFO - PROCESS | 1670 | RSS: Main: 324751360 11:08:49 INFO - PROCESS | 1670 | 11:08:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:08:52 INFO - PROCESS | 1670 | RSS: Main: 329781248 11:08:52 INFO - PROCESS | 1670 | 11:08:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:08:56 INFO - PROCESS | 1670 | RSS: Main: 331116544 11:08:56 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:08:58 INFO - PROCESS | 1670 | RSS: Main: 374562816 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | __start_tp_report 11:08:58 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 11:08:58 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 11:08:58 INFO - PROCESS | 1670 | |i|pagename|runs| 11:08:58 INFO - PROCESS | 1670 | |0;ai-astar;103;115;113;114;113;114;114;117;131;114 11:08:58 INFO - PROCESS | 1670 | |1;audio-beat-detection;143;107;107;164;107;107;129;104;141;109 11:08:58 INFO - PROCESS | 1670 | |2;audio-dft;187;184;187;186;184;185;180;183;183;186 11:08:58 INFO - PROCESS | 1670 | |3;audio-fft;82;57;59;87;57;58;59;67;84;56 11:08:58 INFO - PROCESS | 1670 | |4;audio-oscillator;85;68;98;69;81;68;96;69;67;101 11:08:58 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;89;85;90;88;89;86;89;86;92;130 11:08:58 INFO - PROCESS | 1670 | |6;imaging-darkroom;94;94;96;95;95;94;95;103;133;95 11:08:58 INFO - PROCESS | 1670 | |7;imaging-desaturate;85;87;86;85;86;86;85;87;84;86 11:08:58 INFO - PROCESS | 1670 | |8;json-parse-financial;54;49;68;74;43;44;52;67;63;53 11:08:58 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;66;68;72;80;75;72;68;73;71;70 11:08:58 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;84;83;83;86;84;83;85;81;87;86 11:08:58 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;145;156;141;130;143;149;139;139;155;138 11:08:58 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;166;165;165;165;164;167;227;227;164;165 11:08:58 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;66;67;68;68;66;66;66;66;66;65 11:08:58 INFO - PROCESS | 1670 | __end_tp_report 11:08:58 INFO - PROCESS | 1670 | __start_cc_report 11:08:58 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2033 11:08:58 INFO - PROCESS | 1670 | __end_cc_report 11:08:58 INFO - PROCESS | 1670 | __startTimestamp1470766138408__endTimestamp 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | ------- Summary: start ------- 11:08:58 INFO - PROCESS | 1670 | Number of tests: 14 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:114.80 Median:114.00 stddev:6.80 (6.0%) stddev-sans-first:5.71 11:08:58 INFO - PROCESS | 1670 | Values: 103.0 115.0 113.0 114.0 113.0 114.0 114.0 117.0 131.0 114.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:119.00 stddev:21.10 (17.7%) stddev-sans-first:20.94 11:08:58 INFO - PROCESS | 1670 | Values: 143.0 107.0 107.0 164.0 107.0 107.0 129.0 104.0 141.0 109.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.50 stddev:2.17 (1.2%) stddev-sans-first:2.11 11:08:58 INFO - PROCESS | 1670 | Values: 187.0 184.0 187.0 186.0 184.0 185.0 180.0 183.0 183.0 186.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:66.60 Median:63.00 stddev:12.66 (20.1%) stddev-sans-first:12.14 11:08:58 INFO - PROCESS | 1670 | Values: 82.0 57.0 59.0 87.0 57.0 58.0 59.0 67.0 84.0 56.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:75.00 stddev:13.93 (18.6%) stddev-sans-first:14.66 11:08:58 INFO - PROCESS | 1670 | Values: 85.0 68.0 98.0 69.0 81.0 68.0 96.0 69.0 67.0 101.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:89.00 stddev:13.38 (15.0%) stddev-sans-first:14.13 11:08:58 INFO - PROCESS | 1670 | Values: 89.0 85.0 90.0 88.0 89.0 86.0 89.0 86.0 92.0 130.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:95.00 stddev:12.10 (12.7%) stddev-sans-first:12.68 11:08:58 INFO - PROCESS | 1670 | Values: 94.0 94.0 96.0 95.0 95.0 94.0 95.0 103.0 133.0 95.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:0.95 (1.1%) stddev-sans-first:0.97 11:08:58 INFO - PROCESS | 1670 | Values: 85.0 87.0 86.0 85.0 86.0 86.0 85.0 87.0 84.0 86.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:58.50 stddev:10.67 (18.2%) stddev-sans-first:11.27 11:08:58 INFO - PROCESS | 1670 | Values: 54.0 49.0 68.0 74.0 43.0 44.0 52.0 67.0 63.0 53.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:4.01 (5.6%) stddev-sans-first:3.72 11:08:58 INFO - PROCESS | 1670 | Values: 66.0 68.0 72.0 80.0 75.0 72.0 68.0 73.0 71.0 70.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:1.81 (2.1%) stddev-sans-first:1.92 11:08:58 INFO - PROCESS | 1670 | Values: 84.0 83.0 83.0 86.0 84.0 83.0 85.0 81.0 87.0 86.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:144.00 stddev:8.03 (5.6%) stddev-sans-first:8.50 11:08:58 INFO - PROCESS | 1670 | Values: 145.0 156.0 141.0 130.0 143.0 149.0 139.0 139.0 155.0 138.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:165.50 stddev:26.10 (15.8%) stddev-sans-first:27.35 11:08:58 INFO - PROCESS | 1670 | Values: 166.0 165.0 165.0 165.0 164.0 167.0 227.0 227.0 164.0 165.0 11:08:58 INFO - PROCESS | 1670 | 11:08:58 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 11:08:58 INFO - PROCESS | 1670 | Values: 66.0 67.0 68.0 68.0 66.0 66.0 66.0 66.0 66.0 65.0 11:08:58 INFO - PROCESS | 1670 | -------- Summary: end -------- 11:08:58 INFO - PROCESS | 1670 | 11:08:59 INFO - TEST-INFO | 1670: exit 0 11:08:59 INFO - TEST-OK | kraken | took 59131ms 11:08:59 INFO - SUITE-END | took 1177s 11:08:59 INFO - Completed test suite (00:19:37) 11:08:59 INFO - javascript benchmark 11:08:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2933.2080338308656, 3399.439092549729, 3405.3019131078117, 3415.4193787056206, 3426.725861931046, 5599.048161812491, 5707.65754054757, 5724.227229324045, 5758.712064396779, 5799.971000144993, 5725.169850371693, 5730.341010783751, 5848.152017957394, 5876.294844618641, 5885.11723241514, 5145.176771716526, 5243.056249875021, 5243.764030618631, 5252.317198764155, 5364.812231571896, 4473.642108631302, 5365.087935051031, 5371.892562148755, 5393.703346315951, 5429.755660995245, 5169.0, 5218.895622087575, 5225.320708307896, 5294.549963253128, 5376.38171610262, 5262.8684282892855, 5264.236685680593, 5265.157574788015, 5265.315508983846, 5267.525922666979, 5169.276301317781, 5217.452167522393, 5219.5041471060185, 5230.8692282692855, 5296.179092240709, 5745.138229265602, 5848.0, 5860.912086318709, 5865.354810970833, 5883.38224486427, 6058.727357268934, 6330.936690633127, 6336.746530138789, 6341.778037768656, 6360.141380913564, 6204.7828326008375, 6211.409916057967, 6279.246490421133, 6290.874182516324, 6342.5243106767175, 5078.882645817914, 5854.2389489366105, 5880.705964701748, 5901.468867801918, 5926.762929482858, 6374.84062898422, 6394.8721025579225, 6398.29618741938, 6401.3598640135615, 6427.100205971168, 5996.580239383288, 6053.51571874249, 6057.939420605826, 6068.787592434243, 6092.695365231721, 6228.844278892973, 6230.937690623126, 6231.096491008869, 6240.095186198067, 6241.001439769706, 5132.307138536325, 5249.737513124329, 6148.508119350441, 6246.562740608114, 6248.4064013918605, 6272.560920735505, 6302.810915672537, 6316.936830631635, 6330.05049242608, 6340.936590634127, 4916.336294600254, 5034.0938631046765, 5047.116754567936, 5095.490450954875, 5117.692938423706, 4440.644748420119, 4475.5300693426725, 4505.842295519641, 4542.523035081335, 4580.427446569179, 5767.106098554731, 5784.016717158093, 5787.0, 5787.0, 5844.415558444121, 6317.3366796486625, 6340.9365906340345, 6365.8726825464155, 6366.427021568081, 6389.9680501597195, 5643.463870932255, 5711.914321285184, 5712.914306285409, 5755.453231942959, 5791.82624521265, 5485.6982865942755, 5494.478024587658, 5495.340559132929, 5505.256790333334, 5546.112621980464, 6360.141380913517, 6362.681865906594, 6367.904481432783, 6373.0, 6374.553781235454, 6116.518381401602, 6242.687865606792, 6278.215223097113, 6337.96831015842, 6339.619622822645, 6352.9047064293145, 6406.263279722892, 6418.614883106842, 6420.903686444613, 6423.678816059271, 6270.655113968775, 6274.341194174728, 6295.3704629538515, 6314.810555683337, 6315.052742088724, 6145.4161854623735, 6216.347283535164, 6240.251169859646, 6294.748210071546, 6347.238331400262, 6113.694315284307, 6134.171886795404, 6138.508919286357, 6142.109394137914, 6142.938570614416, 6135.1717518136265, 6157.6613286269685, 6180.907286390618, 6246.250449946036, 6254.749810007549, 6019.465036415686, 6061.545384096122, 6064.824020680126, 6080.620016038326, 6081.829337643192, 5993.460588547052, 6215.471684906834, 6232.968835155795, 6243.250809902841, 6250.624962502265, 5769.394213607511, 5873.177755114373, 5945.821625351246, 5958.612690175125, 5969.074793407029], "value": 5744.115882459223, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.38546275304856, 501.1530513432299, 523.7538356972223, 534.8422215446443, 569.0411656359034, 30933.690663093363, 32787.0, 40552.18895622082, 41439.37840932381, 41618.0, 36114.45828312571, 42911.78544107275, 43308.783456082754, 43339.916502087464, 43509.56490435095, 9670.4197748135, 9833.606655733762, 9841.114299713025, 9850.655227067055, 9861.753456163617, 8707.869381959275, 8902.510361930092, 11708.063354931584, 11920.880791192107, 11939.104567157454, 11947.820782688266, 12015.639530814089, 12061.0, 12082.516699332016, 12135.635930922042, 8658.264047555966, 11923.105767067462, 11971.940140299286, 11990.460429280703, 11994.460249288715, 11486.253393529394, 11785.882141178565, 11800.114991375596, 12150.210236334698, 12170.878291217063, 40584.59415405867, 40951.7952410239, 41037.0, 41050.68967758372, 41063.384049239285, 3468.843902024415, 3504.4918486819524, 3519.665631764978, 3659.2132691471197, 3771.1891943232336, 31251.218719531968, 31638.146942815652, 32320.838395808107, 32350.838245808856, 32383.676163238302, 3190.2502911815936, 3329.400707872582, 3334.883279085245, 3346.0, 3368.949465758016, 504847.0, 504958.0, 507532.0, 507962.92037079524, 508260.0, 60002.39997600055, 60819.78360432855, 61166.0, 61248.38751612427, 61270.69364653148, 65220.673896630215, 66051.0, 66100.33899661039, 66109.0, 66147.0, 15990.280437380346, 16131.919340403458, 16162.0, 16170.27233774483, 16202.351905923866, 11372.20394572372, 11592.072634189413, 12504.562340318227, 12542.24746515212, 12558.623241302776, 17184.656306874043, 17478.912605437145, 17576.560585985197, 17725.379611713528, 17732.93602383861, 5433.510984011458, 6371.649559274284, 6439.162908821917, 6517.608943463408, 6553.148090748172, 888.8622263549031, 891.4027601507006, 1018.4144117132678, 1064.920130990179, 1081.534939975818, 29708.55437168444, 30267.545986810215, 31207.063788086394, 31242.37515249682, 31427.685723142476, 1081.324172392255, 1145.5131569083105, 1146.5585749486509, 1157.8610566732045, 1230.2065167966612, 15740.606484837741, 15797.526074217792, 15857.286422111032, 16180.919095404448, 16246.350145994264, 652489.0, 659676.0, 664794.0, 666476.0, 666562.3422009184, 59350.70324648349, 59939.0, 59972.700136499036, 60034.69982650146, 60060.39939600635, 1251.7559075980096, 1290.5934630591335, 1351.202790353696, 1389.3608939887765, 1405.8523854995283, 30128.3974320508, 30921.0, 31695.52456713197, 32528.674713252563, 32669.019929402155, 54162.458375417315, 57485.425145748006, 57546.42453575411, 57557.42442575688, 57572.71213643905, 588973.0, 594164.0, 594738.0, 595836.0, 596567.0, 26696.599551006362, 26946.056888008825, 27275.454490910466, 40772.96135193169, 41124.0, 47852.0, 48348.0, 48780.2682959763, 49546.00907981748, 49627.25559116544, 39027.77986072586, 39277.625283114954, 39321.60678393179, 40510.59489405068, 40606.79696601498, 30726.385472290876, 38411.80794096012, 38990.41514377343, 39218.80390598029, 40495.392569112075, 37922.0, 38566.0, 38787.61212387953, 38898.0, 38929.80535097307, 28571.571426428203, 29308.120756377342, 32964.67035329712, 35128.648713512535, 35350.050992861536, 25338.74661253364, 25589.488210236064, 25914.087006666345, 25928.254895650683, 25930.22209333723], "value": 20675.26391835536, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.6747009016659, 370.47207728986194, 371.76578755384105, 376.85491085931915, 399.50261923904725, 15450.69098618027, 16332.510024699248, 20381.694274585858, 20854.68717969228, 21007.47481312968, 20657.896710516427, 20886.582268354625, 21099.367018989455, 21250.787492125073, 21526.246581369654, 5770.509506691915, 5997.940020599793, 6072.757089716428, 6253.968730156365, 6254.499640028786, 6105.81682549522, 6142.539309551801, 6143.7235324410285, 6162.414570615807, 6165.352637972993, 3875.5736868944396, 4007.4071930788973, 4081.244969680604, 4293.914121717564, 6779.932200678004, 6722.226943901441, 6730.730770769163, 6731.899021514681, 6738.494612904052, 6739.393454589062, 5192.688438693691, 5478.123500239983, 5511.669299842022, 5536.363318218376, 5541.168824676291, 12805.295708736016, 12815.871841281562, 12904.161229520145, 12937.094403391766, 13094.017948653795, 2195.1658369819365, 2201.570693714726, 2312.7687231276736, 2362.783166669167, 2478.6529885816, 9784.55969481375, 9847.704568862946, 9868.50657467131, 10384.117350025257, 10388.0, 1812.764340635709, 1815.1650240889207, 1826.0, 1851.574137948266, 1865.8507319414414, 115073.42463287714, 121069.0, 124785.37607311906, 126356.36821815924, 127363.0, 9060.95469522648, 11400.061989461812, 11571.826422603499, 11718.765624687458, 11742.88257117418, 14418.206998615176, 14430.494932677306, 14447.205403702897, 14450.060746051473, 14464.27678616065, 8294.253517183482, 8390.077091519928, 8420.284275836622, 8420.410571260076, 8631.956840215758, 8317.459365141247, 8366.330693544502, 8483.618237179342, 8508.596081646583, 8561.914380856111, 3776.9244615107546, 4399.0, 4481.574250446202, 4484.932726009112, 4574.74838883858, 1858.6840237159715, 1908.6850669639632, 1920.2511020701938, 1958.265650381107, 1978.910949007298, 836.9748907532784, 893.9418937769025, 917.4907926100944, 952.1906379577304, 970.1414248390084, 20924.895375523025, 21683.0, 21784.34646960594, 21813.563728725338, 21856.78143218579, 890.7372325163963, 905.2893478619252, 914.9085091490798, 918.8392031394479, 935.8923723771853, 10456.581736730597, 10940.85121062293, 11638.825417618742, 11648.592299269485, 11723.76552468946, 598551.0, 601785.0, 602831.0, 621856.0, 626194.0, 132058.0, 132467.67532324552, 134521.0, 135297.32351338377, 140932.0, 946.6592026870321, 950.2255661635745, 966.3332300712486, 966.777641142527, 994.2493417469908, 18847.90576047111, 19511.707324389863, 19515.707264390763, 19539.609207816047, 20592.69110963307, 37099.0, 56432.71783641056, 56905.0, 57923.13115303358, 58067.128993064296, 77913.61043194748, 78504.21495784969, 82908.58545707233, 83666.16333836828, 84144.0, 4606.848287928018, 5264.710440925785, 7861.2531809478005, 7981.521108733495, 8462.873056904275, 10348.637797677045, 12741.171823831792, 13100.803487947498, 13632.182069075887, 13684.452621895012, 11218.887811122111, 11370.54517819278, 11494.850514948439, 11717.06263498901, 11903.642890713292, 10697.786044279226, 11319.377434241196, 11496.597619083292, 11816.940915295369, 11888.702782430162, 11284.943575282072, 11598.246114002563, 11833.290002599872, 12007.159498834997, 12046.096542759504, 8067.596620169085, 8103.635336409993, 8419.410641255054, 8473.788155296166, 8477.279431248413, 10508.369497830156, 11405.600803972153, 11713.355765432641, 11734.530618775154, 11789.646310610353], "value": 9398.195900730832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.4700590272741, 1533.8849586281024, 1568.435363269223, 1770.2211027148062, 1917.261854186138, 7794.298513133816, 8593.48439093654, 8829.779255518615, 8853.645854165825, 8939.821203575924, 8715.607797649105, 8819.470831750099, 8854.778630534223, 8857.025727170007, 8857.51283679399, 2327.0691723310683, 2354.5637161331574, 2355.4229213842546, 2356.057576969209, 2357.422431504284, 2300.493891343902, 2334.4630734930893, 2347.295811256625, 2348.3189874936247, 2349.001674288429, 2310.745817960023, 2323.94190145246, 2331.6385960176117, 2339.076064954342, 2340.063974410241, 2290.404494831339, 2312.190733243368, 2321.3616255529696, 2321.5472982768283, 2334.0, 2284.9428764281024, 2323.0591610397814, 2327.8603283803027, 2329.7670232976734, 2332.207049603126, 8595.269402100828, 8796.384253102286, 8867.113288671146, 8871.112888711143, 8877.334199934967, 5168.457311982225, 5333.626646134772, 5378.946210537883, 5382.03123437785, 5393.541548968343, 3445.603755568117, 3570.8571657133684, 3645.434957581579, 3657.3233951719017, 3664.5602527696847, 1062.2404980439023, 1067.4555976452066, 1069.7379142110155, 1089.319175515303, 1091.7871015152048, 11425.800290969411, 11488.36813975231, 11617.709557261136, 11631.302121872715, 11635.069194464422, 4572.885677858014, 4631.235846085358, 4807.062622788522, 4826.686265392738, 4827.300041493963, 10064.245181611408, 10074.546645400975, 10083.294169408071, 10090.44502552352, 10118.595256189818, 5364.034473794676, 5365.114756065205, 5365.302510673588, 5366.195070739421, 5366.892662146735, 4382.802773875183, 4539.432570928634, 4707.199776038082, 4815.879603009953, 4905.166121759309, 3448.189675426273, 3908.2965066288343, 3910.354791459434, 4041.393790931325, 4058.0, 1250.8373911391461, 1260.1619922751452, 1261.6025951825145, 1274.815151803002, 1279.0918447901909, 791.841631673656, 883.2668884825707, 887.9067697891758, 909.9226565741853, 933.3793027636546, 4387.780610969438, 5680.829575112753, 5929.229200204032, 5945.672987985702, 6085.330613632497, 424.8661671573444, 435.1992334105262, 435.4578549705637, 437.89271628451235, 440.9757463339547, 3903.6877049835944, 3915.5105611798526, 4012.0, 4024.2353952748886, 4030.0730831908827, 10041.0, 10395.84406233907, 10578.418186999788, 10707.571697132184, 10713.196510261761, 10643.94678026605, 10646.680599582025, 10663.413512256746, 10673.252872298865, 10678.946605266923, 8012.759617211493, 8075.676972921135, 8127.715529956423, 8244.381671374791, 8258.339332853357, 5002.0, 5667.093265077733, 5675.716214189357, 5775.971120144372, 5857.765689372548, 9089.227415669742, 9833.754156146153, 9892.653757118467, 9899.95050024774, 10029.348092373972, 9879.06148915852, 9955.50222488887, 10041.246906481896, 10191.84712229332, 10297.433641149808, 6973.965130174317, 7199.388052015637, 7393.038904942323, 7588.013558237394, 7662.157162711986, 8335.166483351859, 8472.3645726572, 8523.659053637784, 8540.70107546233, 8551.187637174458, 7335.303146201102, 7445.9627701861145, 7541.811454713676, 7630.580318082337, 7698.0, 7263.400564864765, 7339.8532029360185, 7374.078240219973, 7531.322181003517, 7586.696532138873, 6860.622665753432, 7018.929810701828, 7022.473314501535, 7034.331738484836, 7042.718291268292, 4923.778429970588, 4943.65394422387, 5070.619703522177, 5084.872878178076, 5139.408967968582, 3853.3256680081436, 4436.689431739747, 4612.262038073892, 4624.121416930772, 4742.88142796419], "value": 4464.287542263257, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.74031280752496, 294.6832155432909, 295.93045634275944, 305.93077195202767, 311.5560326534688, 5350.625456218061, 5367.570594352453, 5391.703456309906, 5456.91814622781, 5477.370102438221, 4600.941783389822, 4982.053409852126, 5206.7656955437005, 5220.4779522047775, 5243.0, 3403.2512847173584, 3915.9804200978956, 4008.0781420273356, 4014.498187726534, 4048.4332193492933, 4059.4722686050773, 4074.0, 4117.814698338567, 4119.8352065917325, 4145.5647157048525, 3839.4240863870505, 4044.534878488982, 4066.5323487798837, 4094.7338423002557, 4152.875413737593, 4075.0219947212754, 4083.8774836754797, 4086.223617512663, 4100.835966561334, 4127.318992366256, 3281.8011932005616, 3318.7013168814924, 3567.3935430976794, 3585.8565657373674, 3593.694535964446, 4829.9275510867365, 5258.947410525884, 5269.0, 5290.4974027467715, 5305.575553955674, 3921.9213816677207, 4280.1653677532895, 4595.264757638762, 4608.308753686973, 4680.180968330528, 5379.569634429236, 5403.51368376844, 5598.0, 5609.0, 5618.297712785902, 3328.6837750413915, 3931.842726290945, 3937.6849852012056, 3937.9212415751526, 3985.202959408101, 5404.946035523075, 5808.547863034241, 5853.736581853785, 5888.793892213752, 5903.79336723217, 3811.4282857571584, 3846.2692088503095, 3852.3049858062363, 3853.5953724859046, 3872.9612703873163, 3497.2480916603054, 3823.5411750590106, 3924.4505769192338, 3933.3903244997073, 3935.7244992850797, 3853.4797802296894, 3934.3311637021775, 4349.999500114991, 4447.488538818013, 4552.839026048337, 4643.303504474356, 5463.644863083887, 5540.889182216333, 5597.160425936061, 5644.887102258014, 1151.3379806611233, 1314.7962065879804, 1389.5483967710331, 1394.2122700674088, 1398.6013986013936, 802.7832485228955, 856.39624065034, 893.8972018217858, 917.6925729880431, 946.5551190940247, 4356.803943822472, 4852.514748525119, 5214.687118772886, 5257.605679574047, 5263.894442223065, 5044.848654540369, 5690.317161940511, 5695.572832037614, 5695.886082278331, 5729.226554415184, 4400.251556132192, 5154.30416893722, 5183.98912212115, 5232.090287045201, 5345.786168553292, 2900.94115647787, 2976.3154474470994, 3018.607581014454, 3021.2204771610172, 3043.25440267136, 4953.291114565469, 5258.553022993012, 5529.225908372831, 5627.43725627434, 5871.500922421556, 4418.823247070145, 4909.239067944474, 5580.832575022756, 5724.16999535065, 5833.741368800799, 5302.655327403706, 5398.462722520409, 5822.621529600562, 5848.678322692208, 5855.268091488564, 5479.287692600096, 5648.15277708347, 5658.85852853682, 5679.261695979496, 5697.202391665253, 4755.310479980314, 5279.9472005280995, 5306.6550674206055, 5582.86042848932, 5594.272744543184, 5174.14626586609, 5832.3292821326095, 5839.766409343579, 5849.853753656193, 5888.705564721832, 3364.9327013459106, 3673.687736542424, 3966.6231707987695, 3995.92008159841, 4004.699647526389, 4750.643701722453, 4766.117383633376, 4925.827596034265, 5671.262735844314, 5709.800156994485, 5261.552768014609, 5590.832275031756, 5609.775608975595, 5613.635113717595, 5666.518345940642, 5086.872828179326, 5461.453854614507, 5514.944850551604, 5531.621402796836, 5547.528460080777, 4757.549861821096, 5160.394001029456, 5380.973095134499, 5420.945790542044, 5460.399356070909, 4913.582345500529, 5037.244413338029, 5324.622131646987, 5573.024720673785, 5606.495415412633, 4660.7669616519715, 4953.23224900141, 5527.226188333717, 5539.476643922792, 5559.6664200148125], "value": 4062.0681643086423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.2160545005943, 725.7967769024668, 756.3117563017655, 764.6711913877034, 806.8184658451847, 15488.0, 16589.0, 16598.585035374123, 16613.086280254596, 16645.084520351375, 14384.496542621011, 14492.0, 14498.637534061654, 14508.782368264458, 14526.854731452682, 673.737242475435, 695.0720787748362, 830.7466222802058, 834.3533761334953, 859.5358506406533, 835.4987007795305, 848.5333066813259, 849.218718778725, 849.7705619482736, 851.1658574596898, 900.1133883125149, 900.5902314446911, 901.7204666553357, 902.3367824648877, 903.4804987132393, 600.432591201315, 877.2850127146355, 896.7264984179823, 899.7930475990559, 904.8371293167228, 665.2449469851705, 665.5208250059998, 665.7303791964263, 665.866826634675, 667.302668711198, 14698.55904322872, 14744.778828317583, 14766.778498322534, 14771.0, 14776.778348324784, 13676.31618419085, 13915.652108697263, 14060.296985150702, 14069.577912662637, 14094.365753541115, 12732.618021459279, 12761.744765104739, 12879.0, 13070.0, 13095.345232738326, 4545.09098180362, 4599.126166028444, 4705.811767529295, 4767.76161191939, 4776.140294746944, 114587.28119078137, 117028.0, 118141.40929295385, 124593.0, 125527.99999999908, 10965.067969222706, 11075.944620277009, 11080.113590912708, 11430.25703329281, 11440.256383335223, 21920.671189932164, 21924.671129932743, 21925.23261685865, 21927.342179734635, 21928.671069933964, 7871.757724900285, 7880.881786773203, 7952.1650226726515, 8076.071251806117, 8147.063087744985, 4381.18947994622, 4437.095356086623, 4481.5070342262325, 4526.140033393644, 4655.4180727409075, 3855.8650447234213, 4079.347304431277, 4090.897727556835, 4096.692748043849, 4107.178564287155, 601.5157797972674, 684.8356394465293, 715.3883429667663, 730.521508411991, 733.252082875464, 598.7844376024627, 625.5589809184514, 650.3853858104046, 661.2527843536773, 687.669918439152, 8964.238039766562, 9260.907390926139, 9520.381175223589, 9597.760055998517, 9758.219342452587, 539.4309004000817, 577.3043482603453, 591.4115455122181, 690.4028015766326, 699.3111784891878, 3802.866899658499, 3899.1616802387057, 4443.577860103285, 4728.40894888139, 4829.782659780319, 157077.0, 161294.0, 162848.0, 164680.0, 165484.0, 137550.0, 140004.0, 140975.0, 141001.0, 142475.86286205714, 91502.2223111043, 102293.48853255835, 108456.4577177109, 115549.26676099865, 117955.0, 8870.556472176495, 9426.764330891783, 9672.85490717654, 9769.218462523124, 9878.703638890845, 62687.6865615669, 63317.68341158265, 63364.0, 64664.0, 65349.346506534326, 75675.0, 92339.69150771285, 96167.0, 99924.50037749765, 101759.0, 13389.263590502616, 13413.329333533089, 13419.0, 13491.05562610608, 13691.520796772065, 14999.925120182386, 15007.849921501082, 15269.236538173269, 15367.910384571305, 15472.381104755683, 14013.649658758613, 14159.929200354345, 14186.219757913414, 14407.351669174703, 14658.0, 13967.0, 14167.64580885486, 14193.858061419254, 14418.639534011734, 14631.92684036573, 13571.93214033963, 13818.378172982038, 13955.651108722363, 14277.357518911465, 14384.42462301538, 10488.423136727553, 11289.604863829727, 11376.943115284701, 11377.317360958368, 12099.67259003781, 9109.0, 10300.278980471594, 11377.658670239905, 11611.419429028683, 11647.184697071123], "value": 8021.925257999353, "unit": "score"}], "name": "dromaeo_css", "value": 7386.253023349222, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 113.0, 114.0, 113.0, 114.0, 114.0, 117.0, 131.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 107.0, 164.0, 107.0, 107.0, 129.0, 104.0, 141.0, 109.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 187.0, 186.0, 184.0, 185.0, 180.0, 183.0, 183.0, 186.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 59.0, 87.0, 57.0, 58.0, 59.0, 67.0, 84.0, 56.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 98.0, 69.0, 81.0, 68.0, 96.0, 69.0, 67.0, 101.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 90.0, 88.0, 89.0, 86.0, 89.0, 86.0, 92.0, 130.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 95.0, 95.0, 94.0, 95.0, 103.0, 133.0, 95.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 85.0, 86.0, 86.0, 85.0, 87.0, 84.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 49.0, 68.0, 74.0, 43.0, 44.0, 52.0, 67.0, 63.0, 53.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 72.0, 80.0, 75.0, 72.0, 68.0, 73.0, 71.0, 70.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 86.0, 84.0, 83.0, 85.0, 81.0, 87.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 156.0, 141.0, 130.0, 143.0, 149.0, 139.0, 139.0, 155.0, 138.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 165.0, 164.0, 167.0, 227.0, 227.0, 164.0, 165.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 68.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1445.2000000000003, "alertThreshold": 2.0}]} 11:08:59 INFO - Return code: 0 11:08:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:08:59 INFO - # TBPL SUCCESS # 11:08:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:08:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:08:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:08:59 INFO - 2016-08-09 11:08:59.734 screenresolution[1674:9731] starting screenresolution argv=screenresolution get 11:08:59 INFO - 2016-08-09 11:08:59.736 screenresolution[1674:9731] Display 0: 1600x1200x32@60 11:08:59 INFO - 2016-08-09 11:08:59.742 screenresolution[1675:9734] starting screenresolution argv=screenresolution list 11:08:59 INFO - Available Modes on Display 0 11:08:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:08:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:08:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:08:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:08:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:08:59 INFO - Intel Iris: 11:08:59 INFO - Chipset Model: Intel Iris 11:08:59 INFO - Type: GPU 11:08:59 INFO - Bus: Built-In 11:08:59 INFO - VRAM (Dynamic, Max): 1536 MB 11:08:59 INFO - Vendor: Intel (0x8086) 11:08:59 INFO - Device ID: 0x0a2e 11:08:59 INFO - Revision ID: 0x0009 11:08:59 INFO - Displays: 11:08:59 INFO - Display: 11:08:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:08:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:08:59 INFO - Display Serial Number: 1600x1200 60 11:08:59 INFO - Main Display: Yes 11:08:59 INFO - Mirror: Off 11:08:59 INFO - Online: Yes 11:08:59 INFO - Rotation: Supported 11:08:59 INFO - Return code: 0 11:08:59 INFO - Running post-action listener: _resource_record_post_action 11:08:59 INFO - [mozharness: 2016-08-09 18:08:59.977280Z] Finished run-tests step (success) 11:08:59 INFO - Running post-run listener: _resource_record_post_run 11:09:00 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38437888; Write bytes: 700803072; Read time: 391; Write time: 2064 11:09:00 INFO - TinderboxPrint: CPU usage
53.5% 11:09:00 INFO - TinderboxPrint: I/O read bytes / time
38,437,888 / 391 11:09:00 INFO - TinderboxPrint: I/O write bytes / time
700,803,072 / 2,064 11:09:00 INFO - TinderboxPrint: CPU idle
2,258.7 (46.5%) 11:09:00 INFO - TinderboxPrint: CPU system
972.1 (20.0%) 11:09:00 INFO - TinderboxPrint: CPU user
1,622.8 (33.4%) 11:09:00 INFO - TinderboxPrint: Swap in / out
441,368,576 / 0 11:09:00 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386474496; Write bytes: 379492352; Read time: 29200; Write time: 714 11:09:00 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37479424; Write bytes: 294940672; Read time: 376; Write time: 1298 11:09:00 INFO - Running post-run listener: _upload_blobber_files 11:09:00 INFO - Blob upload gear active. 11:09:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:09:00 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 11:09:00 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'] 11:09:00 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 11:09:00 INFO - (blobuploader) - INFO - Open directory for files ... 11:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:09:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:09:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:01 INFO - (blobuploader) - INFO - Done attempting. 11:09:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:09:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:09:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:02 INFO - (blobuploader) - INFO - Done attempting. 11:09:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:09:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:05 INFO - (blobuploader) - INFO - Done attempting. 11:09:05 INFO - (blobuploader) - INFO - Iteration through files over. 11:09:05 INFO - Return code: 0 11:09:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:09:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:09:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6b225efc71f49dc5e9ab3dbc42d1ce9d4cd82f04f78be6adf45425adeed6768f9be80ee1f680297148ae35cc5e80bf11c119c44a1717e068e210867b2009d91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf6f348d8cf012d02694849227a148265e52021de70a3f0ca6b88ad5003752cff9fa3e8e43a337f3f26ce1e6a4d84a6e328dde5340c5c87ddd316ee376ad92f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/208507ddc23e48b6ea63b4b7a3ba8143a82a507bb4d768f5ba34d2a61e314bb1b26cc3d910fb4e7f47b7fe88bfd94823af8b3919342d352eaaba5a82359186de"} 11:09:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:09:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:09:05 INFO - Contents: 11:09:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6b225efc71f49dc5e9ab3dbc42d1ce9d4cd82f04f78be6adf45425adeed6768f9be80ee1f680297148ae35cc5e80bf11c119c44a1717e068e210867b2009d91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf6f348d8cf012d02694849227a148265e52021de70a3f0ca6b88ad5003752cff9fa3e8e43a337f3f26ce1e6a4d84a6e328dde5340c5c87ddd316ee376ad92f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/208507ddc23e48b6ea63b4b7a3ba8143a82a507bb4d768f5ba34d2a61e314bb1b26cc3d910fb4e7f47b7fe88bfd94823af8b3919342d352eaaba5a82359186de"} 11:09:05 INFO - Running post-run listener: copy_logs_to_upload_dir 11:09:05 INFO - Copying logs to upload dir... 11:09:05 INFO - mkdir: /builds/slave/test/build/upload/logs 11:09:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.504501 ========= master_lag: 2.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-09 11:09:07.578211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 11:09:07.579656) ========= 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.nd3poAWQaA/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.P2CEEXcm6t/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/f6b225efc71f49dc5e9ab3dbc42d1ce9d4cd82f04f78be6adf45425adeed6768f9be80ee1f680297148ae35cc5e80bf11c119c44a1717e068e210867b2009d91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf6f348d8cf012d02694849227a148265e52021de70a3f0ca6b88ad5003752cff9fa3e8e43a337f3f26ce1e6a4d84a6e328dde5340c5c87ddd316ee376ad92f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/208507ddc23e48b6ea63b4b7a3ba8143a82a507bb4d768f5ba34d2a61e314bb1b26cc3d910fb4e7f47b7fe88bfd94823af8b3919342d352eaaba5a82359186de"} build_url:https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011288 build_url: 'https://queue.taskcluster.net/v1/task/ZOLzf1hoSJ6s0yX5_IAPKg/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/f6b225efc71f49dc5e9ab3dbc42d1ce9d4cd82f04f78be6adf45425adeed6768f9be80ee1f680297148ae35cc5e80bf11c119c44a1717e068e210867b2009d91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf6f348d8cf012d02694849227a148265e52021de70a3f0ca6b88ad5003752cff9fa3e8e43a337f3f26ce1e6a4d84a6e328dde5340c5c87ddd316ee376ad92f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/208507ddc23e48b6ea63b4b7a3ba8143a82a507bb4d768f5ba34d2a61e314bb1b26cc3d910fb4e7f47b7fe88bfd94823af8b3919342d352eaaba5a82359186de"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 11:09:07.633887) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-09 11:09:07.634266) ========= 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.nd3poAWQaA/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.P2CEEXcm6t/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.005054 ========= master_lag: 2.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-09 11:09:09.808016) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 11:09:09.808354) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 11:09:09.982000) ========= ========= Total master_lag: 4.56 =========