builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0151 starttime: 1470655207.97 results: success (0) buildid: 20160808012421 builduid: f64ec4b55952477e87b269089c3452c5 revision: f8fc21bb232095b7b4c995d259558212cb90a44b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:07.968014) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:07.968454) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:07.968821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:07.993558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:07.993928) ========= 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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-08 04:20:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-08-08 04:20:08 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088922 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:08.108096) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:08.108415) ========= 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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.027799 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:08.156975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 04:20:08.157319) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f8fc21bb232095b7b4c995d259558212cb90a44b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f8fc21bb232095b7b4c995d259558212cb90a44b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-08 04:20:08,233 truncating revision to first 12 chars 2016-08-08 04:20:08,233 Setting DEBUG logging. 2016-08-08 04:20:08,233 attempt 1/10 2016-08-08 04:20:08,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f8fc21bb2320?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 04:20:08,963 unpacking tar archive at: fx-team-f8fc21bb2320/testing/mozharness/ program finished with exit code 0 elapsedTime=0.992957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 04:20:09.172022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:09.172329) ========= script_repo_revision: f8fc21bb232095b7b4c995d259558212cb90a44b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:09.172729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:09.173013) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 04:20:09.190985) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-08 04:20:09.191276) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:09 INFO - MultiFileLogger online at 20160808 04:20:09 in /builds/slave/test 04:20:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:20:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:20:09 INFO - {'append_to_log': False, 04:20:09 INFO - 'base_work_dir': '/builds/slave/test', 04:20:09 INFO - 'blob_upload_branch': 'Fx-Team', 04:20:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:20:09 INFO - 'branch': 'Fx-Team', 04:20:09 INFO - 'buildbot_json_path': 'buildprops.json', 04:20:09 INFO - 'config_files': ('talos/mac_config.py',), 04:20:09 INFO - 'default_actions': ('clobber', 04:20:09 INFO - 'read-buildbot-config', 04:20:09 INFO - 'download-and-extract', 04:20:09 INFO - 'populate-webroot', 04:20:09 INFO - 'create-virtualenv', 04:20:09 INFO - 'install', 04:20:09 INFO - 'run-tests'), 04:20:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:20:09 INFO - 'download_minidump_stackwalk': True, 04:20:09 INFO - 'download_symbols': 'ondemand', 04:20:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:20:09 INFO - 'tooltool.py': '/tools/tooltool.py', 04:20:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:20:09 INFO - '/tools/misc-python/virtualenv.py')}, 04:20:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:20:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:20:09 INFO - 'installer_path': 'installer.exe', 04:20:09 INFO - 'log_level': 'info', 04:20:09 INFO - 'log_name': 'talos', 04:20:09 INFO - 'log_to_console': True, 04:20:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:20:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:20:09 INFO - 'opt_config_files': (), 04:20:09 INFO - 'pip_index': False, 04:20:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:20:09 INFO - 'cmd': ('bash', 04:20:09 INFO - '-c', 04:20:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:20:09 INFO - 'enabled': True, 04:20:09 INFO - 'halt_on_failure': False, 04:20:09 INFO - 'name': 'check_screen_resolution'},), 04:20:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:20:09 INFO - 'cmd': ('bash', 04:20:09 INFO - '-c', 04:20:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:20:09 INFO - 'enabled': True, 04:20:09 INFO - 'halt_on_failure': False, 04:20:09 INFO - 'name': 'check_screen_resolution'},), 04:20:09 INFO - 'run_cmd_checks_enabled': True, 04:20:09 INFO - 'sps_profile': False, 04:20:09 INFO - 'sps_profile_interval': 0, 04:20:09 INFO - 'suite': 'dromaeojs', 04:20:09 INFO - 'system_bits': '32', 04:20:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:20:09 INFO - 'title': 't-yosemite-r7-0151', 04:20:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:20:09 INFO - 'use_talos_json': True, 04:20:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:20:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:20:09 INFO - 'work_dir': 'build'} 04:20:09 INFO - [mozharness: 2016-08-08 11:20:09.386798Z] Running clobber step. 04:20:09 INFO - Running pre-action listener: _resource_record_pre_action 04:20:09 INFO - Running main action method: clobber 04:20:09 INFO - rmtree: /builds/slave/test/build 04:20:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:20:11 INFO - Running post-action listener: _resource_record_post_action 04:20:11 INFO - [mozharness: 2016-08-08 11:20:11.501354Z] Finished clobber step (success) 04:20:11 INFO - [mozharness: 2016-08-08 11:20:11.501518Z] Running read-buildbot-config step. 04:20:11 INFO - Running pre-action listener: _resource_record_pre_action 04:20:11 INFO - Running main action method: read_buildbot_config 04:20:11 INFO - Using buildbot properties: 04:20:11 INFO - { 04:20:11 INFO - "project": "", 04:20:11 INFO - "product": "firefox", 04:20:11 INFO - "script_repo_revision": "production", 04:20:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:20:11 INFO - "repository": "", 04:20:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:20:11 INFO - "buildid": "20160808012421", 04:20:11 INFO - "pgo_build": "False", 04:20:11 INFO - "basedir": "/builds/slave/test", 04:20:11 INFO - "buildnumber": 813, 04:20:11 INFO - "slavename": "t-yosemite-r7-0151", 04:20:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:20:11 INFO - "platform": "yosemite_r7", 04:20:11 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 04:20:11 INFO - "branch": "fx-team", 04:20:11 INFO - "repo_path": "integration/fx-team", 04:20:11 INFO - "revision": "f8fc21bb232095b7b4c995d259558212cb90a44b", 04:20:11 INFO - "stage_platform": "macosx64", 04:20:11 INFO - "builduid": "f64ec4b55952477e87b269089c3452c5", 04:20:11 INFO - "slavebuilddir": "test" 04:20:11 INFO - } 04:20:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:20:11 INFO - Running post-action listener: _resource_record_post_action 04:20:11 INFO - [mozharness: 2016-08-08 11:20:11.504909Z] Finished read-buildbot-config step (success) 04:20:11 INFO - [mozharness: 2016-08-08 11:20:11.505045Z] Running download-and-extract step. 04:20:11 INFO - Running pre-action listener: _resource_record_pre_action 04:20:11 INFO - Running main action method: download_and_extract 04:20:11 INFO - mkdir: /builds/slave/test/build/tests 04:20: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')]} 04:20:11 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:20:11 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:12 INFO - Downloaded 1199 bytes. 04:20:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:20:12 INFO - Using the following test package requirements: 04:20:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:20:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:20:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:20:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'jsshell-mac.zip'], 04:20:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:20:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:20:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:20:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:20:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:20:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:20:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:20:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:12 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:20:12 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:13 INFO - Downloaded 16320444 bytes. 04:20:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:20:20 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 04:20:20 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')]} 04:20:20 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:20:20 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:21 INFO - Downloaded 16320444 bytes. 04:20:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:28 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:20:28 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:29 INFO - Downloaded 14413723 bytes. 04:20:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 04:20:29 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')]} 04:20:29 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:20:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:20:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/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 04:20:32 INFO - Downloaded 111901101 bytes. 04:20:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:20:32 INFO - mkdir: /builds/slave/test/properties 04:20:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:20:32 INFO - Writing to file /builds/slave/test/properties/build_url 04:20:32 INFO - Contents: 04:20:32 INFO - build_url:https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:20:32 INFO - Running post-action listener: _resource_record_post_action 04:20:32 INFO - Running post-action listener: set_extra_try_arguments 04:20:32 INFO - [mozharness: 2016-08-08 11:20:32.866929Z] Finished download-and-extract step (success) 04:20:32 INFO - [mozharness: 2016-08-08 11:20:32.867059Z] Running populate-webroot step. 04:20:32 INFO - Running pre-action listener: _resource_record_pre_action 04:20:32 INFO - Running main action method: populate_webroot 04:20:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:20:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:20:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:20:32 INFO - '--tppagecycles', 04:20:32 INFO - '7'], 04:20:32 INFO - 'tests': ['tsvgm']}}, 04:20:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:20:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:20:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:20:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:20:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['damp', 'tps']}, 04:20:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'tests': ['damp', 'tps']}, 04:20:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['dromaeo_dom']}, 04:20:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:20:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['basic_compositor_video']}, 04:20:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:20:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['a11yr', 04:20:32 INFO - 'ts_paint', 04:20:32 INFO - 'tpaint', 04:20:32 INFO - 'sessionrestore', 04:20:32 INFO - 'sessionrestore_no_auto_restore', 04:20:32 INFO - 'tabpaint']}, 04:20:32 INFO - 'other-e10s': {'tests': ['a11yr', 04:20:32 INFO - 'ts_paint', 04:20:32 INFO - 'tpaint', 04:20:32 INFO - 'sessionrestore', 04:20:32 INFO - 'sessionrestore_no_auto_restore', 04:20:32 INFO - 'tabpaint']}, 04:20:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:20:32 INFO - 'ts_paint', 04:20:32 INFO - 'tpaint', 04:20:32 INFO - 'sessionrestore', 04:20:32 INFO - 'sessionrestore_no_auto_restore', 04:20:32 INFO - 'tabpaint']}, 04:20:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:20:32 INFO - 'ts_paint', 04:20:32 INFO - 'tpaint', 04:20:32 INFO - 'sessionrestore', 04:20:32 INFO - 'sessionrestore_no_auto_restore', 04:20:32 INFO - 'tabpaint']}, 04:20:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['a11yr', 04:20:32 INFO - 'ts_paint', 04:20:32 INFO - 'tpaint', 04:20:32 INFO - 'sessionrestore', 04:20:32 INFO - 'sessionrestore_no_auto_restore', 04:20:32 INFO - 'tabpaint']}, 04:20:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['a11yr', 04:20:32 INFO - 'ts_paint', 04:20:32 INFO - 'tpaint', 04:20:32 INFO - 'sessionrestore', 04:20:32 INFO - 'sessionrestore_no_auto_restore', 04:20:32 INFO - 'tabpaint']}, 04:20:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['tsvgx', 04:20:32 INFO - 'tsvgr_opacity', 04:20:32 INFO - 'tart', 04:20:32 INFO - 'tscrollx', 04:20:32 INFO - 'cart']}, 04:20:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:20:32 INFO - 'tsvgr_opacity', 04:20:32 INFO - 'tart', 04:20:32 INFO - 'tscrollx', 04:20:32 INFO - 'cart']}, 04:20:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'talos_options': ['--disable-e10s'], 04:20:32 INFO - 'tests': ['tp5o']}, 04:20:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'tests': ['tp5o']}, 04:20:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'talos_options': ['--disable-e10s', 04:20:32 INFO - '--xperf_path', 04:20:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:32 INFO - 'tests': ['tp5n']}, 04:20:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:20:32 INFO - 'talos_options': ['--xperf_path', 04:20:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:32 INFO - 'tests': ['tp5n']}}, 04:20:32 INFO - 'talos.zip': {'path': '', 04:20:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:20:32 INFO - Running post-action listener: _resource_record_post_action 04:20:32 INFO - [mozharness: 2016-08-08 11:20:32.886025Z] Finished populate-webroot step (success) 04:20:32 INFO - [mozharness: 2016-08-08 11:20:32.886167Z] Running create-virtualenv step. 04:20:32 INFO - Running pre-action listener: _resource_record_pre_action 04:20:32 INFO - Running main action method: create_virtualenv 04:20:32 INFO - Creating virtualenv /builds/slave/test/build/venv 04:20:32 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 04:20:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:20:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:20:33 INFO - Using real prefix '/tools/python27' 04:20:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:20:33 INFO - Installing distribute.............................................................................................................................................................................................done. 04:20:36 INFO - Installing pip.................done. 04:20:36 INFO - Return code: 0 04:20:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:20:36 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')]} 04:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:36 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')]} 04:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:36 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:36 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 04:20:36 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 04:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:36 INFO - 'HOME': '/Users/cltbld', 04:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:36 INFO - 'LOGNAME': 'cltbld', 04:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:36 INFO - 'MOZ_NO_REMOTE': '1', 04:20:36 INFO - 'NO_EM_RESTART': '1', 04:20:36 INFO - 'PAGER': '/bin/cat', 04:20:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:36 INFO - 'PWD': '/builds/slave/test', 04:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:36 INFO - 'SHELL': '/bin/bash', 04:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:36 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:36 INFO - 'USER': 'cltbld', 04:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:36 INFO - 'XPC_FLAGS': '0x0', 04:20:36 INFO - 'XPC_SERVICE_NAME': '0', 04:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:36 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 04:20:36 INFO - Cleaning up... 04:20:36 INFO - Return code: 0 04:20:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:20:36 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')]} 04:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:36 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')]} 04:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:36 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:36 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 04:20:36 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 04:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:36 INFO - 'HOME': '/Users/cltbld', 04:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:36 INFO - 'LOGNAME': 'cltbld', 04:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:36 INFO - 'MOZ_NO_REMOTE': '1', 04:20:36 INFO - 'NO_EM_RESTART': '1', 04:20:36 INFO - 'PAGER': '/bin/cat', 04:20:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:36 INFO - 'PWD': '/builds/slave/test', 04:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:36 INFO - 'SHELL': '/bin/bash', 04:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:36 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:36 INFO - 'USER': 'cltbld', 04:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:36 INFO - 'XPC_FLAGS': '0x0', 04:20:36 INFO - 'XPC_SERVICE_NAME': '0', 04:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:37 INFO - Downloading/unpacking psutil>=3.1.1 04:20:37 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 04:20:37 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 04:20:37 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 04:20:37 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 04:20:37 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 04:20:37 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 04:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:20:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:40 INFO - Installing collected packages: psutil 04:20:40 INFO - Running setup.py install for psutil 04:20:40 INFO - building 'psutil._psutil_osx' extension 04:20:40 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 04:20:41 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 04:20:41 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 04:20:41 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 04:20:41 INFO - building 'psutil._psutil_posix' extension 04:20:41 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 04:20:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:20:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:20:41 INFO - ^ 04:20:41 INFO - 1 warning generated. 04:20:41 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 04:20:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:41 INFO - Successfully installed psutil 04:20:41 INFO - Cleaning up... 04:20:41 INFO - Return code: 0 04:20:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:20:41 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')]} 04:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:41 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')]} 04:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:41 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:41 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 04:20:41 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 04:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:41 INFO - 'HOME': '/Users/cltbld', 04:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:41 INFO - 'LOGNAME': 'cltbld', 04:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:41 INFO - 'MOZ_NO_REMOTE': '1', 04:20:41 INFO - 'NO_EM_RESTART': '1', 04:20:41 INFO - 'PAGER': '/bin/cat', 04:20:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:41 INFO - 'PWD': '/builds/slave/test', 04:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:41 INFO - 'SHELL': '/bin/bash', 04:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:41 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:41 INFO - 'USER': 'cltbld', 04:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:41 INFO - 'XPC_FLAGS': '0x0', 04:20:41 INFO - 'XPC_SERVICE_NAME': '0', 04:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:20:41 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 04:20:41 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 04:20:41 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 04:20:41 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 04:20:41 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 04:20:41 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 04:20:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:20:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:20:44 INFO - Installing collected packages: mozsystemmonitor 04:20:44 INFO - Running setup.py install for mozsystemmonitor 04:20:44 INFO - Successfully installed mozsystemmonitor 04:20:44 INFO - Cleaning up... 04:20:44 INFO - Return code: 0 04:20:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:44 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:44 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 04:20:44 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 04:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:44 INFO - 'HOME': '/Users/cltbld', 04:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:44 INFO - 'LOGNAME': 'cltbld', 04:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:44 INFO - 'MOZ_NO_REMOTE': '1', 04:20:44 INFO - 'NO_EM_RESTART': '1', 04:20:44 INFO - 'PAGER': '/bin/cat', 04:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:44 INFO - 'PWD': '/builds/slave/test', 04:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:44 INFO - 'SHELL': '/bin/bash', 04:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:44 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:44 INFO - 'USER': 'cltbld', 04:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:44 INFO - 'XPC_FLAGS': '0x0', 04:20:44 INFO - 'XPC_SERVICE_NAME': '0', 04:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:44 INFO - Downloading/unpacking jsonschema==2.5.1 04:20:44 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 04:20:44 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 04:20:44 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 04:20:44 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 04:20:44 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 04:20:44 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 04:20:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:20:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:20:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:47 INFO - Downloading functools32-3.2.3-2.tar.gz 04:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:20:47 INFO - warning: no files found matching '*.txt' 04:20:47 INFO - no previously-included directories found matching 'build' 04:20:47 INFO - no previously-included directories found matching 'dist' 04:20:47 INFO - no previously-included directories found matching '.git*' 04:20:47 INFO - Installing collected packages: jsonschema, functools32 04:20:47 INFO - Running setup.py install for functools32 04:20:48 INFO - warning: no files found matching '*.txt' 04:20:48 INFO - no previously-included directories found matching 'build' 04:20:48 INFO - no previously-included directories found matching 'dist' 04:20:48 INFO - no previously-included directories found matching '.git*' 04:20:48 INFO - Successfully installed jsonschema functools32 04:20:48 INFO - Cleaning up... 04:20:48 INFO - Return code: 0 04:20:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:48 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:48 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 04:20:48 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 04:20:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:48 INFO - 'HOME': '/Users/cltbld', 04:20:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:48 INFO - 'LOGNAME': 'cltbld', 04:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:48 INFO - 'MOZ_NO_REMOTE': '1', 04:20:48 INFO - 'NO_EM_RESTART': '1', 04:20:48 INFO - 'PAGER': '/bin/cat', 04:20:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:48 INFO - 'PWD': '/builds/slave/test', 04:20:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:48 INFO - 'SHELL': '/bin/bash', 04:20:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:48 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:48 INFO - 'USER': 'cltbld', 04:20:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:48 INFO - 'XPC_FLAGS': '0x0', 04:20:48 INFO - 'XPC_SERVICE_NAME': '0', 04:20:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:20:48 INFO - Cleaning up... 04:20:48 INFO - Return code: 0 04:20:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:48 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:48 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 04:20:48 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 04:20:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:48 INFO - 'HOME': '/Users/cltbld', 04:20:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:48 INFO - 'LOGNAME': 'cltbld', 04:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:48 INFO - 'MOZ_NO_REMOTE': '1', 04:20:48 INFO - 'NO_EM_RESTART': '1', 04:20:48 INFO - 'PAGER': '/bin/cat', 04:20:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:48 INFO - 'PWD': '/builds/slave/test', 04:20:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:48 INFO - 'SHELL': '/bin/bash', 04:20:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:48 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:48 INFO - 'USER': 'cltbld', 04:20:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:48 INFO - 'XPC_FLAGS': '0x0', 04:20:48 INFO - 'XPC_SERVICE_NAME': '0', 04:20:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:48 INFO - Downloading/unpacking blobuploader==1.2.4 04:20:48 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 04:20:48 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 04:20:48 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 04:20:48 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 04:20:48 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 04:20:48 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 04:20:51 INFO - Downloading blobuploader-1.2.4.tar.gz 04:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:20:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:20:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:52 INFO - Downloading docopt-0.6.1.tar.gz 04:20:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:20:52 INFO - Installing collected packages: blobuploader, requests, docopt 04:20:52 INFO - Running setup.py install for blobuploader 04:20:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:20:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:20:52 INFO - Running setup.py install for requests 04:20:53 INFO - Running setup.py install for docopt 04:20:53 INFO - Successfully installed blobuploader requests docopt 04:20:53 INFO - Cleaning up... 04:20:53 INFO - Return code: 0 04:20:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:53 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:53 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 04:20:53 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 04:20:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:53 INFO - 'HOME': '/Users/cltbld', 04:20:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:53 INFO - 'LOGNAME': 'cltbld', 04:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:53 INFO - 'MOZ_NO_REMOTE': '1', 04:20:53 INFO - 'NO_EM_RESTART': '1', 04:20:53 INFO - 'PAGER': '/bin/cat', 04:20:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:53 INFO - 'PWD': '/builds/slave/test', 04:20:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:53 INFO - 'SHELL': '/bin/bash', 04:20:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:53 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:53 INFO - 'USER': 'cltbld', 04:20:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:53 INFO - 'XPC_FLAGS': '0x0', 04:20:53 INFO - 'XPC_SERVICE_NAME': '0', 04:20:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:53 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-X_TewA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:53 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-NEx23X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:53 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FCPAIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:53 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-5RZIAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-xPq2xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FHloxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-684rZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-EYXS5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-NiXCO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lv4luL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yMiDY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-tlAiuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-LUdg4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:54 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-IxCZmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:55 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-IUfYJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:55 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-per1F8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:55 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-YdHwLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:20:55 INFO - Running setup.py install for manifestparser 04:20:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:20:55 INFO - Running setup.py install for mozcrash 04:20:55 INFO - Running setup.py install for mozdebug 04:20:55 INFO - Running setup.py install for mozdevice 04:20:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:20:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Running setup.py install for mozfile 04:20:56 INFO - Running setup.py install for mozhttpd 04:20:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Running setup.py install for mozinfo 04:20:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Running setup.py install for mozInstall 04:20:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Running setup.py install for mozleak 04:20:56 INFO - Running setup.py install for mozlog 04:20:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:20:56 INFO - Running setup.py install for moznetwork 04:20:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Running setup.py install for mozprocess 04:20:57 INFO - Running setup.py install for mozprofile 04:20:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Running setup.py install for mozrunner 04:20:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Running setup.py install for mozscreenshot 04:20:57 INFO - Running setup.py install for moztest 04:20:57 INFO - Running setup.py install for mozversion 04:20:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:20:57 INFO - Cleaning up... 04:20:58 INFO - Return code: 0 04:20:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:58 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')]} 04:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:58 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')]} 04:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:20:58 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:20:58 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 04:20:58 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 04:20:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:20:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:58 INFO - 'HOME': '/Users/cltbld', 04:20:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:58 INFO - 'LOGNAME': 'cltbld', 04:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:58 INFO - 'MOZ_NO_REMOTE': '1', 04:20:58 INFO - 'NO_EM_RESTART': '1', 04:20:58 INFO - 'PAGER': '/bin/cat', 04:20:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:58 INFO - 'PWD': '/builds/slave/test', 04:20:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:58 INFO - 'SHELL': '/bin/bash', 04:20:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:20:58 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:20:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:58 INFO - 'USER': 'cltbld', 04:20:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:58 INFO - 'XPC_FLAGS': '0x0', 04:20:58 INFO - 'XPC_SERVICE_NAME': '0', 04:20:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-kwcAxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:58 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)) 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-entUVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:58 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)) 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Mle23N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:58 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)) 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8lR13w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:58 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)) 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-72UA62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:58 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)) 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-p2TkDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:58 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)) 04:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:58 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8FDmGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-sIpcJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TlFLsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-pNDqKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-KGpzDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-atL_X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-2omY3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-NMeLXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-o5fL39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Yws1ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:59 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-t5M0QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:00 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)) 04:21:00 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)) 04:21:00 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)) 04:21:00 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)) 04:21:00 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)) 04:21:00 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)) 04:21:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:02 INFO - Downloading blessings-1.6.tar.gz 04:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:21:02 INFO - Installing collected packages: blessings 04:21:02 INFO - Running setup.py install for blessings 04:21:02 INFO - Successfully installed blessings 04:21:02 INFO - Cleaning up... 04:21:02 INFO - Return code: 0 04:21:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:21:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:03 INFO - Reading from file tmpfile_stdout 04:21:03 INFO - Current package versions: 04:21:03 INFO - blessings == 1.6 04:21:03 INFO - blobuploader == 1.2.4 04:21:03 INFO - docopt == 0.6.1 04:21:03 INFO - functools32 == 3.2.3-2 04:21:03 INFO - jsonschema == 2.5.1 04:21:03 INFO - manifestparser == 1.1 04:21:03 INFO - mozInstall == 1.12 04:21:03 INFO - mozcrash == 0.17 04:21:03 INFO - mozdebug == 0.1 04:21:03 INFO - mozdevice == 0.48 04:21:03 INFO - mozfile == 1.2 04:21:03 INFO - mozhttpd == 0.7 04:21:03 INFO - mozinfo == 0.9 04:21:03 INFO - mozleak == 0.1 04:21:03 INFO - mozlog == 3.3 04:21:03 INFO - moznetwork == 0.27 04:21:03 INFO - mozprocess == 0.23 04:21:03 INFO - mozprofile == 0.28 04:21:03 INFO - mozrunner == 6.12 04:21:03 INFO - mozscreenshot == 0.1 04:21:03 INFO - mozsystemmonitor == 0.3 04:21:03 INFO - moztest == 0.7 04:21:03 INFO - mozversion == 1.4 04:21:03 INFO - psutil == 3.1.1 04:21:03 INFO - requests == 1.2.3 04:21:03 INFO - wsgiref == 0.1.2 04:21:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:03 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:21:03 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 04:21:03 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 04:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:03 INFO - 'HOME': '/Users/cltbld', 04:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:03 INFO - 'LOGNAME': 'cltbld', 04:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:03 INFO - 'MOZ_NO_REMOTE': '1', 04:21:03 INFO - 'NO_EM_RESTART': '1', 04:21:03 INFO - 'PAGER': '/bin/cat', 04:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:03 INFO - 'PWD': '/builds/slave/test', 04:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:03 INFO - 'SHELL': '/bin/bash', 04:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:21:03 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:03 INFO - 'USER': 'cltbld', 04:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:03 INFO - 'XPC_FLAGS': '0x0', 04:21:03 INFO - 'XPC_SERVICE_NAME': '0', 04:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 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)) 04:21:03 INFO - Cleaning up... 04:21:03 INFO - Return code: 0 04:21:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:03 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 0x10acd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868ac9d3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, '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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 04:21:03 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 04:21:03 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 04:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:03 INFO - 'HOME': '/Users/cltbld', 04:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:03 INFO - 'LOGNAME': 'cltbld', 04:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:03 INFO - 'MOZ_NO_REMOTE': '1', 04:21:03 INFO - 'NO_EM_RESTART': '1', 04:21:03 INFO - 'PAGER': '/bin/cat', 04:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:03 INFO - 'PWD': '/builds/slave/test', 04:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:03 INFO - 'SHELL': '/bin/bash', 04:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:21:03 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:03 INFO - 'USER': 'cltbld', 04:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:03 INFO - 'XPC_FLAGS': '0x0', 04:21:03 INFO - 'XPC_SERVICE_NAME': '0', 04:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:21:03 INFO - Cleaning up... 04:21:04 INFO - Return code: 0 04:21:04 INFO - Running post-action listener: _resource_record_post_action 04:21:04 INFO - Running post-action listener: _start_resource_monitoring 04:21:04 INFO - Starting resource monitoring. 04:21:04 INFO - [mozharness: 2016-08-08 11:21:04.034987Z] Finished create-virtualenv step (success) 04:21:04 INFO - [mozharness: 2016-08-08 11:21:04.035771Z] Running install step. 04:21:04 INFO - Running pre-action listener: _resource_record_pre_action 04:21:04 INFO - Running main action method: install 04:21:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:04 INFO - Reading from file tmpfile_stdout 04:21:04 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:04 INFO - Reading from file tmpfile_stdout 04:21:04 INFO - Output received: 04:21:04 INFO - Usage: mozinstall [options] installer 04:21:04 INFO - Options: 04:21:04 INFO - -h, --help show this help message and exit 04:21:04 INFO - -d DEST, --destination=DEST 04:21:04 INFO - Directory to install application into. [default: 04:21:04 INFO - "/builds/slave/test"] 04:21:04 INFO - --app=APP Application being installed. [default: firefox] 04:21:04 INFO - mkdir: /builds/slave/test/build/application 04:21:04 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'] 04:21:04 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 04:21:38 INFO - Reading from file tmpfile_stdout 04:21:38 INFO - Output received: 04:21:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:21:38 INFO - Running post-action listener: _resource_record_post_action 04:21:38 INFO - [mozharness: 2016-08-08 11:21:38.894366Z] Finished install step (success) 04:21:38 INFO - [mozharness: 2016-08-08 11:21:38.894548Z] Running run-tests step. 04:21:38 INFO - Running pre-action listener: _resource_record_pre_action 04:21:38 INFO - Running main action method: run_tests 04:21:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:21:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:21:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:21:38 INFO - 2016-08-08 04:21:38.909 screenresolution[1636:7403] starting screenresolution argv=screenresolution get 04:21:38 INFO - 2016-08-08 04:21:38.911 screenresolution[1636:7403] Display 0: 1600x1200x32@60 04:21:38 INFO - 2016-08-08 04:21:38.917 screenresolution[1637:7405] starting screenresolution argv=screenresolution list 04:21:38 INFO - Available Modes on Display 0 04:21:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:21:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:21:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:21:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:21:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:21:39 INFO - Intel Iris: 04:21:39 INFO - Chipset Model: Intel Iris 04:21:39 INFO - Type: GPU 04:21:39 INFO - Bus: Built-In 04:21:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:21:39 INFO - Vendor: Intel (0x8086) 04:21:39 INFO - Device ID: 0x0a2e 04:21:39 INFO - Revision ID: 0x0009 04:21:39 INFO - Displays: 04:21:39 INFO - Display: 04:21:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:21:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:21:39 INFO - Display Serial Number: 1600x1200 60 04:21:39 INFO - Main Display: Yes 04:21:39 INFO - Mirror: Off 04:21:39 INFO - Online: Yes 04:21:39 INFO - Rotation: Supported 04:21:39 INFO - Return code: 0 04:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:21:39 INFO - Python 2.7.3 04:21:39 INFO - Return code: 0 04:21:39 INFO - grabbing minidump binary from tooltool 04:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:39 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 0x10ac3ad30>, '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 0x10a99fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:21:39 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 04:21:39 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 04:21:39 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 04:21:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:21:39 INFO - Return code: 0 04:21:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:21:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:21:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:21:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:21:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 04:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 04:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cZZhNwZ7t3/Render', 04:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:39 INFO - 'HOME': '/Users/cltbld', 04:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:39 INFO - 'LOGNAME': 'cltbld', 04:21:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:21:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:39 INFO - 'MOZ_NO_REMOTE': '1', 04:21:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:21:39 INFO - 'NO_EM_RESTART': '1', 04:21:39 INFO - 'PAGER': '/bin/cat', 04:21:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:39 INFO - 'PWD': '/builds/slave/test', 04:21:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:39 INFO - 'SHELL': '/bin/bash', 04:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KhvoZnnNEv/Listeners', 04:21:39 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 04:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:39 INFO - 'USER': 'cltbld', 04:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:39 INFO - 'XPC_FLAGS': '0x0', 04:21:39 INFO - 'XPC_SERVICE_NAME': '0', 04:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 04:21:39 INFO - mozversion application_buildid: 20160808012421 04:21:39 INFO - mozversion application_changeset: f8fc21bb232095b7b4c995d259558212cb90a44b 04:21:39 INFO - mozversion application_display_name: Nightly 04:21:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:21:39 INFO - mozversion application_name: Firefox 04:21:39 INFO - mozversion application_remotingname: firefox 04:21:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:21:39 INFO - mozversion application_vendor: Mozilla 04:21:39 INFO - mozversion application_version: 51.0a1 04:21:39 INFO - mozversion platform_buildid: 20160808012421 04:21:39 INFO - mozversion platform_changeset: f8fc21bb232095b7b4c995d259558212cb90a44b 04:21:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:21:39 INFO - mozversion platform_version: 51.0a1 04:21:39 INFO - using testdate: 1470655299 04:21:39 INFO - actual date: 1470655299 04:21:39 INFO - starting webserver on 'localhost:49279' 04:21:39 INFO - SUITE-START | Running 2 tests 04:21:39 INFO - TEST-START | dromaeo_css 04:21:39 INFO - operating with platform_type : mac_ 04:21:39 INFO - Initialising browser for dromaeo_css test... 04:21:39 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmp4KptY2/profile http://localhost:49279/getInfo.html) 04:21:41 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 04:21:41 INFO - PROCESS | 1647 | colorDepth:24 04:21:41 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 04:21:41 INFO - PROCESS | 1647 | __metrics 04:21:41 INFO - PROCESS | 1647 | 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" 04:21:41 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:21:42 INFO - TEST-INFO | 1647: exit 0 04:21:42 INFO - Browser initialized. 04:21:42 INFO - Running cycle 1/1 for dromaeo_css test... 04:21:42 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmp4KptY2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:21:54 INFO - PROCESS | 1649 | RSS: Main: 243425280 04:21:54 INFO - PROCESS | 1649 | 04:22:45 INFO - PROCESS | 1649 | 1470655365627 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] 04:24:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 04:25:00 INFO - PROCESS | 1649 | RSS: Main: 378363904 04:25:00 INFO - PROCESS | 1649 | 04:28:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 04:28:03 INFO - PROCESS | 1649 | RSS: Main: 386719744 04:28:03 INFO - PROCESS | 1649 | 04:31:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 04:31:06 INFO - PROCESS | 1649 | RSS: Main: 361398272 04:31:06 INFO - PROCESS | 1649 | 04:34:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 04:34:09 INFO - PROCESS | 1649 | RSS: Main: 351911936 04:34:09 INFO - PROCESS | 1649 | 04:37:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 04:37:13 INFO - PROCESS | 1649 | RSS: Main: 346341376 04:37:13 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 04:40:17 INFO - PROCESS | 1649 | RSS: Main: 505831424 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | __start_tp_report 04:40:17 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 04:40:17 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 04:40:17 INFO - PROCESS | 1649 | |i|pagename|runs| 04:40:17 INFO - PROCESS | 1649 | |0;dojo.html;3322.368749937512;3322.717569006634;3336.4661654135343;3345.882894098707;3378.375000624883;5633.859153521164;5675.5459563234945;5696.231008813809;5747.568932330081;5749;5662.518685911702;5740.454656807597;5762.596618236725;5774.769009239625;5822.563307751914;5172.6379153459275;5225.869353266161;5233.241180028894;5251.737413129328;5340.973295133538;5336;5339.70631615261;5368.033753924292;5372.54333381663;5390.811321603745;4289.485261768607;5169.327987361657;5233.371995360543;5275.762590683409;5277.445868183824;5156.613254005964;5201.349831271091;5265.104932161542;5277.1820367842665;5287.391949925769;5180.067587834188;5180.222966555009;5231.189165679326;5254.106801843695;5265.631405801595;5774.335951365562;5795.507381872546;5811.128330750379;5817.505512031483;5821.50517206038;6112.969435152841;6150.630962142241;6278.654673992928;6282.994720844643;6298.716557754912;6232.594881332744;6259.843503912393;6304.590201636879;6325.968370158166;6329.240491141047;5888.941110588882;5901.468867801833;5910.320313164041;5913.083472061838;5913.881722365529;6185.505159587222;6202.906956395657;6260.280067792262;6299.842503937438;6305.148804911279;5535.640183388147;5868.67722275279;6194.969025154845;6206.100115483229;6225.470834979077;6263.874722505524;6282.937170628235;6302.779402720883;6310.5267104967315;6311.905321420182;6051.7579296827635;6127.234095738033;6131.437835141539;6213.316535181127;6236.158118653923;5692.686902220417;6016.1878146450545;6281.905771413432;6293.118963345135;6300.338464461257;4960.652754307164;4962.9751851241235;4963.131451995886;4967.528084831935;4969.006198760262;4427.512973572904;4431.911361772811;4484.349769283435;4488.057507923308;4507;5670.8015219467125;5688.317401911714;5702;5706.172604972256;5713.314402271754;5256.553192978639;6232.407921247474;6292.590981586274;6294.150289710922;6333.873322533524;5712.828615141552;5720.513756330675;5721.342045664802;5735.913961290584;5737.942620573825;5337.146056630921;5431.429699881455;5440.836774896759;5466.835994920159;5468;6198.81403557903;6207.81376558704;6224.9377506224355;6290.716917738804;6314;6299.023651334051;6332.620042797263;6334.461570766536;6336.334684858023;6349.111124442489;6152.938470615416;6276.309605943434;6283.2460104787715;6299.905501417574;6313.526485513513;6105.725242364016;6120.0205129226715;6167.414095660905;6243.157173781481;6244.313125556095;6311.211098612674;6322.49420046386;6323.304436511888;6326.778562750282;6341.651209183539;5991;6162.22972128484;6172.876542469216;6189.659568723583;6197.690115494297;6059.182010428536;6133.325334213322;6141.263048434216;6165.815025549241;6174.197354343906;6011.458968692838;6016.278046634432;6063.848403789941;6085.3306136325855;6086.847828804315;5443.918341224964;5925.4370834770625;5945.64326140433;5992.700364981821;6205.317415084247;5949.672767997802;5982.7307771149535;5984.97007514977;6025.548083893638;6076.33160352352 04:40:17 INFO - PROCESS | 1649 | |1;ext.html;509.61014823659906;516.8475299786563;523.6439221329496;532.5606374740837;542.4891676680446;26780.598291025624;39389.40915886264;40723.796381018125;40840.18319633605;40923.795381023054;41483.377749333784;41808;41853.953651158736;41875.79062104693;41994.58005419945;9551.0926461986;9739.318247722662;9758.365706229073;9765.267604929622;9779.315447918649;8704.825903481926;8920.152585504367;11558.942205288962;11741.236819606698;11752.294862308288;11513.884861151408;11544.076473882069;11574.826377604342;11578.073754099652;11588;11662.950334469897;11739.706507337385;11741.706457338549;11748.00141987932;11751.823722644167;11712.472938717692;11792.174547781658;11794.882051179464;11807.291562506278;11812.232204906653;39132.02169945746;39219.21561568753;39328.410073848914;39329.80335098335;39343.016424589325;3349.179451034512;3375.8143302118374;3436.1066122808;3497.667721566447;3954.6638535724496;27931.441371172463;28877.278068048257;31528.211794705087;31957.360852782815;32006.6799332006;3134.905952821396;3179.109849242193;3217.8230197339135;3260.8532616032335;3343.4650455927176;430869;432261;432950;433090.8345458284;433333.33333333157;32656.020319390456;33233.66766332354;55619.721901390236;55818;55926.440735592936;34955.82522087373;35070.12324691852;35100.64899351025;35138;35150;14514;14674.55976320712;14705;14711.705765884837;14727.631809204642;11200.271982321123;12425.937870310772;12480.75038499243;12522.872941435315;12531.74936501283;7214.819629509304;7545;7600.733974310983;7655.846883062419;7834.569098699626;3159.5418664294007;3201.695838895301;3209.165616939566;3224.8548815303366;3238.9838050809594;884.8363052835246;895.1853813030111;927.3693888156118;972.0473935543207;1037.387941114746;27232.183034508995;29803.850980745392;30185.943491978116;30487.085387438412;30516.084517464067;932.8292992294627;1014.2545229256491;1025.5282570017876;1030.3611760708409;1151.9574784819752;14928.477503287333;15230.086194828345;15273.38906443752;15625.60935976587;15633.530994070195;612593;687427;694065;696089;916661;57718.422815772145;58581.70709146427;58607.70696146492;58751;58809.41190588125;1180.350807056133;1238.5107882386587;1363.6863521389937;1375.0786972728297;1408.190290582919;23138.537229255657;29423.55864661989;30412;31013.68986310108;31609.683903161593;53588.196177058155;54113.91772164613;54118;54314.45685543094;54330.72834635802;601645;602568;602808;606414;609393.953030232;25809.225723228334;26964.303569642878;27068.052618158268;27075.72924270811;27078.593821092305;36397;37083.814580926926;37591.060223494635;44959;46761.06477870492;37569.62430375661;37823.243535129695;37984.24031519299;38889;38995.83012509629;36198.8190059048;36716.44925326176;37125.814370927976;37455;38489.422658660704;36511.634883650826;36645.63354366529;36682.44976325411;37953.81023094867;38544.03639909025;26338.473230535667;26861.731382685924;34731.81623356522;34876.779312723935;35131.64868351284;23887.64168537508;25606.975720970953;25650.230493085237;25970.35074123162;26054.86972565125 04:40:17 INFO - PROCESS | 1649 | |2;jquery.html;310.8403082664713;338.5463478938222;365.265815710422;385.5951251186254;393.2037623811781;19977.001149942498;20368.592628147428;20411.979401029927;20943.05756240969;21137.57724845502;20220.797792022076;20359.38921832344;20561.794382056174;20761.688574671356;20820.8958955205;5697.259356283677;5705;5961.433663801932;5971.313298970609;6004.759809607611;3865.246276975991;6030.246219222598;6066.332703402622;6069.635821850681;6076.96961515194;4052.5744796796353;5733.856653583673;5975.133605627181;6016.42843929826;6103.115048317991;5860.120981852713;5918.8816223675285;5922.022866227067;6041.093835924603;6046.213992181032;5770.682612456313;5793.449622285876;5795.101759227285;5797.36229014808;5799.043157878987;12872.356382180853;23106.76893231063;25032.370306305667;25485.745142548523;25505.107321243853;2126.2345555599973;2128.627490189226;2181.945451363713;2193.2338735002377;2894.768418526513;9893.604255829758;9893.90106098937;9958.95430979744;10178.44018578978;10347.379157250589;1776.564741638294;1810.4930619426582;1816.7910690270655;1855.6381505606412;1890.9243630254912;84627.5768621153;111672.4416377921;112961.19489530199;118926;119212;11362;11363.943180284212;11531.88468115325;11538.94230528842;11695.473703683185;14487.78268325976;14515.709685806225;14520.782188267185;14544.345504452325;14559.854401456061;8343.498170329278;8495.702650407206;8506.957465212634;8549.358798090043;8638.568071596395;8426.705065322683;8437.143828357752;8587.828243435097;8592.699255526026;8627.870581941275;4324.243257429937;4408.096340250241;4422.380866678667;4492.685512014193;4509.88725281864;1601.7116918954466;1830.6704793137362;1891.2340502096672;1903.1530968719064;1903.257729485474;706.6576680296869;728.559221670892;747.439420434674;852.3364485981308;918.6049998500708;20202.797972020384;20274.493137671383;20857.957102144832;21796.564068718853;21858.562828743336;874.3879284500874;898.9820203595891;899.9055099214619;991.5884907763198;994.6170724271208;9762.267829912786;11230;11270.830937535944;11299.604513842141;11306.038986686224;504303;586033;602533;609437;615969;76841.61579192069;77292;113042;127955;140223;1000.1698590170141;1006.7684432580401;1017.4658304390255;1029.2177944761886;1033.6278939581896;18360.449186524427;18807.811921880606;18815.341463048728;18848.52878678044;18853.057347132315;52733.47266527439;53912.460875390745;54115;55356.44643553513;56825.715871420376;81214;82240.58879705564;83471.5826420864;83748.7437688423;85474;5633.718314084362;7825.209345084711;8041.276285134366;8070.596470176585;8298.543580103153;10267;10779.24545281854;12437.93024208437;12532.248065116122;12910.419031143809;10542.683719488428;10565.735856603646;10701.357918524913;10755.462226888469;11564.190506664789;9104.998450170608;10918.290311129751;10970.890291096986;11170.944145279222;11410.973012428922;10139.548323776013;10888.564457421615;11150.442477876237;11256.718582035515;12004.939975300067;7891.171427000312;7977.361811055218;8248.46384984974;8322.37582181351;8446.788830279293;10689.51897164645;11135.276207046516;11402.20184587071;11480.712982175179;11666.066714662637 04:40:17 INFO - PROCESS | 1649 | |3;mootools.html;1497.7079469503449;1514.0234548716078;1707.5731067233191;1861.2275905499218;1882.5481884347753;8149.185081491848;8676.349273804459;8691.869621955668;8695.391322607413;8699.869501957475;6864.862702745954;7685.961570192141;7830.099538553068;7948.7217947371855;8486.193811587904;2257.525919556895;2346.038124369009;2348.0276567683145;2348.4852270285655;2360.5868972929757;2316.420894776306;2335.346103091137;2361.268006917861;2361.586722323595;2364.113457453455;1966.783653798081;2334.7898689117974;2341.84777989431;2341.94145146371;2347.542229265294;2289.3933107726384;2324.6745455636224;2325.8255630827757;2327.290176496168;2330.172788660035;1966.4199061276959;2272;2319.1651005637955;2329.4409341758023;2333.043452184613;8560.186782255676;8696;8698.304135669194;8702.434341767765;8717.476951382938;5050.368703912011;5217.982493413786;5219;5220.347456567929;5230.686158830445;3057.8623961921767;3438.5813148788966;3527.805970671612;3594.11944073701;3599.1362073102528;988.9802203955952;1036.75117971687;1058.925875188737;1060.0671409159968;1061.8247989081792;11280.492377843017;11319;11341.206115571913;11344.319340839576;11348.886511134866;4237.152569486115;4346.304591265382;4451.59935605797;4509.526499717548;4705.60002399793;9683.709488715349;9703.708888733348;9707.174890134276;9710;9714.951425242829;5183.377994640668;5188.507091826271;5188.558972487305;5193.506616871391;5196.012757576049;4646.581807637262;4687.20317546018;4747.833825816149;4749.762511874393;4756.762161891892;3883.5533913600298;3896.7467114638025;3912.902177445587;3949.1706741584017;4039.313316736162;1268.5560053981033;1269.9873001270055;1277.9297138657278;1283.3711481374246;1284.3000564692265;752.7403045949138;854.0349405172116;864.481311213267;864.5893200729603;877.8595424732013;5354.759035843396;5470.152126420411;5797.710114494343;5806.361300256968;5834.824955251349;883.911608839111;913.917747402737;937.6999360204669;944.2964991081689;957.2103015012642;3348.748843836722;3748.3065632857465;3763.5671897731563;3857.2285542890972;3866.3040652682225;10243.84634230487;10608.469576521144;10614.946925265323;10620;10657.467126643636;10483.68548943533;10586.52360643773;10594.152467802558;10595.788084238273;10596.470176491144;6245.437910588069;7999.1600881907725;8039.356851451987;8127.106018337978;8133.2680058794995;5567.47109024642;5817.371136081903;5865.912011319919;5869.853253668693;5907.261592300963;9466.432014079177;9655.662051828152;9769.853452198366;9825.213982881209;9915.30592858493;9724.659636912675;9929.453880036668;9942.353747006422;10076.395416275047;10131.7467063324;6506.275836903319;6640.734370625314;6833.521653484208;6836.077129587442;6913.066735990778;7672.923270767221;8135.959320203361;8204.302634276153;8287.668493260444;8292.917070829215;7328.120625524758;7413.518121322097;7427.554346739213;7437.367823735043;7514;7226.060612120391;7268.491205615556;7285.016522769571;7301.926980730124;7357.742479013209;6664.266930637723;6732.091167692299;6742;6806.455483561204;6811.182658080864;4718.433787945469;4779.713217206979;4789.946211833391;4800.543948324904;4926.852194434173;3985.9636494511055;4742.549457801503;4867;4917.336159618406;4978.054169707744 04:40:17 INFO - PROCESS | 1649 | |4;prototype.html;292.9868155932983;301.36712902903906;306.2817692511061;308.39092791736323;311.2809410262293;4764.809407623691;5353.812616558412;5450.892990995134;5451;5456.399796022434;4756.4564399522405;4985.507246376812;5339.492748188925;5374.7850085996515;5417.59368047396;3316.651751566087;3803.8098095095274;3804.752691075071;4026.4161696553983;4038.3336749436307;3309.7683162178537;3583.982080089596;3916.040570060339;4032.919341613166;4101.958980410188;3541.9905326981816;4002.599740026003;4005.5794141615147;4025.496812898388;4043.2924238258333;4024.9472768887786;4028.979855100735;4029;4038.4749982502235;4048.210598933209;2437.439388940536;2865.8423786691724;3418.4872269159578;3421;3514.859405623772;5085.567726743231;5120.539151476385;5120.590352771769;5129.512696293846;5139.665921715114;4009;4535.455745310584;4545.818167273305;4563.543645635443;4613.538646135393;4544.227481328182;4602.585767280927;5274.92087618686;5376.580538757575;5494.587905907073;3860.652541271271;3863.420486926955;3864.8840534784003;3885.922281554353;3891.6692081173132;5419.458054194589;5458.590605704549;5533.972330138364;5608.915866262009;5730.885382292373;3389.1357703785375;3838.5201849768778;3861.555921069113;3920.0787814863484;3924.843006279774;3333;3962.663173630274;4016.758994460342;4024.8591299304385;4037.6366127048705;3915.3735402335487;4193.7903104845245;4528.682992190515;4614.74618895964;4620.19146649335;4906;5302.840914772563;5543.196236545759;5551.805686800943;5578.44215578447;1026.4970164619283;1237.5482948723652;1318.9736205276033;1327.6680829792551;1331.2678027085065;753.3689562098106;764.10217993857;804.3163311185446;819.8319344534356;824.3116997307316;3658.70730341572;4025.8993525162105;4208.6633069354375;4340.502526628308;4413.9323170396265;4016.5180178378782;4695.483496815347;4713.457952335457;4774.761261936889;4814.927776083362;3731;3979.467904856593;4110.671146308288;4333.956660433419;4359.80380882861;2468.43254533369;2745.752882240608;2942.941141177207;3026.9091927242216;3065.1877252528097;5216.7913283469;5967.373425790316;5985.760569577255;5993.220881285405;6014;4767.155823225029;5191.766370513336;5464.9779581554285;5895.734691938873;5918.319393269743;4994.375703037121;5854.648721076749;5891.558133140117;5920.2895652521975;5942.7920022799;5321.494458026481;5703.51896924839;5798.376014990631;5824.883502330014;5848.549355880051;5359.3032905722;5361.839144825662;5620.831375058755;5644.745986430538;5652.830415087554;4712.198926182626;5402.864928376822;5573.191887176417;5700.97615346546;5878.177055212359;3605.531280933462;3708.2212735324806;3738.943915841319;3756.887293381203;3776.734793843983;4836.661433699607;5609.943900560942;5613.270274864241;5636.915446268391;5645.435456454322;4842.757862106951;5334.093204155381;5381.596380271573;5427.972860135674;5508.724563771875;5212.810619539879;5348;5432.29380180574;5456.263404440509;5468.6875149962925;4543.79552920126;4673.859784206479;5037.647364684437;5379.919301210563;5404.270423492935;4749.335093087039;5563.471470210324;5571.526420254324;5626.578006649435;5749.195112684144;5119.846404607868;5491.697956612425;5524.613277070566;5596.964561556125;5636.85907852307 04:40:17 INFO - PROCESS | 1649 | |5;yui.html;709.5990765217651;725.5429079679801;765.6592816196791;805.3999770171223;819.6106849246606;16119.113448760314;16554.58613534659;16569.00585964843;16584.751228731562;16596.751048734277;13092.6252743462;14264.928675356634;14774.852251477481;14840.332185051671;14897.25513724312;673.9595624262536;693.7329128285621;817.3706246190429;822.1737154160061;847.3687103108193;829.5396055189367;832.7793134819278;842.0779246724826;843.0936743001267;846.8306338732248;888.6134531478795;889.3907673243849;889.9243564297043;889.973300800977;890.6437425030008;687.4603436302478;839.8614228652265;883.924866386358;883.9292856571523;886.366248132585;660.6135410784697;662.6454846657064;663.6482664187988;664.7806223946086;665.4177594604721;14815.111093334435;14834.925825370912;14835.777463338058;14873.405063797436;14900.70198596022;13803.792943105862;14051.929740351336;14110.435582576685;14131.505397311044;14341.139531628136;12832.101752877303;12873.613791586267;12988.675283117904;13238.470461181541;13270.734585308337;4183.267928112598;4648.418947631546;4694.929576056362;4771.188897887333;4871.975640121812;93291.60062599067;94861.52569237178;98789.51815722769;123749;123820;11215;11342.432878356214;11490.770184596262;11679.182457228082;11710.180287379802;17842.28630854751;21928.013239404267;21960;21987;21994;8057.758267251992;8164.877526836984;8298.21166989135;8347.290480309242;8353.874691879626;4150.750954942713;4416.84541041062;4561.566651168134;4690;4714.693544919569;3619.8371073301764;3724.6647801697977;3766.209096089797;3767.3783825669007;3768.453574231721;666.3902529185741;668.9933100669028;673.1518286958468;688.721067967474;689.6655122265697;487.7804987755524;552.9087700529368;562.3111688182026;569.2485918587424;655.3413819111762;7223.963880180566;8515.57422128891;9269.521410579318;9460.57427415768;9465.763355916157;581.2530897796312;596.9343372229051;637.2956497644751;655.4854439265249;659.1562799616498;4333.2486411893;4350.434443522385;4646.390181213232;4656.976715116471;4746.525347465225;93934;105991;152887.23556382148;164768;168415.31584684242;103723;141973;147052.5294747039;147089.5291047076;149695;123979;125921.74078259284;126441.45923218579;126690.36654816852;126798.99999999815;8760.51817150063;8776.473411595325;8788.472691638523;8916.375853690179;8940.597673104856;47318.76340618275;56215;59701.70149149226;62619.06071408842;63873.680631596544;73783.63108184424;80007.59996199982;81131.18868811236;90862.54568727114;95893.5205323969;13194.538191163263;13307.733845323233;13352.532661356807;13548.79676804829;14102.788458173341;11975.461104250504;12333.198342107735;12538.185017973803;12796.552120675731;13196.406161722938;12130.878691212974;12189.207701499374;12224.877751222373;12491.93754031224;12536.37318134108;12248.203866748632;12434.689132771391;13464.25946572948;13618.795718064435;14174.220417877115;13339.399727012114;13602.795958060835;13643.386047627682;13907.165570065828;14019.929900350433;11421.347697042835;11452.713682158013;11464.94267528657;11540.826887596524;11970.76058478843;9131;10661.281373249773;11136.702074853698;11779.823302650295;11790.23363481371 04:40:17 INFO - PROCESS | 1649 | __end_tp_report 04:40:17 INFO - PROCESS | 1649 | __start_cc_report 04:40:17 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1436 04:40:17 INFO - PROCESS | 1649 | __end_cc_report 04:40:17 INFO - PROCESS | 1649 | __startTimestamp1470656417516__endTimestamp 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | ------- Summary: start ------- 04:40:17 INFO - PROCESS | 1649 | Number of tests: 6 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5754.54 Median:5949.67 stddev:643.38 (10.8%) stddev-sans-first:616.41 04:40:17 INFO - PROCESS | 1649 | Values: 3322.4 3322.7 3336.5 3345.9 3378.4 5633.9 5675.5 5696.2 5747.6 5749.0 5662.5 5740.5 5762.6 5774.8 5822.6 5172.6 5225.9 5233.2 5251.7 5341.0 5336.0 5339.7 5368.0 5372.5 5390.8 4289.5 5169.3 5233.4 5275.8 5277.4 5156.6 5201.3 5265.1 5277.2 5287.4 5180.1 5180.2 5231.2 5254.1 5265.6 5774.3 5795.5 5811.1 5817.5 5821.5 6113.0 6150.6 6278.7 6283.0 6298.7 6232.6 6259.8 6304.6 6326.0 6329.2 5888.9 5901.5 5910.3 5913.1 5913.9 6185.5 6202.9 6260.3 6299.8 6305.1 5535.6 5868.7 6195.0 6206.1 6225.5 6263.9 6282.9 6302.8 6310.5 6311.9 6051.8 6127.2 6131.4 6213.3 6236.2 5692.7 6016.2 6281.9 6293.1 6300.3 4960.7 4963.0 4963.1 4967.5 4969.0 4427.5 4431.9 4484.3 4488.1 4507.0 5670.8 5688.3 5702.0 5706.2 5713.3 5256.6 6232.4 6292.6 6294.2 6333.9 5712.8 5720.5 5721.3 5735.9 5737.9 5337.1 5431.4 5440.8 5466.8 5468.0 6198.8 6207.8 6224.9 6290.7 6314.0 6299.0 6332.6 6334.5 6336.3 6349.1 6152.9 6276.3 6283.2 6299.9 6313.5 6105.7 6120.0 6167.4 6243.2 6244.3 6311.2 6322.5 6323.3 6326.8 6341.7 5991.0 6162.2 6172.9 6189.7 6197.7 6059.2 6133.3 6141.3 6165.8 6174.2 6011.5 6016.3 6063.8 6085.3 6086.8 5443.9 5925.4 5945.6 5992.7 6205.3 5949.7 5982.7 5985.0 6025.5 6076.3 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:69910.66 Median:35085.39 stddev:161554.47 (460.5%) stddev-sans-first:161923.64 04:40:17 INFO - PROCESS | 1649 | Values: 509.6 516.8 523.6 532.6 542.5 26780.6 39389.4 40723.8 40840.2 40923.8 41483.4 41808.0 41854.0 41875.8 41994.6 9551.1 9739.3 9758.4 9765.3 9779.3 8704.8 8920.2 11558.9 11741.2 11752.3 11513.9 11544.1 11574.8 11578.1 11588.0 11663.0 11739.7 11741.7 11748.0 11751.8 11712.5 11792.2 11794.9 11807.3 11812.2 39132.0 39219.2 39328.4 39329.8 39343.0 3349.2 3375.8 3436.1 3497.7 3954.7 27931.4 28877.3 31528.2 31957.4 32006.7 3134.9 3179.1 3217.8 3260.9 3343.5 430869.0 432261.0 432950.0 433090.8 433333.3 32656.0 33233.7 55619.7 55818.0 55926.4 34955.8 35070.1 35100.6 35138.0 35150.0 14514.0 14674.6 14705.0 14711.7 14727.6 11200.3 12425.9 12480.8 12522.9 12531.7 7214.8 7545.0 7600.7 7655.8 7834.6 3159.5 3201.7 3209.2 3224.9 3239.0 884.8 895.2 927.4 972.0 1037.4 27232.2 29803.9 30185.9 30487.1 30516.1 932.8 1014.3 1025.5 1030.4 1152.0 14928.5 15230.1 15273.4 15625.6 15633.5 612593.0 687427.0 694065.0 696089.0 916661.0 57718.4 58581.7 58607.7 58751.0 58809.4 1180.4 1238.5 1363.7 1375.1 1408.2 23138.5 29423.6 30412.0 31013.7 31609.7 53588.2 54113.9 54118.0 54314.5 54330.7 601645.0 602568.0 602808.0 606414.0 609394.0 25809.2 26964.3 27068.1 27075.7 27078.6 36397.0 37083.8 37591.1 44959.0 46761.1 37569.6 37823.2 37984.2 38889.0 38995.8 36198.8 36716.4 37125.8 37455.0 38489.4 36511.6 36645.6 36682.4 37953.8 38544.0 26338.5 26861.7 34731.8 34876.8 35131.6 23887.6 25607.0 25650.2 25970.4 26054.9 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:33864.22 Median:14199.94 stddev:97319.61 (685.4%) stddev-sans-first:97560.01 04:40:17 INFO - PROCESS | 1649 | Values: 310.8 338.5 365.3 385.6 393.2 19977.0 20368.6 20412.0 20943.1 21137.6 20220.8 20359.4 20561.8 20761.7 20820.9 5697.3 5705.0 5961.4 5971.3 6004.8 3865.2 6030.2 6066.3 6069.6 6077.0 4052.6 5733.9 5975.1 6016.4 6103.1 5860.1 5918.9 5922.0 6041.1 6046.2 5770.7 5793.4 5795.1 5797.4 5799.0 12872.4 23106.8 25032.4 25485.7 25505.1 2126.2 2128.6 2181.9 2193.2 2894.8 9893.6 9893.9 9959.0 10178.4 10347.4 1776.6 1810.5 1816.8 1855.6 1890.9 84627.6 111672.4 112961.2 118926.0 119212.0 11362.0 11363.9 11531.9 11538.9 11695.5 14487.8 14515.7 14520.8 14544.3 14559.9 8343.5 8495.7 8507.0 8549.4 8638.6 8426.7 8437.1 8587.8 8592.7 8627.9 4324.2 4408.1 4422.4 4492.7 4509.9 1601.7 1830.7 1891.2 1903.2 1903.3 706.7 728.6 747.4 852.3 918.6 20202.8 20274.5 20858.0 21796.6 21858.6 874.4 899.0 899.9 991.6 994.6 9762.3 11230.0 11270.8 11299.6 11306.0 504303.0 586033.0 602533.0 609437.0 615969.0 76841.6 77292.0 113042.0 127955.0 140223.0 1000.2 1006.8 1017.5 1029.2 1033.6 18360.4 18807.8 18815.3 18848.5 18853.1 52733.5 53912.5 54115.0 55356.4 56825.7 81214.0 82240.6 83471.6 83748.7 85474.0 5633.7 7825.2 8041.3 8070.6 8298.5 10267.0 10779.2 12437.9 12532.2 12910.4 10542.7 10565.7 10701.4 10755.5 11564.2 9105.0 10918.3 10970.9 11170.9 11411.0 10139.5 10888.6 11150.4 11256.7 12004.9 7891.2 7977.4 8248.5 8322.4 8446.8 10689.5 11135.3 11402.2 11480.7 11666.1 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5549.67 Median:5014.21 stddev:3114.77 (62.1%) stddev-sans-first:3108.62 04:40:17 INFO - PROCESS | 1649 | Values: 1497.7 1514.0 1707.6 1861.2 1882.5 8149.2 8676.3 8691.9 8695.4 8699.9 6864.9 7686.0 7830.1 7948.7 8486.2 2257.5 2346.0 2348.0 2348.5 2360.6 2316.4 2335.3 2361.3 2361.6 2364.1 1966.8 2334.8 2341.8 2341.9 2347.5 2289.4 2324.7 2325.8 2327.3 2330.2 1966.4 2272.0 2319.2 2329.4 2333.0 8560.2 8696.0 8698.3 8702.4 8717.5 5050.4 5218.0 5219.0 5220.3 5230.7 3057.9 3438.6 3527.8 3594.1 3599.1 989.0 1036.8 1058.9 1060.1 1061.8 11280.5 11319.0 11341.2 11344.3 11348.9 4237.2 4346.3 4451.6 4509.5 4705.6 9683.7 9703.7 9707.2 9710.0 9715.0 5183.4 5188.5 5188.6 5193.5 5196.0 4646.6 4687.2 4747.8 4749.8 4756.8 3883.6 3896.7 3912.9 3949.2 4039.3 1268.6 1270.0 1277.9 1283.4 1284.3 752.7 854.0 864.5 864.6 877.9 5354.8 5470.2 5797.7 5806.4 5834.8 883.9 913.9 937.7 944.3 957.2 3348.7 3748.3 3763.6 3857.2 3866.3 10243.8 10608.5 10614.9 10620.0 10657.5 10483.7 10586.5 10594.2 10595.8 10596.5 6245.4 7999.2 8039.4 8127.1 8133.3 5567.5 5817.4 5865.9 5869.9 5907.3 9466.4 9655.7 9769.9 9825.2 9915.3 9724.7 9929.5 9942.4 10076.4 10131.7 6506.3 6640.7 6833.5 6836.1 6913.1 7672.9 8136.0 8204.3 8287.7 8292.9 7328.1 7413.5 7427.6 7437.4 7514.0 7226.1 7268.5 7285.0 7301.9 7357.7 6664.3 6732.1 6742.0 6806.5 6811.2 4718.4 4779.7 4789.9 4800.5 4926.9 3986.0 4742.5 4867.0 4917.3 4978.1 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4392.55 Median:4765.98 stddev:1377.54 (28.9%) stddev-sans-first:1346.60 04:40:17 INFO - PROCESS | 1649 | Values: 293.0 301.4 306.3 308.4 311.3 4764.8 5353.8 5450.9 5451.0 5456.4 4756.5 4985.5 5339.5 5374.8 5417.6 3316.7 3803.8 3804.8 4026.4 4038.3 3309.8 3584.0 3916.0 4032.9 4102.0 3542.0 4002.6 4005.6 4025.5 4043.3 4024.9 4029.0 4029.0 4038.5 4048.2 2437.4 2865.8 3418.5 3421.0 3514.9 5085.6 5120.5 5120.6 5129.5 5139.7 4009.0 4535.5 4545.8 4563.5 4613.5 4544.2 4602.6 5274.9 5376.6 5494.6 3860.7 3863.4 3864.9 3885.9 3891.7 5419.5 5458.6 5534.0 5608.9 5730.9 3389.1 3838.5 3861.6 3920.1 3924.8 3333.0 3962.7 4016.8 4024.9 4037.6 3915.4 4193.8 4528.7 4614.7 4620.2 4906.0 5302.8 5543.2 5551.8 5578.4 1026.5 1237.5 1319.0 1327.7 1331.3 753.4 764.1 804.3 819.8 824.3 3658.7 4025.9 4208.7 4340.5 4413.9 4016.5 4695.5 4713.5 4774.8 4814.9 3731.0 3979.5 4110.7 4334.0 4359.8 2468.4 2745.8 2942.9 3026.9 3065.2 5216.8 5967.4 5985.8 5993.2 6014.0 4767.2 5191.8 5465.0 5895.7 5918.3 4994.4 5854.6 5891.6 5920.3 5942.8 5321.5 5703.5 5798.4 5824.9 5848.5 5359.3 5361.8 5620.8 5644.7 5652.8 4712.2 5402.9 5573.2 5701.0 5878.2 3605.5 3708.2 3738.9 3756.9 3776.7 4836.7 5609.9 5613.3 5636.9 5645.4 4842.8 5334.1 5381.6 5428.0 5508.7 5212.8 5348.0 5432.3 5456.3 5468.7 4543.8 4673.9 5037.6 5379.9 5404.3 4749.3 5563.5 5571.5 5626.6 5749.2 5119.8 5491.7 5524.6 5597.0 5636.9 04:40:17 INFO - PROCESS | 1649 | 04:40:17 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24816.31 Median:4167.01 stddev:40270.38 (966.4%) stddev-sans-first:40342.67 04:40:17 INFO - PROCESS | 1649 | Values: 709.6 725.5 765.7 805.4 819.6 16119.1 16554.6 16569.0 16584.8 16596.8 13092.6 14264.9 14774.9 14840.3 14897.3 674.0 693.7 817.4 822.2 847.4 829.5 832.8 842.1 843.1 846.8 888.6 889.4 889.9 890.0 890.6 687.5 839.9 883.9 883.9 886.4 660.6 662.6 663.6 664.8 665.4 14815.1 14834.9 14835.8 14873.4 14900.7 13803.8 14051.9 14110.4 14131.5 14341.1 12832.1 12873.6 12988.7 13238.5 13270.7 4183.3 4648.4 4694.9 4771.2 4872.0 93291.6 94861.5 98789.5 123749.0 123820.0 11215.0 11342.4 11490.8 11679.2 11710.2 17842.3 21928.0 21960.0 21987.0 21994.0 8057.8 8164.9 8298.2 8347.3 8353.9 4150.8 4416.8 4561.6 4690.0 4714.7 3619.8 3724.7 3766.2 3767.4 3768.5 666.4 669.0 673.2 688.7 689.7 487.8 552.9 562.3 569.2 655.3 7224.0 8515.6 9269.5 9460.6 9465.8 581.3 596.9 637.3 655.5 659.2 4333.2 4350.4 4646.4 4657.0 4746.5 93934.0 105991.0 152887.2 164768.0 168415.3 103723.0 141973.0 147052.5 147089.5 149695.0 123979.0 125921.7 126441.5 126690.4 126799.0 8760.5 8776.5 8788.5 8916.4 8940.6 47318.8 56215.0 59701.7 62619.1 63873.7 73783.6 80007.6 81131.2 90862.5 95893.5 13194.5 13307.7 13352.5 13548.8 14102.8 11975.5 12333.2 12538.2 12796.6 13196.4 12130.9 12189.2 12224.9 12491.9 12536.4 12248.2 12434.7 13464.3 13618.8 14174.2 13339.4 13602.8 13643.4 13907.2 14019.9 11421.3 11452.7 11464.9 11540.8 11970.8 9131.0 10661.3 11136.7 11779.8 11790.2 04:40:17 INFO - PROCESS | 1649 | -------- Summary: end -------- 04:40:17 INFO - PROCESS | 1649 | 04:40:18 INFO - TEST-INFO | 1649: exit 0 04:40:18 INFO - TEST-OK | dromaeo_css | took 1118658ms 04:40:18 INFO - TEST-START | kraken 04:40:18 INFO - operating with platform_type : mac_ 04:40:18 INFO - Initialising browser for kraken test... 04:40:18 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpkcfvO1/profile http://localhost:49279/getInfo.html) 04:40:20 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:40:20 INFO - PROCESS | 1660 | colorDepth:24 04:40:20 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:40:20 INFO - PROCESS | 1660 | __metrics 04:40:20 INFO - PROCESS | 1660 | 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" 04:40:20 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:40:21 INFO - TEST-INFO | 1660: exit 0 04:40:21 INFO - Browser initialized. 04:40:21 INFO - Running cycle 1/1 for kraken test... 04:40:21 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpkcfvO1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:33 INFO - PROCESS | 1661 | RSS: Main: 243290112 04:40:33 INFO - PROCESS | 1661 | 04:40:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 04:40:36 INFO - PROCESS | 1661 | RSS: Main: 294588416 04:40:36 INFO - PROCESS | 1661 | 04:40:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 04:40:40 INFO - PROCESS | 1661 | RSS: Main: 304775168 04:40:40 INFO - PROCESS | 1661 | 04:40:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 04:40:44 INFO - PROCESS | 1661 | RSS: Main: 395542528 04:40:44 INFO - PROCESS | 1661 | 04:40:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 04:40:46 INFO - PROCESS | 1661 | RSS: Main: 296669184 04:40:46 INFO - PROCESS | 1661 | 04:40:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:40:49 INFO - PROCESS | 1661 | RSS: Main: 301658112 04:40:49 INFO - PROCESS | 1661 | 04:40:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 04:40:53 INFO - PROCESS | 1661 | RSS: Main: 338878464 04:40:53 INFO - PROCESS | 1661 | 04:40:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 04:40:56 INFO - PROCESS | 1661 | RSS: Main: 342421504 04:40:56 INFO - PROCESS | 1661 | 04:40:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 04:41:00 INFO - PROCESS | 1661 | RSS: Main: 339795968 04:41:00 INFO - PROCESS | 1661 | 04:41:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:02 INFO - PROCESS | 1661 | RSS: Main: 314499072 04:41:02 INFO - PROCESS | 1661 | 04:41:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:04 INFO - PROCESS | 1661 | RSS: Main: 319041536 04:41:04 INFO - PROCESS | 1661 | 04:41:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:07 INFO - PROCESS | 1661 | RSS: Main: 323874816 04:41:07 INFO - PROCESS | 1661 | 04:41:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:11 INFO - PROCESS | 1661 | RSS: Main: 330985472 04:41:11 INFO - PROCESS | 1661 | 04:41:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:14 INFO - PROCESS | 1661 | RSS: Main: 332341248 04:41:14 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 04:41:16 INFO - PROCESS | 1661 | RSS: Main: 375820288 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | __start_tp_report 04:41:16 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 04:41:16 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 04:41:16 INFO - PROCESS | 1661 | |i|pagename|runs| 04:41:16 INFO - PROCESS | 1661 | |0;ai-astar;101;113;113;116;163;160;111;114;111;112 04:41:16 INFO - PROCESS | 1661 | |1;audio-beat-detection;142;109;102;152;113;135;158;111;160;105 04:41:16 INFO - PROCESS | 1661 | |2;audio-dft;186;184;185;182;183;183;184;186;181;180 04:41:16 INFO - PROCESS | 1661 | |3;audio-fft;78;58;57;106;60;57;68;60;84;57 04:41:16 INFO - PROCESS | 1661 | |4;audio-oscillator;87;54;93;96;108;97;130;69;68;102 04:41:16 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;87;90;88;89;88;91;88;91;87 04:41:16 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;94;96;96;95;95;95;94;95;94 04:41:16 INFO - PROCESS | 1661 | |7;imaging-desaturate;86;85;84;85;84;84;85;85;85;85 04:41:16 INFO - PROCESS | 1661 | |8;json-parse-financial;54;54;54;53;53;53;53;53;52;54 04:41:16 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;66;66;67;81;78;71;71;71;71;73 04:41:16 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;83;86;83;85;84;85;82;86;84;82 04:41:16 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;137;136;136;136;133;138;134;137;158;146 04:41:16 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;171;167;168;169;172;230;205;166;168;169 04:41:16 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;68;68;67;66;65;66;66;68;67 04:41:16 INFO - PROCESS | 1661 | __end_tp_report 04:41:16 INFO - PROCESS | 1661 | __start_cc_report 04:41:16 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2046 04:41:16 INFO - PROCESS | 1661 | __end_cc_report 04:41:16 INFO - PROCESS | 1661 | __startTimestamp1470656476578__endTimestamp 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | ------- Summary: start ------- 04:41:16 INFO - PROCESS | 1661 | Number of tests: 14 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:121.40 Median:113.50 stddev:21.52 (19.0%) stddev-sans-first:21.52 04:41:16 INFO - PROCESS | 1661 | Values: 101.0 113.0 113.0 116.0 163.0 160.0 111.0 114.0 111.0 112.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:138.50 stddev:23.14 (16.7%) stddev-sans-first:24.04 04:41:16 INFO - PROCESS | 1661 | Values: 142.0 109.0 102.0 152.0 113.0 135.0 158.0 111.0 160.0 105.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:2.01 (1.1%) stddev-sans-first:1.90 04:41:16 INFO - PROCESS | 1661 | Values: 186.0 184.0 185.0 182.0 183.0 183.0 184.0 186.0 181.0 180.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:68.50 Median:60.00 stddev:16.29 (27.2%) stddev-sans-first:16.91 04:41:16 INFO - PROCESS | 1661 | Values: 78.0 58.0 57.0 106.0 60.0 57.0 68.0 60.0 84.0 57.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:78.00 stddev:22.09 (28.3%) stddev-sans-first:23.39 04:41:16 INFO - PROCESS | 1661 | Values: 87.0 54.0 93.0 96.0 108.0 97.0 130.0 69.0 68.0 102.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.50 stddev:1.52 (1.7%) stddev-sans-first:1.56 04:41:16 INFO - PROCESS | 1661 | Values: 90.0 87.0 90.0 88.0 89.0 88.0 91.0 88.0 91.0 87.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 04:41:16 INFO - PROCESS | 1661 | Values: 94.0 94.0 96.0 96.0 95.0 95.0 95.0 94.0 95.0 94.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 04:41:16 INFO - PROCESS | 1661 | Values: 86.0 85.0 84.0 85.0 84.0 84.0 85.0 85.0 85.0 85.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:0.67 (1.3%) stddev-sans-first:0.67 04:41:16 INFO - PROCESS | 1661 | Values: 54.0 54.0 54.0 53.0 53.0 53.0 53.0 53.0 52.0 54.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:4.90 (6.9%) stddev-sans-first:4.78 04:41:16 INFO - PROCESS | 1661 | Values: 66.0 66.0 67.0 81.0 78.0 71.0 71.0 71.0 71.0 73.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.50 stddev:1.49 (1.8%) stddev-sans-first:1.54 04:41:16 INFO - PROCESS | 1661 | Values: 83.0 86.0 83.0 85.0 84.0 85.0 82.0 86.0 84.0 82.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:137.00 stddev:7.50 (5.5%) stddev-sans-first:7.92 04:41:16 INFO - PROCESS | 1661 | Values: 137.0 136.0 136.0 136.0 133.0 138.0 134.0 137.0 158.0 146.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:170.00 stddev:21.45 (12.6%) stddev-sans-first:22.58 04:41:16 INFO - PROCESS | 1661 | Values: 171.0 167.0 168.0 169.0 172.0 230.0 205.0 166.0 168.0 169.0 04:41:16 INFO - PROCESS | 1661 | 04:41:16 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 04:41:16 INFO - PROCESS | 1661 | Values: 66.0 68.0 68.0 67.0 66.0 65.0 66.0 66.0 68.0 67.0 04:41:16 INFO - PROCESS | 1661 | -------- Summary: end -------- 04:41:16 INFO - PROCESS | 1661 | 04:41:17 INFO - TEST-INFO | 1661: exit 0 04:41:17 INFO - TEST-OK | kraken | took 59313ms 04:41:17 INFO - SUITE-END | took 1177s 04:41:17 INFO - Completed test suite (00:19:38) 04:41:17 INFO - javascript benchmark 04:41:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.368749937512, 3322.717569006634, 3336.4661654135343, 3345.882894098707, 3378.375000624883, 5633.859153521164, 5675.5459563234945, 5696.231008813809, 5747.568932330081, 5749.0, 5662.518685911702, 5740.454656807597, 5762.596618236725, 5774.769009239625, 5822.563307751914, 5172.6379153459275, 5225.869353266161, 5233.241180028894, 5251.737413129328, 5340.973295133538, 5336.0, 5339.70631615261, 5368.033753924292, 5372.54333381663, 5390.811321603745, 4289.485261768607, 5169.327987361657, 5233.371995360543, 5275.762590683409, 5277.445868183824, 5156.613254005964, 5201.349831271091, 5265.104932161542, 5277.1820367842665, 5287.391949925769, 5180.067587834188, 5180.222966555009, 5231.189165679326, 5254.106801843695, 5265.631405801595, 5774.335951365562, 5795.507381872546, 5811.128330750379, 5817.505512031483, 5821.50517206038, 6112.969435152841, 6150.630962142241, 6278.654673992928, 6282.994720844643, 6298.716557754912, 6232.594881332744, 6259.843503912393, 6304.590201636879, 6325.968370158166, 6329.240491141047, 5888.941110588882, 5901.468867801833, 5910.320313164041, 5913.083472061838, 5913.881722365529, 6185.505159587222, 6202.906956395657, 6260.280067792262, 6299.842503937438, 6305.148804911279, 5535.640183388147, 5868.67722275279, 6194.969025154845, 6206.100115483229, 6225.470834979077, 6263.874722505524, 6282.937170628235, 6302.779402720883, 6310.5267104967315, 6311.905321420182, 6051.7579296827635, 6127.234095738033, 6131.437835141539, 6213.316535181127, 6236.158118653923, 5692.686902220417, 6016.1878146450545, 6281.905771413432, 6293.118963345135, 6300.338464461257, 4960.652754307164, 4962.9751851241235, 4963.131451995886, 4967.528084831935, 4969.006198760262, 4427.512973572904, 4431.911361772811, 4484.349769283435, 4488.057507923308, 4507.0, 5670.8015219467125, 5688.317401911714, 5702.0, 5706.172604972256, 5713.314402271754, 5256.553192978639, 6232.407921247474, 6292.590981586274, 6294.150289710922, 6333.873322533524, 5712.828615141552, 5720.513756330675, 5721.342045664802, 5735.913961290584, 5737.942620573825, 5337.146056630921, 5431.429699881455, 5440.836774896759, 5466.835994920159, 5468.0, 6198.81403557903, 6207.81376558704, 6224.9377506224355, 6290.716917738804, 6314.0, 6299.023651334051, 6332.620042797263, 6334.461570766536, 6336.334684858023, 6349.111124442489, 6152.938470615416, 6276.309605943434, 6283.2460104787715, 6299.905501417574, 6313.526485513513, 6105.725242364016, 6120.0205129226715, 6167.414095660905, 6243.157173781481, 6244.313125556095, 6311.211098612674, 6322.49420046386, 6323.304436511888, 6326.778562750282, 6341.651209183539, 5991.0, 6162.22972128484, 6172.876542469216, 6189.659568723583, 6197.690115494297, 6059.182010428536, 6133.325334213322, 6141.263048434216, 6165.815025549241, 6174.197354343906, 6011.458968692838, 6016.278046634432, 6063.848403789941, 6085.3306136325855, 6086.847828804315, 5443.918341224964, 5925.4370834770625, 5945.64326140433, 5992.700364981821, 6205.317415084247, 5949.672767997802, 5982.7307771149535, 5984.97007514977, 6025.548083893638, 6076.33160352352], "value": 5713.300124780126, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.61014823659906, 516.8475299786563, 523.6439221329496, 532.5606374740837, 542.4891676680446, 26780.598291025624, 39389.40915886264, 40723.796381018125, 40840.18319633605, 40923.795381023054, 41483.377749333784, 41808.0, 41853.953651158736, 41875.79062104693, 41994.58005419945, 9551.0926461986, 9739.318247722662, 9758.365706229073, 9765.267604929622, 9779.315447918649, 8704.825903481926, 8920.152585504367, 11558.942205288962, 11741.236819606698, 11752.294862308288, 11513.884861151408, 11544.076473882069, 11574.826377604342, 11578.073754099652, 11588.0, 11662.950334469897, 11739.706507337385, 11741.706457338549, 11748.00141987932, 11751.823722644167, 11712.472938717692, 11792.174547781658, 11794.882051179464, 11807.291562506278, 11812.232204906653, 39132.02169945746, 39219.21561568753, 39328.410073848914, 39329.80335098335, 39343.016424589325, 3349.179451034512, 3375.8143302118374, 3436.1066122808, 3497.667721566447, 3954.6638535724496, 27931.441371172463, 28877.278068048257, 31528.211794705087, 31957.360852782815, 32006.6799332006, 3134.905952821396, 3179.109849242193, 3217.8230197339135, 3260.8532616032335, 3343.4650455927176, 430869.0, 432261.0, 432950.0, 433090.8345458284, 433333.33333333157, 32656.020319390456, 33233.66766332354, 55619.721901390236, 55818.0, 55926.440735592936, 34955.82522087373, 35070.12324691852, 35100.64899351025, 35138.0, 35150.0, 14514.0, 14674.55976320712, 14705.0, 14711.705765884837, 14727.631809204642, 11200.271982321123, 12425.937870310772, 12480.75038499243, 12522.872941435315, 12531.74936501283, 7214.819629509304, 7545.0, 7600.733974310983, 7655.846883062419, 7834.569098699626, 3159.5418664294007, 3201.695838895301, 3209.165616939566, 3224.8548815303366, 3238.9838050809594, 884.8363052835246, 895.1853813030111, 927.3693888156118, 972.0473935543207, 1037.387941114746, 27232.183034508995, 29803.850980745392, 30185.943491978116, 30487.085387438412, 30516.084517464067, 932.8292992294627, 1014.2545229256491, 1025.5282570017876, 1030.3611760708409, 1151.9574784819752, 14928.477503287333, 15230.086194828345, 15273.38906443752, 15625.60935976587, 15633.530994070195, 612593.0, 687427.0, 694065.0, 696089.0, 916661.0, 57718.422815772145, 58581.70709146427, 58607.70696146492, 58751.0, 58809.41190588125, 1180.350807056133, 1238.5107882386587, 1363.6863521389937, 1375.0786972728297, 1408.190290582919, 23138.537229255657, 29423.55864661989, 30412.0, 31013.68986310108, 31609.683903161593, 53588.196177058155, 54113.91772164613, 54118.0, 54314.45685543094, 54330.72834635802, 601645.0, 602568.0, 602808.0, 606414.0, 609393.953030232, 25809.225723228334, 26964.303569642878, 27068.052618158268, 27075.72924270811, 27078.593821092305, 36397.0, 37083.814580926926, 37591.060223494635, 44959.0, 46761.06477870492, 37569.62430375661, 37823.243535129695, 37984.24031519299, 38889.0, 38995.83012509629, 36198.8190059048, 36716.44925326176, 37125.814370927976, 37455.0, 38489.422658660704, 36511.634883650826, 36645.63354366529, 36682.44976325411, 37953.81023094867, 38544.03639909025, 26338.473230535667, 26861.731382685924, 34731.81623356522, 34876.779312723935, 35131.64868351284, 23887.64168537508, 25606.975720970953, 25650.230493085237, 25970.35074123162, 26054.86972565125], "value": 18704.572545237985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.8403082664713, 338.5463478938222, 365.265815710422, 385.5951251186254, 393.2037623811781, 19977.001149942498, 20368.592628147428, 20411.979401029927, 20943.05756240969, 21137.57724845502, 20220.797792022076, 20359.38921832344, 20561.794382056174, 20761.688574671356, 20820.8958955205, 5697.259356283677, 5705.0, 5961.433663801932, 5971.313298970609, 6004.759809607611, 3865.246276975991, 6030.246219222598, 6066.332703402622, 6069.635821850681, 6076.96961515194, 4052.5744796796353, 5733.856653583673, 5975.133605627181, 6016.42843929826, 6103.115048317991, 5860.120981852713, 5918.8816223675285, 5922.022866227067, 6041.093835924603, 6046.213992181032, 5770.682612456313, 5793.449622285876, 5795.101759227285, 5797.36229014808, 5799.043157878987, 12872.356382180853, 23106.76893231063, 25032.370306305667, 25485.745142548523, 25505.107321243853, 2126.2345555599973, 2128.627490189226, 2181.945451363713, 2193.2338735002377, 2894.768418526513, 9893.604255829758, 9893.90106098937, 9958.95430979744, 10178.44018578978, 10347.379157250589, 1776.564741638294, 1810.4930619426582, 1816.7910690270655, 1855.6381505606412, 1890.9243630254912, 84627.5768621153, 111672.4416377921, 112961.19489530199, 118926.0, 119212.0, 11362.0, 11363.943180284212, 11531.88468115325, 11538.94230528842, 11695.473703683185, 14487.78268325976, 14515.709685806225, 14520.782188267185, 14544.345504452325, 14559.854401456061, 8343.498170329278, 8495.702650407206, 8506.957465212634, 8549.358798090043, 8638.568071596395, 8426.705065322683, 8437.143828357752, 8587.828243435097, 8592.699255526026, 8627.870581941275, 4324.243257429937, 4408.096340250241, 4422.380866678667, 4492.685512014193, 4509.88725281864, 1601.7116918954466, 1830.6704793137362, 1891.2340502096672, 1903.1530968719064, 1903.257729485474, 706.6576680296869, 728.559221670892, 747.439420434674, 852.3364485981308, 918.6049998500708, 20202.797972020384, 20274.493137671383, 20857.957102144832, 21796.564068718853, 21858.562828743336, 874.3879284500874, 898.9820203595891, 899.9055099214619, 991.5884907763198, 994.6170724271208, 9762.267829912786, 11230.0, 11270.830937535944, 11299.604513842141, 11306.038986686224, 504303.0, 586033.0, 602533.0, 609437.0, 615969.0, 76841.61579192069, 77292.0, 113042.0, 127955.0, 140223.0, 1000.1698590170141, 1006.7684432580401, 1017.4658304390255, 1029.2177944761886, 1033.6278939581896, 18360.449186524427, 18807.811921880606, 18815.341463048728, 18848.52878678044, 18853.057347132315, 52733.47266527439, 53912.460875390745, 54115.0, 55356.44643553513, 56825.715871420376, 81214.0, 82240.58879705564, 83471.5826420864, 83748.7437688423, 85474.0, 5633.718314084362, 7825.209345084711, 8041.276285134366, 8070.596470176585, 8298.543580103153, 10267.0, 10779.24545281854, 12437.93024208437, 12532.248065116122, 12910.419031143809, 10542.683719488428, 10565.735856603646, 10701.357918524913, 10755.462226888469, 11564.190506664789, 9104.998450170608, 10918.290311129751, 10970.890291096986, 11170.944145279222, 11410.973012428922, 10139.548323776013, 10888.564457421615, 11150.442477876237, 11256.718582035515, 12004.939975300067, 7891.171427000312, 7977.361811055218, 8248.46384984974, 8322.37582181351, 8446.788830279293, 10689.51897164645, 11135.276207046516, 11402.20184587071, 11480.712982175179, 11666.066714662637], "value": 9370.127896680948, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.7079469503449, 1514.0234548716078, 1707.5731067233191, 1861.2275905499218, 1882.5481884347753, 8149.185081491848, 8676.349273804459, 8691.869621955668, 8695.391322607413, 8699.869501957475, 6864.862702745954, 7685.961570192141, 7830.099538553068, 7948.7217947371855, 8486.193811587904, 2257.525919556895, 2346.038124369009, 2348.0276567683145, 2348.4852270285655, 2360.5868972929757, 2316.420894776306, 2335.346103091137, 2361.268006917861, 2361.586722323595, 2364.113457453455, 1966.783653798081, 2334.7898689117974, 2341.84777989431, 2341.94145146371, 2347.542229265294, 2289.3933107726384, 2324.6745455636224, 2325.8255630827757, 2327.290176496168, 2330.172788660035, 1966.4199061276959, 2272.0, 2319.1651005637955, 2329.4409341758023, 2333.043452184613, 8560.186782255676, 8696.0, 8698.304135669194, 8702.434341767765, 8717.476951382938, 5050.368703912011, 5217.982493413786, 5219.0, 5220.347456567929, 5230.686158830445, 3057.8623961921767, 3438.5813148788966, 3527.805970671612, 3594.11944073701, 3599.1362073102528, 988.9802203955952, 1036.75117971687, 1058.925875188737, 1060.0671409159968, 1061.8247989081792, 11280.492377843017, 11319.0, 11341.206115571913, 11344.319340839576, 11348.886511134866, 4237.152569486115, 4346.304591265382, 4451.59935605797, 4509.526499717548, 4705.60002399793, 9683.709488715349, 9703.708888733348, 9707.174890134276, 9710.0, 9714.951425242829, 5183.377994640668, 5188.507091826271, 5188.558972487305, 5193.506616871391, 5196.012757576049, 4646.581807637262, 4687.20317546018, 4747.833825816149, 4749.762511874393, 4756.762161891892, 3883.5533913600298, 3896.7467114638025, 3912.902177445587, 3949.1706741584017, 4039.313316736162, 1268.5560053981033, 1269.9873001270055, 1277.9297138657278, 1283.3711481374246, 1284.3000564692265, 752.7403045949138, 854.0349405172116, 864.481311213267, 864.5893200729603, 877.8595424732013, 5354.759035843396, 5470.152126420411, 5797.710114494343, 5806.361300256968, 5834.824955251349, 883.911608839111, 913.917747402737, 937.6999360204669, 944.2964991081689, 957.2103015012642, 3348.748843836722, 3748.3065632857465, 3763.5671897731563, 3857.2285542890972, 3866.3040652682225, 10243.84634230487, 10608.469576521144, 10614.946925265323, 10620.0, 10657.467126643636, 10483.68548943533, 10586.52360643773, 10594.152467802558, 10595.788084238273, 10596.470176491144, 6245.437910588069, 7999.1600881907725, 8039.356851451987, 8127.106018337978, 8133.2680058794995, 5567.47109024642, 5817.371136081903, 5865.912011319919, 5869.853253668693, 5907.261592300963, 9466.432014079177, 9655.662051828152, 9769.853452198366, 9825.213982881209, 9915.30592858493, 9724.659636912675, 9929.453880036668, 9942.353747006422, 10076.395416275047, 10131.7467063324, 6506.275836903319, 6640.734370625314, 6833.521653484208, 6836.077129587442, 6913.066735990778, 7672.923270767221, 8135.959320203361, 8204.302634276153, 8287.668493260444, 8292.917070829215, 7328.120625524758, 7413.518121322097, 7427.554346739213, 7437.367823735043, 7514.0, 7226.060612120391, 7268.491205615556, 7285.016522769571, 7301.926980730124, 7357.742479013209, 6664.266930637723, 6732.091167692299, 6742.0, 6806.455483561204, 6811.182658080864, 4718.433787945469, 4779.713217206979, 4789.946211833391, 4800.543948324904, 4926.852194434173, 3985.9636494511055, 4742.549457801503, 4867.0, 4917.336159618406, 4978.054169707744], "value": 4468.390533531431, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.9868155932983, 301.36712902903906, 306.2817692511061, 308.39092791736323, 311.2809410262293, 4764.809407623691, 5353.812616558412, 5450.892990995134, 5451.0, 5456.399796022434, 4756.4564399522405, 4985.507246376812, 5339.492748188925, 5374.7850085996515, 5417.59368047396, 3316.651751566087, 3803.8098095095274, 3804.752691075071, 4026.4161696553983, 4038.3336749436307, 3309.7683162178537, 3583.982080089596, 3916.040570060339, 4032.919341613166, 4101.958980410188, 3541.9905326981816, 4002.599740026003, 4005.5794141615147, 4025.496812898388, 4043.2924238258333, 4024.9472768887786, 4028.979855100735, 4029.0, 4038.4749982502235, 4048.210598933209, 2437.439388940536, 2865.8423786691724, 3418.4872269159578, 3421.0, 3514.859405623772, 5085.567726743231, 5120.539151476385, 5120.590352771769, 5129.512696293846, 5139.665921715114, 4009.0, 4535.455745310584, 4545.818167273305, 4563.543645635443, 4613.538646135393, 4544.227481328182, 4602.585767280927, 5274.92087618686, 5376.580538757575, 5494.587905907073, 3860.652541271271, 3863.420486926955, 3864.8840534784003, 3885.922281554353, 3891.6692081173132, 5419.458054194589, 5458.590605704549, 5533.972330138364, 5608.915866262009, 5730.885382292373, 3389.1357703785375, 3838.5201849768778, 3861.555921069113, 3920.0787814863484, 3924.843006279774, 3333.0, 3962.663173630274, 4016.758994460342, 4024.8591299304385, 4037.6366127048705, 3915.3735402335487, 4193.7903104845245, 4528.682992190515, 4614.74618895964, 4620.19146649335, 4906.0, 5302.840914772563, 5543.196236545759, 5551.805686800943, 5578.44215578447, 1026.4970164619283, 1237.5482948723652, 1318.9736205276033, 1327.6680829792551, 1331.2678027085065, 753.3689562098106, 764.10217993857, 804.3163311185446, 819.8319344534356, 824.3116997307316, 3658.70730341572, 4025.8993525162105, 4208.6633069354375, 4340.502526628308, 4413.9323170396265, 4016.5180178378782, 4695.483496815347, 4713.457952335457, 4774.761261936889, 4814.927776083362, 3731.0, 3979.467904856593, 4110.671146308288, 4333.956660433419, 4359.80380882861, 2468.43254533369, 2745.752882240608, 2942.941141177207, 3026.9091927242216, 3065.1877252528097, 5216.7913283469, 5967.373425790316, 5985.760569577255, 5993.220881285405, 6014.0, 4767.155823225029, 5191.766370513336, 5464.9779581554285, 5895.734691938873, 5918.319393269743, 4994.375703037121, 5854.648721076749, 5891.558133140117, 5920.2895652521975, 5942.7920022799, 5321.494458026481, 5703.51896924839, 5798.376014990631, 5824.883502330014, 5848.549355880051, 5359.3032905722, 5361.839144825662, 5620.831375058755, 5644.745986430538, 5652.830415087554, 4712.198926182626, 5402.864928376822, 5573.191887176417, 5700.97615346546, 5878.177055212359, 3605.531280933462, 3708.2212735324806, 3738.943915841319, 3756.887293381203, 3776.734793843983, 4836.661433699607, 5609.943900560942, 5613.270274864241, 5636.915446268391, 5645.435456454322, 4842.757862106951, 5334.093204155381, 5381.596380271573, 5427.972860135674, 5508.724563771875, 5212.810619539879, 5348.0, 5432.29380180574, 5456.263404440509, 5468.6875149962925, 4543.79552920126, 4673.859784206479, 5037.647364684437, 5379.919301210563, 5404.270423492935, 4749.335093087039, 5563.471470210324, 5571.526420254324, 5626.578006649435, 5749.195112684144, 5119.846404607868, 5491.697956612425, 5524.613277070566, 5596.964561556125, 5636.85907852307], "value": 3951.411727595207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5990765217651, 725.5429079679801, 765.6592816196791, 805.3999770171223, 819.6106849246606, 16119.113448760314, 16554.58613534659, 16569.00585964843, 16584.751228731562, 16596.751048734277, 13092.6252743462, 14264.928675356634, 14774.852251477481, 14840.332185051671, 14897.25513724312, 673.9595624262536, 693.7329128285621, 817.3706246190429, 822.1737154160061, 847.3687103108193, 829.5396055189367, 832.7793134819278, 842.0779246724826, 843.0936743001267, 846.8306338732248, 888.6134531478795, 889.3907673243849, 889.9243564297043, 889.973300800977, 890.6437425030008, 687.4603436302478, 839.8614228652265, 883.924866386358, 883.9292856571523, 886.366248132585, 660.6135410784697, 662.6454846657064, 663.6482664187988, 664.7806223946086, 665.4177594604721, 14815.111093334435, 14834.925825370912, 14835.777463338058, 14873.405063797436, 14900.70198596022, 13803.792943105862, 14051.929740351336, 14110.435582576685, 14131.505397311044, 14341.139531628136, 12832.101752877303, 12873.613791586267, 12988.675283117904, 13238.470461181541, 13270.734585308337, 4183.267928112598, 4648.418947631546, 4694.929576056362, 4771.188897887333, 4871.975640121812, 93291.60062599067, 94861.52569237178, 98789.51815722769, 123749.0, 123820.0, 11215.0, 11342.432878356214, 11490.770184596262, 11679.182457228082, 11710.180287379802, 17842.28630854751, 21928.013239404267, 21960.0, 21987.0, 21994.0, 8057.758267251992, 8164.877526836984, 8298.21166989135, 8347.290480309242, 8353.874691879626, 4150.750954942713, 4416.84541041062, 4561.566651168134, 4690.0, 4714.693544919569, 3619.8371073301764, 3724.6647801697977, 3766.209096089797, 3767.3783825669007, 3768.453574231721, 666.3902529185741, 668.9933100669028, 673.1518286958468, 688.721067967474, 689.6655122265697, 487.7804987755524, 552.9087700529368, 562.3111688182026, 569.2485918587424, 655.3413819111762, 7223.963880180566, 8515.57422128891, 9269.521410579318, 9460.57427415768, 9465.763355916157, 581.2530897796312, 596.9343372229051, 637.2956497644751, 655.4854439265249, 659.1562799616498, 4333.2486411893, 4350.434443522385, 4646.390181213232, 4656.976715116471, 4746.525347465225, 93934.0, 105991.0, 152887.23556382148, 164768.0, 168415.31584684242, 103723.0, 141973.0, 147052.5294747039, 147089.5291047076, 149695.0, 123979.0, 125921.74078259284, 126441.45923218579, 126690.36654816852, 126798.99999999815, 8760.51817150063, 8776.473411595325, 8788.472691638523, 8916.375853690179, 8940.597673104856, 47318.76340618275, 56215.0, 59701.70149149226, 62619.06071408842, 63873.680631596544, 73783.63108184424, 80007.59996199982, 81131.18868811236, 90862.54568727114, 95893.5205323969, 13194.538191163263, 13307.733845323233, 13352.532661356807, 13548.79676804829, 14102.788458173341, 11975.461104250504, 12333.198342107735, 12538.185017973803, 12796.552120675731, 13196.406161722938, 12130.878691212974, 12189.207701499374, 12224.877751222373, 12491.93754031224, 12536.37318134108, 12248.203866748632, 12434.689132771391, 13464.25946572948, 13618.795718064435, 14174.220417877115, 13339.399727012114, 13602.795958060835, 13643.386047627682, 13907.165570065828, 14019.929900350433, 11421.347697042835, 11452.713682158013, 11464.94267528657, 11540.826887596524, 11970.76058478843, 9131.0, 10661.281373249773, 11136.702074853698, 11779.823302650295, 11790.23363481371], "value": 7790.609550987244, "unit": "score"}], "name": "dromaeo_css", "value": 7186.4901709888, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 116.0, 163.0, 160.0, 111.0, 114.0, 111.0, 112.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 102.0, 152.0, 113.0, 135.0, 158.0, 111.0, 160.0, 105.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 182.0, 183.0, 183.0, 184.0, 186.0, 181.0, 180.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 57.0, 106.0, 60.0, 57.0, 68.0, 60.0, 84.0, 57.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 54.0, 93.0, 96.0, 108.0, 97.0, 130.0, 69.0, 68.0, 102.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 89.0, 88.0, 91.0, 88.0, 91.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 85.0, 84.0, 84.0, 85.0, 85.0, 85.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 53.0, 53.0, 53.0, 53.0, 53.0, 52.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 81.0, 78.0, 71.0, 71.0, 71.0, 71.0, 73.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 85.0, 84.0, 85.0, 82.0, 86.0, 84.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 136.0, 136.0, 133.0, 138.0, 134.0, 137.0, 158.0, 146.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 168.0, 169.0, 172.0, 230.0, 205.0, 166.0, 168.0, 169.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 67.0, 66.0, 65.0, 66.0, 66.0, 68.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1453.9999999999998, "alertThreshold": 2.0}]} 04:41:17 INFO - Return code: 0 04:41:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:41:17 INFO - # TBPL SUCCESS # 04:41:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:41:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:41:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:41:17 INFO - 2016-08-08 04:41:17.898 screenresolution[1665:9951] starting screenresolution argv=screenresolution get 04:41:17 INFO - 2016-08-08 04:41:17.901 screenresolution[1665:9951] Display 0: 1600x1200x32@60 04:41:17 INFO - 2016-08-08 04:41:17.906 screenresolution[1666:9954] starting screenresolution argv=screenresolution list 04:41:17 INFO - Available Modes on Display 0 04:41:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:41:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:41:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:41:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:41:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:41:18 INFO - Intel Iris: 04:41:18 INFO - Chipset Model: Intel Iris 04:41:18 INFO - Type: GPU 04:41:18 INFO - Bus: Built-In 04:41:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:41:18 INFO - Vendor: Intel (0x8086) 04:41:18 INFO - Device ID: 0x0a2e 04:41:18 INFO - Revision ID: 0x0009 04:41:18 INFO - Displays: 04:41:18 INFO - Display: 04:41:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:41:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:41:18 INFO - Display Serial Number: 1600x1200 60 04:41:18 INFO - Main Display: Yes 04:41:18 INFO - Mirror: Off 04:41:18 INFO - Online: Yes 04:41:18 INFO - Rotation: Supported 04:41:18 INFO - Return code: 0 04:41:18 INFO - Running post-action listener: _resource_record_post_action 04:41:18 INFO - [mozharness: 2016-08-08 11:41:18.142565Z] Finished run-tests step (success) 04:41:18 INFO - Running post-run listener: _resource_record_post_run 04:41:18 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38204416; Write bytes: 706459648; Read time: 387; Write time: 1961 04:41:18 INFO - TinderboxPrint: CPU usage
53.6% 04:41:18 INFO - TinderboxPrint: I/O read bytes / time
38,204,416 / 387 04:41:18 INFO - TinderboxPrint: I/O write bytes / time
706,459,648 / 1,961 04:41:18 INFO - TinderboxPrint: CPU idle
2,252.6 (46.4%) 04:41:18 INFO - TinderboxPrint: CPU system
975.0 (20.1%) 04:41:18 INFO - TinderboxPrint: CPU user
1,626.0 (33.5%) 04:41:18 INFO - TinderboxPrint: Swap in / out
445,194,240 / 0 04:41:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388002304; Write bytes: 381203968; Read time: 29207; Write time: 654 04:41:18 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37250048; Write bytes: 297824768; Read time: 373; Write time: 1241 04:41:18 INFO - Running post-run listener: _upload_blobber_files 04:41:18 INFO - Blob upload gear active. 04:41:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:41:18 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 04:41:18 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:41:18 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:41:19 INFO - (blobuploader) - INFO - Open directory for files ... 04:41:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:41:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:41:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:20 INFO - (blobuploader) - INFO - Done attempting. 04:41:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:41:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:41:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:21 INFO - (blobuploader) - INFO - Done attempting. 04:41:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:41:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:41:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:24 INFO - (blobuploader) - INFO - Done attempting. 04:41:24 INFO - (blobuploader) - INFO - Iteration through files over. 04:41:24 INFO - Return code: 0 04:41:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:41:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:41:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d70312bccc8ac0f459428610946933133abee221ae574a154746007920ac91510637f165e68e4526bf6ce6e7d68e54d939dddaada35afd7c8bde8bbc2b9ddc75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a47eef9bd5747f02c302b373924fec02c33d04c8ad30fbfa4db282306913622da9133950a85e95f011832fdda902c0303eb1366b991701b3cac8da8339a643a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3c8597d765a6591f8d871aca7df1103514ed0da6b1b075331f295b7fea492a4fa9db9fff2c73d3990bd5bd6f186c1f542ad4cf0dad1944be3dc4e5ea20f5513"} 04:41:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:41:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:41:24 INFO - Contents: 04:41:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d70312bccc8ac0f459428610946933133abee221ae574a154746007920ac91510637f165e68e4526bf6ce6e7d68e54d939dddaada35afd7c8bde8bbc2b9ddc75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a47eef9bd5747f02c302b373924fec02c33d04c8ad30fbfa4db282306913622da9133950a85e95f011832fdda902c0303eb1366b991701b3cac8da8339a643a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3c8597d765a6591f8d871aca7df1103514ed0da6b1b075331f295b7fea492a4fa9db9fff2c73d3990bd5bd6f186c1f542ad4cf0dad1944be3dc4e5ea20f5513"} 04:41:24 INFO - Running post-run listener: copy_logs_to_upload_dir 04:41:24 INFO - Copying logs to upload dir... 04:41:24 INFO - mkdir: /builds/slave/test/build/upload/logs 04:41:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.777385 ========= master_lag: 0.62 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-08 04:41:25.590512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 04:41:25.599246) ========= 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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/Fx-Team/sha512/d70312bccc8ac0f459428610946933133abee221ae574a154746007920ac91510637f165e68e4526bf6ce6e7d68e54d939dddaada35afd7c8bde8bbc2b9ddc75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a47eef9bd5747f02c302b373924fec02c33d04c8ad30fbfa4db282306913622da9133950a85e95f011832fdda902c0303eb1366b991701b3cac8da8339a643a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3c8597d765a6591f8d871aca7df1103514ed0da6b1b075331f295b7fea492a4fa9db9fff2c73d3990bd5bd6f186c1f542ad4cf0dad1944be3dc4e5ea20f5513"} build_url:https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011121 build_url: 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d70312bccc8ac0f459428610946933133abee221ae574a154746007920ac91510637f165e68e4526bf6ce6e7d68e54d939dddaada35afd7c8bde8bbc2b9ddc75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a47eef9bd5747f02c302b373924fec02c33d04c8ad30fbfa4db282306913622da9133950a85e95f011832fdda902c0303eb1366b991701b3cac8da8339a643a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3c8597d765a6591f8d871aca7df1103514ed0da6b1b075331f295b7fea492a4fa9db9fff2c73d3990bd5bd6f186c1f542ad4cf0dad1944be3dc4e5ea20f5513"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 04:41:25.637997) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 04:41:25.638349) ========= 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.cZZhNwZ7t3/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.KhvoZnnNEv/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005118 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 04:41:25.686654) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 04:41:25.689993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 04:41:25.690345) ========= ========= Total master_lag: 0.76 =========