builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1472841340.24 results: success (0) buildid: 20160902095849 builduid: 622b57a8f6894c51936ff198d1210de0 revision: 0907f97ba5e3ec9aaeb58c69651e8e6927472f1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.240779) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.241206) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.241499) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.306288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.306655) ========= 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.b2qZz5Mspx/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.NFmzziZDib/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-09-02 11:35:40-- 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% 40.1M=0s 2016-09-02 11:35:40 (40.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.580832 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.914757) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.915050) ========= 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.b2qZz5Mspx/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.NFmzziZDib/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.034228 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:40.980332) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 11:35:40.980661) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0907f97ba5e3ec9aaeb58c69651e8e6927472f1b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 0907f97ba5e3ec9aaeb58c69651e8e6927472f1b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b2qZz5Mspx/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.NFmzziZDib/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-09-02 11:35:41,065 truncating revision to first 12 chars 2016-09-02 11:35:41,065 Setting DEBUG logging. 2016-09-02 11:35:41,066 attempt 1/10 2016-09-02 11:35:41,066 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0907f97ba5e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 11:35:41,822 unpacking tar archive at: autoland-0907f97ba5e3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.019184 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 11:35:42.035537) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:42.036227) ========= script_repo_revision: 0907f97ba5e3ec9aaeb58c69651e8e6927472f1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:42.037355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:42.038095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 11:35:42.074009) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-02 11:35:42.074653) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b2qZz5Mspx/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.NFmzziZDib/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:35:42 INFO - MultiFileLogger online at 20160902 11:35:42 in /builds/slave/test 11:35:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:35:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:35:42 INFO - {'append_to_log': False, 11:35:42 INFO - 'base_work_dir': '/builds/slave/test', 11:35:42 INFO - 'blob_upload_branch': 'Autoland', 11:35:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:35:42 INFO - 'branch': 'Autoland', 11:35:42 INFO - 'buildbot_json_path': 'buildprops.json', 11:35:42 INFO - 'config_files': ('talos/mac_config.py',), 11:35:42 INFO - 'default_actions': ('clobber', 11:35:42 INFO - 'read-buildbot-config', 11:35:42 INFO - 'download-and-extract', 11:35:42 INFO - 'populate-webroot', 11:35:42 INFO - 'create-virtualenv', 11:35:42 INFO - 'install', 11:35:42 INFO - 'run-tests'), 11:35:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:35:42 INFO - 'download_minidump_stackwalk': True, 11:35:42 INFO - 'download_symbols': 'ondemand', 11:35:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:35:42 INFO - 'tooltool.py': '/tools/tooltool.py', 11:35:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:35:42 INFO - '/tools/misc-python/virtualenv.py')}, 11:35:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:35:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:35:42 INFO - 'installer_path': 'installer.exe', 11:35:42 INFO - 'log_level': 'info', 11:35:42 INFO - 'log_name': 'talos', 11:35:42 INFO - 'log_to_console': True, 11:35:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:35:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:35:42 INFO - 'opt_config_files': (), 11:35:42 INFO - 'pip_index': False, 11:35:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:35:42 INFO - 'cmd': ('bash', 11:35:42 INFO - '-c', 11:35:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:35:42 INFO - 'enabled': True, 11:35:42 INFO - 'halt_on_failure': False, 11:35:42 INFO - 'name': 'check_screen_resolution'},), 11:35:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:35:42 INFO - 'cmd': ('bash', 11:35:42 INFO - '-c', 11:35:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:35:42 INFO - 'enabled': True, 11:35:42 INFO - 'halt_on_failure': False, 11:35:42 INFO - 'name': 'check_screen_resolution'},), 11:35:42 INFO - 'run_cmd_checks_enabled': True, 11:35:42 INFO - 'sps_profile': False, 11:35:42 INFO - 'sps_profile_interval': 0, 11:35:42 INFO - 'suite': 'dromaeojs', 11:35:42 INFO - 'system_bits': '32', 11:35:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:35:42 INFO - 'title': 't-yosemite-r7-0212', 11:35:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:35:42 INFO - 'use_talos_json': True, 11:35:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:35:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:35:42 INFO - 'work_dir': 'build'} 11:35:42 INFO - [mozharness: 2016-09-02 18:35:42.291141Z] Running clobber step. 11:35:42 INFO - Running pre-action listener: _resource_record_pre_action 11:35:42 INFO - Running main action method: clobber 11:35:42 INFO - rmtree: /builds/slave/test/build 11:35:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:35:44 INFO - Running post-action listener: _resource_record_post_action 11:35:44 INFO - [mozharness: 2016-09-02 18:35:44.458070Z] Finished clobber step (success) 11:35:44 INFO - [mozharness: 2016-09-02 18:35:44.458325Z] Running read-buildbot-config step. 11:35:44 INFO - Running pre-action listener: _resource_record_pre_action 11:35:44 INFO - Running main action method: read_buildbot_config 11:35:44 INFO - Using buildbot properties: 11:35:44 INFO - { 11:35:44 INFO - "project": "", 11:35:44 INFO - "product": "firefox", 11:35:44 INFO - "script_repo_revision": "production", 11:35:44 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:35:44 INFO - "repository": "", 11:35:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:35:44 INFO - "buildid": "20160902095849", 11:35:44 INFO - "pgo_build": "False", 11:35:44 INFO - "basedir": "/builds/slave/test", 11:35:44 INFO - "buildnumber": 181, 11:35:44 INFO - "slavename": "t-yosemite-r7-0212", 11:35:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:35:44 INFO - "platform": "yosemite_r7", 11:35:44 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:35:44 INFO - "branch": "autoland", 11:35:44 INFO - "repo_path": "integration/autoland", 11:35:44 INFO - "revision": "0907f97ba5e3ec9aaeb58c69651e8e6927472f1b", 11:35:44 INFO - "stage_platform": "macosx64", 11:35:44 INFO - "builduid": "622b57a8f6894c51936ff198d1210de0", 11:35:44 INFO - "slavebuilddir": "test" 11:35:44 INFO - } 11:35:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:35:44 INFO - Running post-action listener: _resource_record_post_action 11:35:44 INFO - [mozharness: 2016-09-02 18:35:44.464299Z] Finished read-buildbot-config step (success) 11:35:44 INFO - [mozharness: 2016-09-02 18:35:44.464518Z] Running download-and-extract step. 11:35:44 INFO - Running pre-action listener: _resource_record_pre_action 11:35:44 INFO - Running main action method: download_and_extract 11:35:44 INFO - mkdir: /builds/slave/test/build/tests 11:35: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')]} 11:35:44 INFO - trying https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:35:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:35:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:35:45 INFO - Downloaded 1199 bytes. 11:35:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:35:45 INFO - Using the following test package requirements: 11:35:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:35:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:35:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:35:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'jsshell-mac.zip'], 11:35:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:35:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:35:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:35:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:35:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:35:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:35:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:35:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:35:45 INFO - trying https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:35:45 INFO - Downloading https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:35:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:35:46 INFO - Downloaded 16399363 bytes. 11:35:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:35:53 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 11:35: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')]} 11:35:53 INFO - trying https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:35:53 INFO - Downloading https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:35:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:35:54 INFO - Downloaded 16399363 bytes. 11:35:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:01 INFO - trying https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:36:01 INFO - Downloading https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:36:02 INFO - Downloaded 14413998 bytes. 11:36:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:02 INFO - trying https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:36:02 INFO - Downloading https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:36:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/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 11:36:05 INFO - Downloaded 111625300 bytes. 11:36:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:36:05 INFO - mkdir: /builds/slave/test/properties 11:36:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:36:05 INFO - Writing to file /builds/slave/test/properties/build_url 11:36:05 INFO - Contents: 11:36:05 INFO - build_url:https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:36:06 INFO - Running post-action listener: _resource_record_post_action 11:36:06 INFO - Running post-action listener: set_extra_try_arguments 11:36:06 INFO - [mozharness: 2016-09-02 18:36:06.555407Z] Finished download-and-extract step (success) 11:36:06 INFO - [mozharness: 2016-09-02 18:36:06.555517Z] Running populate-webroot step. 11:36:06 INFO - Running pre-action listener: _resource_record_pre_action 11:36:06 INFO - Running main action method: populate_webroot 11:36:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:36:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:36:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:36:06 INFO - '--tppagecycles', 11:36:06 INFO - '7'], 11:36:06 INFO - 'tests': ['tsvgm']}}, 11:36:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:36:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:36:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:36:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:36:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:36:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:36:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['damp', 'tps']}, 11:36:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'tests': ['damp', 'tps']}, 11:36:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['dromaeo_dom']}, 11:36:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:36:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['basic_compositor_video']}, 11:36:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:36:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['a11yr', 11:36:06 INFO - 'ts_paint', 11:36:06 INFO - 'tpaint', 11:36:06 INFO - 'sessionrestore', 11:36:06 INFO - 'sessionrestore_no_auto_restore', 11:36:06 INFO - 'tabpaint']}, 11:36:06 INFO - 'other-e10s': {'tests': ['a11yr', 11:36:06 INFO - 'ts_paint', 11:36:06 INFO - 'tpaint', 11:36:06 INFO - 'sessionrestore', 11:36:06 INFO - 'sessionrestore_no_auto_restore', 11:36:06 INFO - 'tabpaint']}, 11:36:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:36:06 INFO - 'ts_paint', 11:36:06 INFO - 'tpaint', 11:36:06 INFO - 'sessionrestore', 11:36:06 INFO - 'sessionrestore_no_auto_restore', 11:36:06 INFO - 'tabpaint']}, 11:36:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:36:06 INFO - 'ts_paint', 11:36:06 INFO - 'tpaint', 11:36:06 INFO - 'sessionrestore', 11:36:06 INFO - 'sessionrestore_no_auto_restore', 11:36:06 INFO - 'tabpaint']}, 11:36:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['a11yr', 11:36:06 INFO - 'ts_paint', 11:36:06 INFO - 'tpaint', 11:36:06 INFO - 'sessionrestore', 11:36:06 INFO - 'sessionrestore_no_auto_restore', 11:36:06 INFO - 'tabpaint']}, 11:36:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['a11yr', 11:36:06 INFO - 'ts_paint', 11:36:06 INFO - 'tpaint', 11:36:06 INFO - 'sessionrestore', 11:36:06 INFO - 'sessionrestore_no_auto_restore', 11:36:06 INFO - 'tabpaint']}, 11:36:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['tsvgx', 11:36:06 INFO - 'tsvgr_opacity', 11:36:06 INFO - 'tart', 11:36:06 INFO - 'tscrollx', 11:36:06 INFO - 'cart']}, 11:36:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:36:06 INFO - 'tsvgr_opacity', 11:36:06 INFO - 'tart', 11:36:06 INFO - 'tscrollx', 11:36:06 INFO - 'cart']}, 11:36:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'talos_options': ['--disable-e10s'], 11:36:06 INFO - 'tests': ['tp5o']}, 11:36:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'tests': ['tp5o']}, 11:36:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'talos_options': ['--disable-e10s', 11:36:06 INFO - '--xperf_path', 11:36:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:36:06 INFO - 'tests': ['tp5n']}, 11:36:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:36:06 INFO - 'talos_options': ['--xperf_path', 11:36:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:36:06 INFO - 'tests': ['tp5n']}}, 11:36:06 INFO - 'talos.zip': {'path': '', 11:36:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:36:06 INFO - Running post-action listener: _resource_record_post_action 11:36:06 INFO - [mozharness: 2016-09-02 18:36:06.574224Z] Finished populate-webroot step (success) 11:36:06 INFO - [mozharness: 2016-09-02 18:36:06.574354Z] Running create-virtualenv step. 11:36:06 INFO - Running pre-action listener: _resource_record_pre_action 11:36:06 INFO - Running main action method: create_virtualenv 11:36:06 INFO - Creating virtualenv /builds/slave/test/build/venv 11:36:06 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 11:36:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:36:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:36:06 INFO - Using real prefix '/tools/python27' 11:36:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:36:07 INFO - Installing distribute.............................................................................................................................................................................................done. 11:36:10 INFO - Installing pip.................done. 11:36:10 INFO - Return code: 0 11:36:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:10 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:10 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 11:36:10 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 11:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:10 INFO - 'HOME': '/Users/cltbld', 11:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:10 INFO - 'LOGNAME': 'cltbld', 11:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:10 INFO - 'MOZ_NO_REMOTE': '1', 11:36:10 INFO - 'NO_EM_RESTART': '1', 11:36:10 INFO - 'PAGER': '/bin/cat', 11:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:10 INFO - 'PWD': '/builds/slave/test', 11:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:10 INFO - 'SHELL': '/bin/bash', 11:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:10 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:10 INFO - 'USER': 'cltbld', 11:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:10 INFO - 'XPC_FLAGS': '0x0', 11:36:10 INFO - 'XPC_SERVICE_NAME': '0', 11:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:10 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 11:36:10 INFO - Cleaning up... 11:36:10 INFO - Return code: 0 11:36:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:10 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:10 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 11:36:10 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 11:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:10 INFO - 'HOME': '/Users/cltbld', 11:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:10 INFO - 'LOGNAME': 'cltbld', 11:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:10 INFO - 'MOZ_NO_REMOTE': '1', 11:36:10 INFO - 'NO_EM_RESTART': '1', 11:36:10 INFO - 'PAGER': '/bin/cat', 11:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:10 INFO - 'PWD': '/builds/slave/test', 11:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:10 INFO - 'SHELL': '/bin/bash', 11:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:10 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:10 INFO - 'USER': 'cltbld', 11:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:10 INFO - 'XPC_FLAGS': '0x0', 11:36:10 INFO - 'XPC_SERVICE_NAME': '0', 11:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:10 INFO - Downloading/unpacking psutil>=3.1.1 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 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 11:36:10 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 11:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:36:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:36:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:36:14 INFO - Installing collected packages: psutil 11:36:14 INFO - Running setup.py install for psutil 11:36:14 INFO - building 'psutil._psutil_osx' extension 11:36:14 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 11:36:14 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 11:36:14 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 11:36:14 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 11:36:14 INFO - building 'psutil._psutil_posix' extension 11:36:14 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 11:36:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:36:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:36:14 INFO - ^ 11:36:15 INFO - 1 warning generated. 11:36:15 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 11:36:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:36:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:36:15 INFO - Successfully installed psutil 11:36:15 INFO - Cleaning up... 11:36:15 INFO - Return code: 0 11:36:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:36:15 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')]} 11:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:15 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')]} 11:36:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:15 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:15 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 11:36:15 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 11:36:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:15 INFO - 'HOME': '/Users/cltbld', 11:36:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:15 INFO - 'LOGNAME': 'cltbld', 11:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:15 INFO - 'MOZ_NO_REMOTE': '1', 11:36:15 INFO - 'NO_EM_RESTART': '1', 11:36:15 INFO - 'PAGER': '/bin/cat', 11:36:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:15 INFO - 'PWD': '/builds/slave/test', 11:36:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:15 INFO - 'SHELL': '/bin/bash', 11:36:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:15 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:15 INFO - 'USER': 'cltbld', 11:36:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:15 INFO - 'XPC_FLAGS': '0x0', 11:36:15 INFO - 'XPC_SERVICE_NAME': '0', 11:36:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 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 11:36:15 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 11:36:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:36:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:36:18 INFO - Installing collected packages: mozsystemmonitor 11:36:18 INFO - Running setup.py install for mozsystemmonitor 11:36:18 INFO - Successfully installed mozsystemmonitor 11:36:18 INFO - Cleaning up... 11:36:18 INFO - Return code: 0 11:36:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:18 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:18 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 11:36:18 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 11:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:18 INFO - 'HOME': '/Users/cltbld', 11:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:18 INFO - 'LOGNAME': 'cltbld', 11:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:18 INFO - 'MOZ_NO_REMOTE': '1', 11:36:18 INFO - 'NO_EM_RESTART': '1', 11:36:18 INFO - 'PAGER': '/bin/cat', 11:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:18 INFO - 'PWD': '/builds/slave/test', 11:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:18 INFO - 'SHELL': '/bin/bash', 11:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:18 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:18 INFO - 'USER': 'cltbld', 11:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:18 INFO - 'XPC_FLAGS': '0x0', 11:36:18 INFO - 'XPC_SERVICE_NAME': '0', 11:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:18 INFO - Downloading/unpacking jsonschema==2.5.1 11:36:18 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 11:36:18 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 11:36:18 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 11:36:18 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 11:36:18 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 11:36:18 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 11:36:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:36:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:21 INFO - Downloading functools32-3.2.3-2.tar.gz 11:36:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:36:21 INFO - warning: no files found matching '*.txt' 11:36:21 INFO - no previously-included directories found matching 'build' 11:36:21 INFO - no previously-included directories found matching 'dist' 11:36:21 INFO - no previously-included directories found matching '.git*' 11:36:21 INFO - Installing collected packages: jsonschema, functools32 11:36:21 INFO - Running setup.py install for functools32 11:36:21 INFO - warning: no files found matching '*.txt' 11:36:21 INFO - no previously-included directories found matching 'build' 11:36:21 INFO - no previously-included directories found matching 'dist' 11:36:21 INFO - no previously-included directories found matching '.git*' 11:36:21 INFO - Successfully installed jsonschema functools32 11:36:21 INFO - Cleaning up... 11:36:21 INFO - Return code: 0 11:36:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:36:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:36:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:36:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:21 INFO - 'HOME': '/Users/cltbld', 11:36:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:21 INFO - 'LOGNAME': 'cltbld', 11:36:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:21 INFO - 'MOZ_NO_REMOTE': '1', 11:36:21 INFO - 'NO_EM_RESTART': '1', 11:36:21 INFO - 'PAGER': '/bin/cat', 11:36:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:21 INFO - 'PWD': '/builds/slave/test', 11:36:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:21 INFO - 'SHELL': '/bin/bash', 11:36:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:21 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:21 INFO - 'USER': 'cltbld', 11:36:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:21 INFO - 'XPC_FLAGS': '0x0', 11:36:21 INFO - 'XPC_SERVICE_NAME': '0', 11:36:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:36:22 INFO - Cleaning up... 11:36:22 INFO - Return code: 0 11:36:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:22 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:22 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 11:36:22 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 11:36:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:22 INFO - 'HOME': '/Users/cltbld', 11:36:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:22 INFO - 'LOGNAME': 'cltbld', 11:36:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:22 INFO - 'MOZ_NO_REMOTE': '1', 11:36:22 INFO - 'NO_EM_RESTART': '1', 11:36:22 INFO - 'PAGER': '/bin/cat', 11:36:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:22 INFO - 'PWD': '/builds/slave/test', 11:36:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:22 INFO - 'SHELL': '/bin/bash', 11:36:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:22 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:22 INFO - 'USER': 'cltbld', 11:36:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:22 INFO - 'XPC_FLAGS': '0x0', 11:36:22 INFO - 'XPC_SERVICE_NAME': '0', 11:36:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:22 INFO - Downloading/unpacking blobuploader==1.2.4 11:36:22 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 11:36:22 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 11:36:22 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 11:36:22 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 11:36:22 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 11:36:22 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 11:36:24 INFO - Downloading blobuploader-1.2.4.tar.gz 11:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:36:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:36:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:36:25 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 11:36:25 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 11:36:25 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 11:36:25 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 11:36:25 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 11:36:25 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 11:36:25 INFO - Downloading docopt-0.6.1.tar.gz 11:36:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:36:26 INFO - Installing collected packages: blobuploader, requests, docopt 11:36:26 INFO - Running setup.py install for blobuploader 11:36:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:36:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:36:26 INFO - Running setup.py install for requests 11:36:26 INFO - Running setup.py install for docopt 11:36:26 INFO - Successfully installed blobuploader requests docopt 11:36:26 INFO - Cleaning up... 11:36:27 INFO - Return code: 0 11:36:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:27 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:27 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 11:36:27 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 11:36:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:27 INFO - 'HOME': '/Users/cltbld', 11:36:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:27 INFO - 'LOGNAME': 'cltbld', 11:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:27 INFO - 'MOZ_NO_REMOTE': '1', 11:36:27 INFO - 'NO_EM_RESTART': '1', 11:36:27 INFO - 'PAGER': '/bin/cat', 11:36:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:27 INFO - 'PWD': '/builds/slave/test', 11:36:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:27 INFO - 'SHELL': '/bin/bash', 11:36:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:27 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:27 INFO - 'USER': 'cltbld', 11:36:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:27 INFO - 'XPC_FLAGS': '0x0', 11:36:27 INFO - 'XPC_SERVICE_NAME': '0', 11:36:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-KYwljy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-sfbfml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-jFHrlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-wNX6qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-3w4kEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-4vg4bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:36:27 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-H3RHzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-8rMYj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-33ZZQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ACSbi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1HQysK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-cWQWR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-WhqP_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-qX7978-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-rtGZpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-nJ9Jmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:36:28 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Djt9KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:36:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:36:29 INFO - Running setup.py install for manifestparser 11:36:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:36:29 INFO - Running setup.py install for mozcrash 11:36:29 INFO - Running setup.py install for mozdebug 11:36:29 INFO - Running setup.py install for mozdevice 11:36:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:36:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:36:29 INFO - Running setup.py install for mozfile 11:36:29 INFO - Running setup.py install for mozhttpd 11:36:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:36:29 INFO - Running setup.py install for mozinfo 11:36:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Running setup.py install for mozInstall 11:36:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Running setup.py install for mozleak 11:36:30 INFO - Running setup.py install for mozlog 11:36:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Running setup.py install for moznetwork 11:36:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Running setup.py install for mozprocess 11:36:30 INFO - Running setup.py install for mozprofile 11:36:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:36:30 INFO - Running setup.py install for mozrunner 11:36:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:36:31 INFO - Running setup.py install for mozscreenshot 11:36:31 INFO - Running setup.py install for moztest 11:36:31 INFO - Running setup.py install for mozversion 11:36:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:36:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:36:31 INFO - Cleaning up... 11:36:31 INFO - Return code: 0 11:36:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:31 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:31 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 11:36:31 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 11:36:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:31 INFO - 'HOME': '/Users/cltbld', 11:36:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:31 INFO - 'LOGNAME': 'cltbld', 11:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:31 INFO - 'MOZ_NO_REMOTE': '1', 11:36:31 INFO - 'NO_EM_RESTART': '1', 11:36:31 INFO - 'PAGER': '/bin/cat', 11:36:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:31 INFO - 'PWD': '/builds/slave/test', 11:36:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:31 INFO - 'SHELL': '/bin/bash', 11:36:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:31 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:31 INFO - 'USER': 'cltbld', 11:36:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:31 INFO - 'XPC_FLAGS': '0x0', 11:36:31 INFO - 'XPC_SERVICE_NAME': '0', 11:36:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:36:31 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-F3pDW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-j6kKDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-rjmBLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-yT3XVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-oNTsCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fbVl7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-9yuZYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-b6cJb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-C1x9CY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-x3Mx0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:36:32 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)) 11:36:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:36:32 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ImdIrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:36:33 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)) 11:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:36:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Wqx4sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:36:33 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)) 11:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:36:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-houU3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:36:33 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)) 11:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:36:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-bJz_uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:36:33 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)) 11:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-mknORg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:36:33 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)) 11:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:36:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-boZgGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:36:33 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)) 11:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:36:33 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-3A2kwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:36:33 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)) 11:36:33 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)) 11:36:33 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)) 11:36:33 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)) 11:36:33 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)) 11:36:33 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)) 11:36:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:36:36 INFO - Downloading blessings-1.6.tar.gz 11:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:36:36 INFO - Installing collected packages: blessings 11:36:36 INFO - Running setup.py install for blessings 11:36:36 INFO - Successfully installed blessings 11:36:36 INFO - Cleaning up... 11:36:36 INFO - Return code: 0 11:36:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:36:36 INFO - Reading from file tmpfile_stdout 11:36:36 INFO - Current package versions: 11:36:36 INFO - blessings == 1.6 11:36:36 INFO - blobuploader == 1.2.4 11:36:36 INFO - docopt == 0.6.1 11:36:36 INFO - functools32 == 3.2.3-2 11:36:36 INFO - jsonschema == 2.5.1 11:36:36 INFO - manifestparser == 1.1 11:36:36 INFO - mozInstall == 1.12 11:36:36 INFO - mozcrash == 0.17 11:36:36 INFO - mozdebug == 0.1 11:36:36 INFO - mozdevice == 0.48 11:36:36 INFO - mozfile == 1.2 11:36:36 INFO - mozhttpd == 0.7 11:36:36 INFO - mozinfo == 0.9 11:36:36 INFO - mozleak == 0.1 11:36:36 INFO - mozlog == 3.3 11:36:36 INFO - moznetwork == 0.27 11:36:36 INFO - mozprocess == 0.23 11:36:36 INFO - mozprofile == 0.28 11:36:36 INFO - mozrunner == 6.12 11:36:36 INFO - mozscreenshot == 0.1 11:36:36 INFO - mozsystemmonitor == 0.3 11:36:36 INFO - moztest == 0.7 11:36:36 INFO - mozversion == 1.4 11:36:36 INFO - psutil == 3.1.1 11:36:36 INFO - requests == 1.2.3 11:36:36 INFO - wsgiref == 0.1.2 11:36:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:36: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')]} 11:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36: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')]} 11:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:36 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:36 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 11:36:36 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 11:36:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:36 INFO - 'HOME': '/Users/cltbld', 11:36:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:36 INFO - 'LOGNAME': 'cltbld', 11:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:36 INFO - 'MOZ_NO_REMOTE': '1', 11:36:36 INFO - 'NO_EM_RESTART': '1', 11:36:36 INFO - 'PAGER': '/bin/cat', 11:36:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:36 INFO - 'PWD': '/builds/slave/test', 11:36:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:36 INFO - 'SHELL': '/bin/bash', 11:36:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:36 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:36 INFO - 'USER': 'cltbld', 11:36:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:36 INFO - 'XPC_FLAGS': '0x0', 11:36:36 INFO - 'XPC_SERVICE_NAME': '0', 11:36:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 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)) 11:36:37 INFO - Cleaning up... 11:36:37 INFO - Return code: 0 11:36:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:36:37 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 0x101acaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d9d8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, '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.b2qZz5Mspx/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.NFmzziZDib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 11:36:37 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 11:36:37 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 11:36:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:36:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:36:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:36:37 INFO - 'HOME': '/Users/cltbld', 11:36:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:36:37 INFO - 'LOGNAME': 'cltbld', 11:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:36:37 INFO - 'MOZ_NO_REMOTE': '1', 11:36:37 INFO - 'NO_EM_RESTART': '1', 11:36:37 INFO - 'PAGER': '/bin/cat', 11:36:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:36:37 INFO - 'PWD': '/builds/slave/test', 11:36:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:36:37 INFO - 'SHELL': '/bin/bash', 11:36:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:36:37 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:36:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:36:37 INFO - 'USER': 'cltbld', 11:36:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:36:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:36:37 INFO - 'XPC_FLAGS': '0x0', 11:36:37 INFO - 'XPC_SERVICE_NAME': '0', 11:36:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:36:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:36:37 INFO - Cleaning up... 11:36:37 INFO - Return code: 0 11:36:37 INFO - Running post-action listener: _resource_record_post_action 11:36:37 INFO - Running post-action listener: _start_resource_monitoring 11:36:37 INFO - Starting resource monitoring. 11:36:37 INFO - [mozharness: 2016-09-02 18:36:37.418028Z] Finished create-virtualenv step (success) 11:36:37 INFO - [mozharness: 2016-09-02 18:36:37.419031Z] Running install step. 11:36:37 INFO - Running pre-action listener: _resource_record_pre_action 11:36:37 INFO - Running main action method: install 11:36:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:36:37 INFO - Reading from file tmpfile_stdout 11:36:37 INFO - Detecting whether we're running mozinstall >=1.0... 11:36:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:36:37 INFO - Reading from file tmpfile_stdout 11:36:37 INFO - Output received: 11:36:37 INFO - Usage: mozinstall [options] installer 11:36:37 INFO - Options: 11:36:37 INFO - -h, --help show this help message and exit 11:36:37 INFO - -d DEST, --destination=DEST 11:36:37 INFO - Directory to install application into. [default: 11:36:37 INFO - "/builds/slave/test"] 11:36:37 INFO - --app=APP Application being installed. [default: firefox] 11:36:37 INFO - mkdir: /builds/slave/test/build/application 11:36:37 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'] 11:36:37 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 11:37:12 INFO - Reading from file tmpfile_stdout 11:37:12 INFO - Output received: 11:37:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:37:12 INFO - Running post-action listener: _resource_record_post_action 11:37:12 INFO - [mozharness: 2016-09-02 18:37:12.405788Z] Finished install step (success) 11:37:12 INFO - [mozharness: 2016-09-02 18:37:12.405949Z] Running run-tests step. 11:37:12 INFO - Running pre-action listener: _resource_record_pre_action 11:37:12 INFO - Running main action method: run_tests 11:37:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:37:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:37:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:37:12 INFO - 2016-09-02 11:37:12.420 screenresolution[1641:7864] starting screenresolution argv=screenresolution get 11:37:12 INFO - 2016-09-02 11:37:12.423 screenresolution[1641:7864] Display 0: 1600x1200x32@60 11:37:12 INFO - 2016-09-02 11:37:12.428 screenresolution[1642:7866] starting screenresolution argv=screenresolution list 11:37:12 INFO - Available Modes on Display 0 11:37:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:37:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:37:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:37:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:37:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:37:12 INFO - Intel Iris: 11:37:12 INFO - Chipset Model: Intel Iris 11:37:12 INFO - Type: GPU 11:37:12 INFO - Bus: Built-In 11:37:12 INFO - VRAM (Dynamic, Max): 1536 MB 11:37:12 INFO - Vendor: Intel (0x8086) 11:37:12 INFO - Device ID: 0x0a2e 11:37:12 INFO - Revision ID: 0x0009 11:37:12 INFO - Displays: 11:37:12 INFO - Display: 11:37:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:37:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:37:12 INFO - Display Serial Number: 1600x1200 60 11:37:12 INFO - Main Display: Yes 11:37:12 INFO - Mirror: Off 11:37:12 INFO - Online: Yes 11:37:12 INFO - Rotation: Supported 11:37:12 INFO - Return code: 0 11:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:37:12 INFO - Python 2.7.3 11:37:12 INFO - Return code: 0 11:37:12 INFO - grabbing minidump binary from tooltool 11:37: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')]} 11:37:12 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 0x101a2ed30>, '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 0x101793db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a26b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:37:12 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 11:37:12 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 11:37:12 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 11:37:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:37:12 INFO - Return code: 0 11:37:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:37:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:37:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:37:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:37:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 11:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --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 11:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2qZz5Mspx/Render', 11:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:37:12 INFO - 'HOME': '/Users/cltbld', 11:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:37:12 INFO - 'LOGNAME': 'cltbld', 11:37:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:37:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:12 INFO - 'MOZ_NO_REMOTE': '1', 11:37:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:37:12 INFO - 'NO_EM_RESTART': '1', 11:37:12 INFO - 'PAGER': '/bin/cat', 11:37:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:12 INFO - 'PWD': '/builds/slave/test', 11:37:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:37:12 INFO - 'SHELL': '/bin/bash', 11:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NFmzziZDib/Listeners', 11:37:12 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 11:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:37:12 INFO - 'USER': 'cltbld', 11:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:12 INFO - 'XPC_FLAGS': '0x0', 11:37:12 INFO - 'XPC_SERVICE_NAME': '0', 11:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:37:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 11:37:13 INFO - mozversion application_buildid: 20160902095849 11:37:13 INFO - mozversion application_changeset: 0907f97ba5e3ec9aaeb58c69651e8e6927472f1b 11:37:13 INFO - mozversion application_display_name: Nightly 11:37:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:37:13 INFO - mozversion application_name: Firefox 11:37:13 INFO - mozversion application_remotingname: firefox 11:37:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:37:13 INFO - mozversion application_vendor: Mozilla 11:37:13 INFO - mozversion application_version: 51.0a1 11:37:13 INFO - mozversion platform_buildid: 20160902095849 11:37:13 INFO - mozversion platform_changeset: 0907f97ba5e3ec9aaeb58c69651e8e6927472f1b 11:37:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:37:13 INFO - mozversion platform_version: 51.0a1 11:37:13 INFO - using testdate: 1472841433 11:37:13 INFO - actual date: 1472841433 11:37:13 INFO - starting webserver on 'localhost:49273' 11:37:13 INFO - SUITE-START | Running 2 tests 11:37:13 INFO - TEST-START | dromaeo_css 11:37:13 INFO - operating with platform_type : mac_ 11:37:13 INFO - Initialising browser for dromaeo_css test... 11:37:13 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpq_BAQe/profile http://localhost:49273/getInfo.html) 11:37:15 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 11:37:15 INFO - PROCESS | 1652 | colorDepth:24 11:37:15 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 11:37:15 INFO - PROCESS | 1652 | __metrics 11:37:15 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:37:15 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:37:16 INFO - TEST-INFO | 1652: exit 0 11:37:16 INFO - Browser initialized. 11:37:16 INFO - Running cycle 1/1 for dromaeo_css test... 11:37:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpq_BAQe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:37:28 INFO - PROCESS | 1655 | RSS: Main: 234078208 11:37:28 INFO - PROCESS | 1655 | 11:38:19 INFO - PROCESS | 1655 | 1472841499464 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] 11:40:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 11:40:32 INFO - PROCESS | 1655 | RSS: Main: 358821888 11:40:32 INFO - PROCESS | 1655 | 11:43:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 11:43:36 INFO - PROCESS | 1655 | RSS: Main: 369434624 11:43:36 INFO - PROCESS | 1655 | 11:46:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 11:46:38 INFO - PROCESS | 1655 | RSS: Main: 333500416 11:46:38 INFO - PROCESS | 1655 | 11:49:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 11:49:41 INFO - PROCESS | 1655 | RSS: Main: 338620416 11:49:41 INFO - PROCESS | 1655 | 11:52:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 11:52:45 INFO - PROCESS | 1655 | RSS: Main: 322883584 11:52:45 INFO - PROCESS | 1655 | 11:55:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:55:49 INFO - PROCESS | 1655 | RSS: Main: 421486592 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | __start_tp_report 11:55:49 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 11:55:49 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 11:55:49 INFO - PROCESS | 1655 | |i|pagename|runs| 11:55:49 INFO - PROCESS | 1655 | |0;dojo.html;3323.102762254191;3338.666133386661;3360.848761805717;3411.4200585900394;3439.0886415100003;5614.961232172051;5653.4911857932775;5740.540756739461;5761.62549434287;5766.423357664242;5713.485786279234;5741.511971482429;5788.652680839153;5794.71026448678;5855.23881895353;5099.592032637399;5126.205438157073;5183.041137389576;5191.740412979355;5297.47025297471;5248.895022099556;5311.176767601009;5319.015982043335;5327.46725327468;5350.063738845686;5118.309028281172;5130.127878260667;5149.84550463483;5168.2764412982215;5232.895342093175;5152.201408781645;5163.819266325698;5173.715445650488;5182.689038657692;5198.636095473318;5209.114450543417;5212.061828870802;5221.295125158131;5221.530062294374;5224.8693782655355;5617.578681598897;5631.2960879890015;5651.745671444794;5734.111212762028;5783.392743761929;4920.852374428737;5166.509181627739;6246.500279977591;6262.96868515659;6277.96861015692;6258.06129080637;6311.368863113697;6317.589356691847;6318.33657465964;6347.714352854133;5689.886202276014;5729.169270455761;5742.7702891883855;5815.244018277639;5848.1227815827115;5303.708296043755;6240.53196010292;6265.404786545271;6273.027680709497;6285.120083188357;5094.745262736922;6184.5980011299125;6200.441960223511;6206.565540412128;6227.782027629011;6124.782202784781;6198.845028874224;6238.157848690369;6309.116723658692;6318.842028949313;5615.185798059259;6193.938060619426;6265.843353916189;6274.874502509924;6281.403266689657;6196.938030619726;6285.560010799291;6287.1198032275515;6290.937090629127;6323.810285691345;4878.43897951733;4895.143349913751;4981.501849814989;4999.725015124203;5030.37120359955;3723.646253605903;4425.845095421645;4431.180231657154;4496.920739022611;4551.931721024187;5747.08046712524;5749.281339832521;5757.827265182052;5767.336756272998;5776.364599894008;6106.005662907935;6204.441600255998;6308.337624549448;6325.810225693236;6352.904706429407;5696.174054762037;5729.0260655688635;5738.8278351649515;5792.884142317214;5807.912881306784;5425.9186112208345;5449.373322067933;5456.645318054314;5459.372172200247;5471.23402723619;6243.063540468785;6321.1782468278825;6321.810345689637;6343.492520598434;6343.936560634427;6236.438720515175;6256.311805701278;6301.684915754102;6352.396522330371;6357.936420635734;6255.468285195628;6294.464970477561;6308.936910631019;6316.052592111037;6332.113504109337;6096.512279017757;6224.875502490016;6244.750209991549;6254.249490061222;6262.874742505033;6107.219158344477;6140.596275277727;6142.314611233224;6254.906176407449;6260.530460215593;5837;6067.120267561268;6070.3929607038945;6091.329953705001;6124.663143527148;5948.13752005948;6041.879162416639;6044.637321760532;6067.757289708362;6074.210352654127;5911.733971971185;5931.228940237742;5937.990541608024;5991.071383935497;6049.485793707584;6085.695715214133;6145.201123853871;6149.692515374303;6158.784442544489;6179.5056395489155;5786.652800831964;5919.674417906885;5961.642301461926;5967.46292833647;5986.640801551921 11:55:49 INFO - PROCESS | 1655 | |1;ext.html;468.9437267527897;492.6379111353156;498.4093848789183;521.7678133230711;524.2293828072732;30122;37842.62157378425;39344.80327598361;39619.40570891439;41715.79142104293;40566.59433405665;40764.38853417201;40826.38760418589;40863.591364086424;40880;8694.21752042316;9516;9704.514774261293;9705.466199359033;9724;8741.606627701769;8880.200781929625;8897;8907.77730556738;11656.9508744213;11691.298522088659;11951.10366722495;12017.879821201763;12035.277883326984;12043.759124817498;12048.915597596215;12125.93937030318;12131.575394861138;12131.575394861182;12137;11556.76886462266;11702.590409335633;11703.882961170364;11790;11819.290842549475;38524.614753852664;38632.61367386318;38720.22559548821;38725.41911871324;38835.805820971;3232.595925509311;3312.565877701282;3400.4559270516597;3523.841427135785;3588.4258518637153;28040.719592804016;30296.292233982498;30594.541081883788;30662.386752265054;30988.53517197244;3189.6012998375204;3239.400710868497;3273.770836041478;3372.3761104195555;3387.237871478922;448792;455173.72413138056;455207;456390;459693;31726;55285.170722439194;55698.72150639221;55742.72128639412;55827.72086139624;32580.837095814368;34110.31779364399;34230.82884585561;34281.48577771335;34309.828450858084;14183.85816141846;14341.856581434053;14347.92826035884;14360.281985900872;14361.569152925429;12073.45669444877;12175.756484870253;12195;12197.878021219853;12206.633800985985;7117.110361204849;7218.494705370574;7508.737194198176;7590.658420371096;7607.771766847003;3198.1684761962056;3228.112269125962;3239.9676003239665;3263.0584802042745;3279.6884295991845;918.3479729392072;925.7824411263348;1020.336781092296;1028.7788125553043;1067.1889364083197;24325.87837060839;29305.706942930297;29550;29755.404891902042;30219.244518887204;1042.3433237060603;1070.9143268538498;1082.2478377527543;1090.9236353455183;1162.9476673549711;14741;15189.6202594936;15421.614459638375;15531.456399026205;15579.806139741553;591100;596890;597436;597882;598649;29581.26046848846;36906.44640330344;36907.26185476276;36917.446238306446;36981.630183698355;1310.2793463594985;1353.2151352215692;1371.8833899118686;1378.7173629405947;1379.2896658221025;23724.485979444635;24259.636105458787;27337.04320348778;28961;29451.852740736158;47327.52672473231;47652.523474766196;47733.76133119312;47743.522564773906;47849.76075119741;550541;552893;555576;557017;558672;22777.97499112511;23248.302550923498;25125.74874251234;25611.615825762256;25890.35274118162;42656;45205.321920170565;45527.317090243014;46040.53959460497;46171.538284618066;36197.914062578166;37267.62732372642;37531.62468375391;37605.24789504249;38652.420213697376;36832.26335473329;37537.2492550142;38022.63664957817;38820.02949926162;39040.02399940024;36879.631203688696;37022.62977370192;37767.43348849715;37802;37815.67645132407;26959.595606066316;28025.859870700515;29014.41971160607;32221.35557288888;33665.831670841486;20947.49628829809;24397.718141572986;24518.680092828243;24665.876670616533;24749.505009900062 11:55:49 INFO - PROCESS | 1655 | |2;jquery.html;328.26960013968915;371.4261466034977;381.427858212681;394.396573242938;401.5984015984016;19778.90110549447;20896.8955155224;21776.782232177673;21949.231776887813;22099.78451185188;20272.594548109028;20577.382678519632;21010.36968890932;21208.4697882553;21229.893850530763;5197.714125723083;5732.742026608792;5847.239858818348;6032.27612686476;6037.124616930542;4185.267578173807;4407.713498622596;5947.078202878561;6054.334023257438;6348.650824204667;6648.966755166218;6725.865482690343;6726.3609957054005;6730.091437655928;6745.089412929241;6401.199850018747;6652.434543063821;6709.29552397001;6715.160604924385;6724.058631791553;5830.3586605473365;5843.152742852305;5850.06398976166;7153.463490238253;7533.510321829063;28863;29434.705652943623;29523.55714664282;29645.258868528243;30071.248218794706;2211.137656314039;2370.7155141382973;2578.8323758955607;2704.797140214472;2992.2369795701957;15421.845124283042;15828.920855395765;16489.587760305967;16740.66518669632;17455.65088698219;1945.9027048647652;1948.3278268997174;1972.2111155537757;2011.647961606713;2024.7064175694443;136273;139969.3001534996;142625;143639.28180359135;144600.55399446082;21150;21341.25305614296;21395.67906481404;21432.463098754717;21556.353309400743;35070.843624073954;35087.64912350895;35259.294814103574;35261;35336;10340.37957722539;10647.201459890539;10786.568537258421;10947.069499092508;10959.83560246597;8026.799330016679;8259;8844.955775221211;8852.911470885338;9005.774855628531;9371.671991480265;9709.611615535441;9806;9816.607335706634;9828.557714902847;2928.2679330167457;3180.25264062945;3559.914226160994;3645.76302540334;3785.37541305687;838.7316058861228;848.6647774129184;854.8290341931638;894.5750768384966;912.1653686876434;21196.682049769264;22042.669359959615;22130.668039979413;22184.667229991563;22206.333809985725;919.9099067604909;920.1120918313859;920.5259291464901;951.4453691837439;1032.5353590883983;10801.351918884891;11072.833907491395;11566;11602.303861768321;11657.825132623017;597515;599523.0310029922;611556;611903;613502;63566.046509302396;77561.73410462479;86047;125025;129125.70874291325;869.7086476030474;926.5228407370209;975.8826144065006;993.4089216915924;999.9250056245811;19154.52113697169;19278.13248403797;19502.707459388403;19516.121774519896;19538.511537211685;43005.56994430016;53977;54533;55842.72078639581;56385;69991.65004174947;77561.06097347611;79060.418791625;79163;80085;7182.569045857265;7202.675879585327;7604.5437273763755;7860.174681658572;8360.414770965974;10434.582616695247;12953.805692914802;13090.345482726016;13355.732885342433;13457.192568445924;11344.546218151181;11385.03227225695;11476.885231147582;11590;11652.009579185864;11048.060914822008;11149.777004460027;11297.938041637679;11431.59989400367;11562.88437115618;11501.48243329069;11521.539138434704;11596.710082248012;11666.591669291534;11714.004309633776;6652.567583107082;7981.40139489529;8136.7152149674475;8236.588170591567;8314.376421768271;9932.659091022619;10595.629152979609;11131.777364452828;11306.94346528295;11597.82603260935 11:55:49 INFO - PROCESS | 1655 | |3;mootools.html;1474.8746356559695;1509.0719207687273;1715.5796829776707;1893.867429279951;1920.6638838203319;7953.801154971115;8231.711890083849;8420.621072051757;8947.955260223705;8975.820483590333;8416.915830841705;8695.173958473935;8706.738797836058;8797.208251257385;8850.601722922467;2376.132711560281;2410.7950824179966;2413.6379543068592;2414.1429792423614;2414.4808866093786;2116.407405926343;2187.507810742578;2367.3134790910703;2376.607859703147;2431.3435372449426;2311.4683622766775;2384.8688322142275;2388.5461762265113;2394.365493144318;2394.5091256292503;2382.1781485387514;2400.7479214682467;2402.243293362603;2409.8433601815827;2417.9758202417925;2393.1743548475747;2403.5433267679086;2404.8797560121925;2407.3740827385004;2410.650455683916;8834.779130521723;8908.554572271361;8914.375993680445;8914.910850891538;8922.553872306358;5215.921761173586;5396.865078373033;5400.621956463049;5403.864903377408;5407.242985981966;3408.9488657670154;3545.982270088659;3607.603163651996;3614.1868079682126;3631.94552081719;1040.8178568750438;1075.4461452352045;1078.66561365976;1080.3895798873612;1093.3549205968516;11541.365224912626;11592.942035289854;11613.070954323633;11618.709532261677;11643.126765492621;4524.140413321458;4644.419447569054;4830.2513110467935;4843.418789745253;4844.588210002189;9910.35582687123;9912.00879912003;9916.801663966824;9923.900760992297;9928.453935033498;4619.422572178478;5364.678119312854;5369.275147855068;5377.462253774669;5378.273933019071;4774.379330686988;4874.707517548887;4926.556609905054;4926.778294976734;4930.580900623416;3907.199024200032;3955.248502784462;3988.222296652183;3999.240334252919;4025.9798701006307;1192.4574318684977;1241.050596293836;1280.1742875845027;1297.7289742949838;1308.7055412532295;744.0959234529964;842.5576572299469;877.9912200877909;884.0982198157972;888.7955770172896;4859.882227087719;4884.902301953941;5700.287464066992;5816.331121921034;5822.737976791054;914.7667344827026;931.7950050988771;940.7977284883783;947.3605316411395;975.9853602195973;3797.696184305249;3832.3868181090893;3898.9805050974564;3899.707521935866;3958.7822669752863;10206.183505319557;10230.948845255725;10603.416812075257;10674.46627668629;10832.945835270773;10792.028717415471;10796.514156862802;10805.83791243132;10813.62152324665;10815.242932994772;7680.078390593165;8240.876386854203;8340.457870238415;8350.582470876554;8353.624086916103;5200.583953283653;5300.3639563252655;5835.124731290341;5869.706514674163;6036.094585811988;9643.951780241054;9735.1725103367;10011.048950349705;10071.446070465901;10211.336263142872;7966.522008679266;10081.899181008095;10316.17470602365;10327.638532651321;10349.74125646865;7310.451716121206;7480.3641690456925;7556.357709594746;7607.35337496319;7613.6573854175585;8330.958345208477;8498.235158835734;8507.276881464897;8510.234078932677;8523.701670441753;7335.1197856257595;7448.478606497493;7476.327130558276;7579.810504737426;7588.924110759042;6323.272823625158;7309.305615966475;7389.150247721581;7425.486133248352;7446.292602202782;6952.130983627047;6965.233824279427;7052.647367631701;7052.929470705432;7062.788116356517;4840.806367745251;4903.583195428429;4910.533499317559;4976.4075495842435;5043.4956504349275;4790.904181916412;4799.736014519095;4836.782344794422;4893.168161412497;4930.309756634002 11:55:49 INFO - PROCESS | 1655 | |4;prototype.html;298.99551506727397;301.4603859092225;308.27401469539234;311.2405730018754;312.551488613839;5458.4541545845395;5468.234447177393;5477.3427188737405;5493.835184944453;5523.613347065707;4136.441580386654;5106.948930510694;5317.3619165700175;5389.757460914258;5484.643498172616;3533.469979503069;3972.5232972043395;4017.27689015977;4075.9999999999927;4095.3242714952;3817.7973938209375;4013.3779264214095;4147.751134931914;4192.727472714264;4194.853180138696;3826.7129965252716;4042.090529630839;4082.6529744971713;4093.4269202311552;4109.897252568695;3362.798232106057;3363.9159021024425;3576.5708115026105;4088.8160032798446;4093.7339072960312;3061.9081427557207;3486.477028445728;3512.2965361799506;3513.3851575974104;3513.5608048993877;4347.869553915973;5053;5221.39953905302;5222.73886305687;5229.686218826844;4107.466029416157;4297.35539669049;4574.4968053514085;4617.422822147231;4633.305004249356;5009.874753131165;5164.948350516484;5463.863403414906;5574;5675.31896172462;3258.7230085442766;3673.6142705016123;3871.1031594518763;3886.812813492937;3893.201893611803;5067.569256613192;5371.462853714636;5700.287464066992;5934.495567876736;5990.670513121733;3159.6524382317925;3257.8371081445835;3920.2159568086495;3925.175713100281;3944.9013774655864;3556.5732112146193;3985.4221137934837;4007.5591684914634;4022.416749571296;4042.333015052542;4132.73137246078;4469.642428605704;4627.722336659811;4703.811847526129;4718.4809670936165;4594.724316541018;5006.499350064964;5678.14827775837;5731.799387021518;5777.728899642072;1255.7927941889668;1282.83262231369;1358.151155527795;1364.4951367993906;1368.9178649281075;851.3699862102;875.4134729731095;877.7360600734921;877.9473231606124;880.4629176202511;4569.954300456953;5176.023786322804;5400.297961265011;5427.3215848019;5469.671819690831;5536.2249285100115;5799.869025540063;5909.113632955005;5928.103007037706;5935.792247271324;4774.856754297377;5064.797408103635;5397.892042159214;5441.59188060897;5470.617056805985;2846.5445528715304;2928.912132636024;3011.9548206776917;3075.8923437679573;3089.1504836170234;5286.656367336111;5564.360098588714;5761.600655613208;6081.969590152021;6107.389261073857;5077.416097148801;5586.385497595261;6063.514918806485;6111.816645500642;6114.266288045463;5656.773729050792;6151.785357271378;6169.5064394848305;6176.505879529627;6182.969085154546;4605.700629459075;5176.9741151294;5232.651103293971;5902.317839415746;5957.3446920837805;4751.40607424072;5795.82612521625;5807.128930660435;5825.300963884361;5842.415758424124;5356;5454.772676147835;5677.807660391364;6076.66578338178;6159.599626024294;3573.5533058367705;3612.27754449106;3885.222955408986;3901.2249426511435;3932.9213415732097;5839.562032847469;5873.063468265867;5954.345022047484;5975.7012149391485;5978.641281523122;5571.3592936811165;5739.704118352693;5758.337791154071;5811.360750317546;5813.418658134322;5003.799848006039;5753.884922301614;5764.769409223584;5786.826395208151;5798.913016304843;5003.849884503471;5404.919016196855;5591.692456914909;5658.830235092953;5681.0400411857845;5781.768729250783;5794.246747922743;5815.313559067823;5829.6793676346515;5832.445917637987;4711.929321060118;4737.194676904865;5625.46558076982;5635.520980716685;5637.069883469182 11:55:49 INFO - PROCESS | 1655 | |5;yui.html;617.9721912513936;712.3873468816817;713.2795876165073;739.8705226585346;857.811281518066;13121.47514099435;14923.328450219738;15093.320800563974;15522.301496432645;15645.608859778511;14316.35576399062;14323.427062917472;14465.855341446582;14476.782848257284;14921.477748278812;839.1356902390532;854.6495936665968;855.995720021399;856.9700060497884;857.8670306102533;683.7470136049668;698.3749544157976;839.7858546070744;854.3848429131046;863.6329559937033;887.0951629338103;887.6804350433839;888.2139306713573;889.0664801957951;889.3818795936827;880.9955950220208;881.154092071612;882.1046637662713;882.849915514364;883.6862913665652;524.9842504724865;652.5660435810181;661.2726001398423;668.1981622053506;669.993300066998;14454.349554270084;14514.70970580593;14544;14544.490942817056;14546.92726536371;13275.53535626258;13609.387577558935;13700.862991370059;13717.245551494663;13929.742435509037;10419.687409377731;12326.12882516585;12461.501539938392;12467.251964882045;12581.245125292511;4372.934405983913;4375.234333991571;4398.648108151372;4407.140607581523;4454.153710794939;94853.5257323709;114371;114556;115084.4245778774;115214.84785152209;9117.772055698528;10503.474826258656;10651.337165708574;10765.569377224823;10849.186311026704;21303.78696213049;21310.254141104986;21316.36050918475;21346;21381.67927481089;7396.667149978264;7672.309492145622;7686.346660533805;7814.57019863913;7927.4054445915635;4277.1873344064525;4287.828486860491;4295.82816687329;4342.044750154961;4490.663200259994;2989.37223183134;3429.554157959499;3556.5554305711785;3575.070481674731;3577.856885724542;479.8643736620556;628.7864421666137;645.4803882874237;649.2630863969429;649.9892666903012;536.4822945857188;544.4419470043158;554.1687468796805;579.8721486708396;607.902735562308;7885.487443316166;8466.500695979305;8904.910950890539;8915.777105572413;8947.955260223787;541.1017710600385;584.1500616602833;592.3217915486744;610.845502001218;623.5603899251017;4239.936400953989;4340.305551111807;4355.3684715716045;4406.361077643788;4415.359772832921;104328;149576;150625;157077;157451;90787;91324.5433772827;130020;130942;137352;71603;71828;73390;85651;115861;9292.02433744447;9443.386179898285;9602.90397096048;9701.805963880824;9756.073247804336;61969.69015154895;63248;63984.68007659932;65832;66286.66856665685;77778.61110694411;88723;93450;96842;97856;12755.87244127547;12781.936090319861;12805.615831525069;12914.483420663068;13173.538926137539;14387.28063596795;14445.92777036108;14496.34766435534;14753.188574628499;14866.331015104563;13324.067315287837;13379.53171638988;13474.86525134736;13506.527271545448;13509.662258443617;11202.94398528002;13250.271235082164;13291.335433228493;13487;13529.391177397238;10754.462276886281;12863.549775758178;13006.284654344103;13234.404451799885;13553.390097445441;9316.813663726824;10663.733406664895;10801.189910756568;10998.176959768442;11078.667639970814;11008.504617292401;11312.321260724382;11405.828912566152;11520.308781473139;11524.250923689933 11:55:49 INFO - PROCESS | 1655 | __end_tp_report 11:55:49 INFO - PROCESS | 1655 | __start_cc_report 11:55:49 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1192 11:55:49 INFO - PROCESS | 1655 | __end_cc_report 11:55:49 INFO - PROCESS | 1655 | __startTimestamp1472842549131__endTimestamp 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | ------- Summary: start ------- 11:55:49 INFO - PROCESS | 1655 | Number of tests: 6 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5729.20 Median:5911.73 stddev:651.56 (11.0%) stddev-sans-first:625.62 11:55:49 INFO - PROCESS | 1655 | Values: 3323.1 3338.7 3360.8 3411.4 3439.1 5615.0 5653.5 5740.5 5761.6 5766.4 5713.5 5741.5 5788.7 5794.7 5855.2 5099.6 5126.2 5183.0 5191.7 5297.5 5248.9 5311.2 5319.0 5327.5 5350.1 5118.3 5130.1 5149.8 5168.3 5232.9 5152.2 5163.8 5173.7 5182.7 5198.6 5209.1 5212.1 5221.3 5221.5 5224.9 5617.6 5631.3 5651.7 5734.1 5783.4 4920.9 5166.5 6246.5 6263.0 6278.0 6258.1 6311.4 6317.6 6318.3 6347.7 5689.9 5729.2 5742.8 5815.2 5848.1 5303.7 6240.5 6265.4 6273.0 6285.1 5094.7 6184.6 6200.4 6206.6 6227.8 6124.8 6198.8 6238.2 6309.1 6318.8 5615.2 6193.9 6265.8 6274.9 6281.4 6196.9 6285.6 6287.1 6290.9 6323.8 4878.4 4895.1 4981.5 4999.7 5030.4 3723.6 4425.8 4431.2 4496.9 4551.9 5747.1 5749.3 5757.8 5767.3 5776.4 6106.0 6204.4 6308.3 6325.8 6352.9 5696.2 5729.0 5738.8 5792.9 5807.9 5425.9 5449.4 5456.6 5459.4 5471.2 6243.1 6321.2 6321.8 6343.5 6343.9 6236.4 6256.3 6301.7 6352.4 6357.9 6255.5 6294.5 6308.9 6316.1 6332.1 6096.5 6224.9 6244.8 6254.2 6262.9 6107.2 6140.6 6142.3 6254.9 6260.5 5837.0 6067.1 6070.4 6091.3 6124.7 5948.1 6041.9 6044.6 6067.8 6074.2 5911.7 5931.2 5938.0 5991.1 6049.5 6085.7 6145.2 6149.7 6158.8 6179.5 5786.7 5919.7 5961.6 5967.5 5986.6 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:64753.79 Median:18755.39 stddev:144147.16 (768.6%) stddev-sans-first:144470.73 11:55:49 INFO - PROCESS | 1655 | Values: 468.9 492.6 498.4 521.8 524.2 30122.0 37842.6 39344.8 39619.4 41715.8 40566.6 40764.4 40826.4 40863.6 40880.0 8694.2 9516.0 9704.5 9705.5 9724.0 8741.6 8880.2 8897.0 8907.8 11657.0 11691.3 11951.1 12017.9 12035.3 12043.8 12048.9 12125.9 12131.6 12131.6 12137.0 11556.8 11702.6 11703.9 11790.0 11819.3 38524.6 38632.6 38720.2 38725.4 38835.8 3232.6 3312.6 3400.5 3523.8 3588.4 28040.7 30296.3 30594.5 30662.4 30988.5 3189.6 3239.4 3273.8 3372.4 3387.2 448792.0 455173.7 455207.0 456390.0 459693.0 31726.0 55285.2 55698.7 55742.7 55827.7 32580.8 34110.3 34230.8 34281.5 34309.8 14183.9 14341.9 14347.9 14360.3 14361.6 12073.5 12175.8 12195.0 12197.9 12206.6 7117.1 7218.5 7508.7 7590.7 7607.8 3198.2 3228.1 3240.0 3263.1 3279.7 918.3 925.8 1020.3 1028.8 1067.2 24325.9 29305.7 29550.0 29755.4 30219.2 1042.3 1070.9 1082.2 1090.9 1162.9 14741.0 15189.6 15421.6 15531.5 15579.8 591100.0 596890.0 597436.0 597882.0 598649.0 29581.3 36906.4 36907.3 36917.4 36981.6 1310.3 1353.2 1371.9 1378.7 1379.3 23724.5 24259.6 27337.0 28961.0 29451.9 47327.5 47652.5 47733.8 47743.5 47849.8 550541.0 552893.0 555576.0 557017.0 558672.0 22778.0 23248.3 25125.7 25611.6 25890.4 42656.0 45205.3 45527.3 46040.5 46171.5 36197.9 37267.6 37531.6 37605.2 38652.4 36832.3 37537.2 38022.6 38820.0 39040.0 36879.6 37022.6 37767.4 37802.0 37815.7 26959.6 28025.9 29014.4 32221.4 33665.8 20947.5 24397.7 24518.7 24665.9 24749.5 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:36564.21 Median:35079.25 stddev:100955.34 (287.8%) stddev-sans-first:101201.88 11:55:49 INFO - PROCESS | 1655 | Values: 328.3 371.4 381.4 394.4 401.6 19778.9 20896.9 21776.8 21949.2 22099.8 20272.6 20577.4 21010.4 21208.5 21229.9 5197.7 5732.7 5847.2 6032.3 6037.1 4185.3 4407.7 5947.1 6054.3 6348.7 6649.0 6725.9 6726.4 6730.1 6745.1 6401.2 6652.4 6709.3 6715.2 6724.1 5830.4 5843.2 5850.1 7153.5 7533.5 28863.0 29434.7 29523.6 29645.3 30071.2 2211.1 2370.7 2578.8 2704.8 2992.2 15421.8 15828.9 16489.6 16740.7 17455.7 1945.9 1948.3 1972.2 2011.6 2024.7 136273.0 139969.3 142625.0 143639.3 144600.6 21150.0 21341.3 21395.7 21432.5 21556.4 35070.8 35087.6 35259.3 35261.0 35336.0 10340.4 10647.2 10786.6 10947.1 10959.8 8026.8 8259.0 8845.0 8852.9 9005.8 9371.7 9709.6 9806.0 9816.6 9828.6 2928.3 3180.3 3559.9 3645.8 3785.4 838.7 848.7 854.8 894.6 912.2 21196.7 22042.7 22130.7 22184.7 22206.3 919.9 920.1 920.5 951.4 1032.5 10801.4 11072.8 11566.0 11602.3 11657.8 597515.0 599523.0 611556.0 611903.0 613502.0 63566.0 77561.7 86047.0 125025.0 129125.7 869.7 926.5 975.9 993.4 999.9 19154.5 19278.1 19502.7 19516.1 19538.5 43005.6 53977.0 54533.0 55842.7 56385.0 69991.7 77561.1 79060.4 79163.0 80085.0 7182.6 7202.7 7604.5 7860.2 8360.4 10434.6 12953.8 13090.3 13355.7 13457.2 11344.5 11385.0 11476.9 11590.0 11652.0 11048.1 11149.8 11297.9 11431.6 11562.9 11501.5 11521.5 11596.7 11666.6 11714.0 6652.6 7981.4 8136.7 8236.6 8314.4 9932.7 10595.6 11131.8 11306.9 11597.8 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5686.85 Median:4889.04 stddev:3182.52 (65.1%) stddev-sans-first:3175.70 11:55:49 INFO - PROCESS | 1655 | Values: 1474.9 1509.1 1715.6 1893.9 1920.7 7953.8 8231.7 8420.6 8948.0 8975.8 8416.9 8695.2 8706.7 8797.2 8850.6 2376.1 2410.8 2413.6 2414.1 2414.5 2116.4 2187.5 2367.3 2376.6 2431.3 2311.5 2384.9 2388.5 2394.4 2394.5 2382.2 2400.7 2402.2 2409.8 2418.0 2393.2 2403.5 2404.9 2407.4 2410.7 8834.8 8908.6 8914.4 8914.9 8922.6 5215.9 5396.9 5400.6 5403.9 5407.2 3408.9 3546.0 3607.6 3614.2 3631.9 1040.8 1075.4 1078.7 1080.4 1093.4 11541.4 11592.9 11613.1 11618.7 11643.1 4524.1 4644.4 4830.3 4843.4 4844.6 9910.4 9912.0 9916.8 9923.9 9928.5 4619.4 5364.7 5369.3 5377.5 5378.3 4774.4 4874.7 4926.6 4926.8 4930.6 3907.2 3955.2 3988.2 3999.2 4026.0 1192.5 1241.1 1280.2 1297.7 1308.7 744.1 842.6 878.0 884.1 888.8 4859.9 4884.9 5700.3 5816.3 5822.7 914.8 931.8 940.8 947.4 976.0 3797.7 3832.4 3899.0 3899.7 3958.8 10206.2 10230.9 10603.4 10674.5 10832.9 10792.0 10796.5 10805.8 10813.6 10815.2 7680.1 8240.9 8340.5 8350.6 8353.6 5200.6 5300.4 5835.1 5869.7 6036.1 9644.0 9735.2 10011.0 10071.4 10211.3 7966.5 10081.9 10316.2 10327.6 10349.7 7310.5 7480.4 7556.4 7607.4 7613.7 8331.0 8498.2 8507.3 8510.2 8523.7 7335.1 7448.5 7476.3 7579.8 7588.9 6323.3 7309.3 7389.2 7425.5 7446.3 6952.1 6965.2 7052.6 7052.9 7062.8 4840.8 4903.6 4910.5 4976.4 5043.5 4790.9 4799.7 4836.8 4893.2 4930.3 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4585.08 Median:5199.19 stddev:1444.94 (27.8%) stddev-sans-first:1412.72 11:55:49 INFO - PROCESS | 1655 | Values: 299.0 301.5 308.3 311.2 312.6 5458.5 5468.2 5477.3 5493.8 5523.6 4136.4 5106.9 5317.4 5389.8 5484.6 3533.5 3972.5 4017.3 4076.0 4095.3 3817.8 4013.4 4147.8 4192.7 4194.9 3826.7 4042.1 4082.7 4093.4 4109.9 3362.8 3363.9 3576.6 4088.8 4093.7 3061.9 3486.5 3512.3 3513.4 3513.6 4347.9 5053.0 5221.4 5222.7 5229.7 4107.5 4297.4 4574.5 4617.4 4633.3 5009.9 5164.9 5463.9 5574.0 5675.3 3258.7 3673.6 3871.1 3886.8 3893.2 5067.6 5371.5 5700.3 5934.5 5990.7 3159.7 3257.8 3920.2 3925.2 3944.9 3556.6 3985.4 4007.6 4022.4 4042.3 4132.7 4469.6 4627.7 4703.8 4718.5 4594.7 5006.5 5678.1 5731.8 5777.7 1255.8 1282.8 1358.2 1364.5 1368.9 851.4 875.4 877.7 877.9 880.5 4570.0 5176.0 5400.3 5427.3 5469.7 5536.2 5799.9 5909.1 5928.1 5935.8 4774.9 5064.8 5397.9 5441.6 5470.6 2846.5 2928.9 3012.0 3075.9 3089.2 5286.7 5564.4 5761.6 6082.0 6107.4 5077.4 5586.4 6063.5 6111.8 6114.3 5656.8 6151.8 6169.5 6176.5 6183.0 4605.7 5177.0 5232.7 5902.3 5957.3 4751.4 5795.8 5807.1 5825.3 5842.4 5356.0 5454.8 5677.8 6076.7 6159.6 3573.6 3612.3 3885.2 3901.2 3932.9 5839.6 5873.1 5954.3 5975.7 5978.6 5571.4 5739.7 5758.3 5811.4 5813.4 5003.8 5753.9 5764.8 5786.8 5798.9 5003.8 5404.9 5591.7 5658.8 5681.0 5781.8 5794.2 5815.3 5829.7 5832.4 4711.9 4737.2 5625.5 5635.5 5637.1 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:23563.32 Median:4341.18 stddev:37389.05 (861.3%) stddev-sans-first:37454.25 11:55:49 INFO - PROCESS | 1655 | Values: 618.0 712.4 713.3 739.9 857.8 13121.5 14923.3 15093.3 15522.3 15645.6 14316.4 14323.4 14465.9 14476.8 14921.5 839.1 854.6 856.0 857.0 857.9 683.7 698.4 839.8 854.4 863.6 887.1 887.7 888.2 889.1 889.4 881.0 881.2 882.1 882.8 883.7 525.0 652.6 661.3 668.2 670.0 14454.3 14514.7 14544.0 14544.5 14546.9 13275.5 13609.4 13700.9 13717.2 13929.7 10419.7 12326.1 12461.5 12467.3 12581.2 4372.9 4375.2 4398.6 4407.1 4454.2 94853.5 114371.0 114556.0 115084.4 115214.8 9117.8 10503.5 10651.3 10765.6 10849.2 21303.8 21310.3 21316.4 21346.0 21381.7 7396.7 7672.3 7686.3 7814.6 7927.4 4277.2 4287.8 4295.8 4342.0 4490.7 2989.4 3429.6 3556.6 3575.1 3577.9 479.9 628.8 645.5 649.3 650.0 536.5 544.4 554.2 579.9 607.9 7885.5 8466.5 8904.9 8915.8 8948.0 541.1 584.2 592.3 610.8 623.6 4239.9 4340.3 4355.4 4406.4 4415.4 104328.0 149576.0 150625.0 157077.0 157451.0 90787.0 91324.5 130020.0 130942.0 137352.0 71603.0 71828.0 73390.0 85651.0 115861.0 9292.0 9443.4 9602.9 9701.8 9756.1 61969.7 63248.0 63984.7 65832.0 66286.7 77778.6 88723.0 93450.0 96842.0 97856.0 12755.9 12781.9 12805.6 12914.5 13173.5 14387.3 14445.9 14496.3 14753.2 14866.3 13324.1 13379.5 13474.9 13506.5 13509.7 11202.9 13250.3 13291.3 13487.0 13529.4 10754.5 12863.5 13006.3 13234.4 13553.4 9316.8 10663.7 10801.2 10998.2 11078.7 11008.5 11312.3 11405.8 11520.3 11524.3 11:55:49 INFO - PROCESS | 1655 | -------- Summary: end -------- 11:55:49 INFO - PROCESS | 1655 | 11:55:49 INFO - TEST-INFO | 1655: exit 0 11:55:49 INFO - TEST-OK | dromaeo_css | took 1116749ms 11:55:49 INFO - TEST-START | kraken 11:55:49 INFO - operating with platform_type : mac_ 11:55:49 INFO - Initialising browser for kraken test... 11:55:49 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpczMvXv/profile http://localhost:49273/getInfo.html) 11:55:51 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 11:55:51 INFO - PROCESS | 1661 | colorDepth:24 11:55:51 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 11:55:51 INFO - PROCESS | 1661 | __metrics 11:55:52 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:55:52 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:55:52 INFO - TEST-INFO | 1661: exit 0 11:55:52 INFO - Browser initialized. 11:55:52 INFO - Running cycle 1/1 for kraken test... 11:55:52 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpczMvXv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:56:04 INFO - PROCESS | 1662 | RSS: Main: 233992192 11:56:04 INFO - PROCESS | 1662 | 11:56:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:56:08 INFO - PROCESS | 1662 | RSS: Main: 293203968 11:56:08 INFO - PROCESS | 1662 | 11:56:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:56:11 INFO - PROCESS | 1662 | RSS: Main: 289382400 11:56:11 INFO - PROCESS | 1662 | 11:56:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:56:16 INFO - PROCESS | 1662 | RSS: Main: 383111168 11:56:16 INFO - PROCESS | 1662 | 11:56:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:56:18 INFO - PROCESS | 1662 | RSS: Main: 282140672 11:56:18 INFO - PROCESS | 1662 | 11:56:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:56:21 INFO - PROCESS | 1662 | RSS: Main: 284991488 11:56:21 INFO - PROCESS | 1662 | 11:56:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:56:24 INFO - PROCESS | 1662 | RSS: Main: 322244608 11:56:24 INFO - PROCESS | 1662 | 11:56:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:56:28 INFO - PROCESS | 1662 | RSS: Main: 325025792 11:56:28 INFO - PROCESS | 1662 | 11:56:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:56:31 INFO - PROCESS | 1662 | RSS: Main: 323670016 11:56:31 INFO - PROCESS | 1662 | 11:56:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:56:34 INFO - PROCESS | 1662 | RSS: Main: 298696704 11:56:34 INFO - PROCESS | 1662 | 11:56:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:56:36 INFO - PROCESS | 1662 | RSS: Main: 303321088 11:56:36 INFO - PROCESS | 1662 | 11:56:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:56:39 INFO - PROCESS | 1662 | RSS: Main: 301006848 11:56:39 INFO - PROCESS | 1662 | 11:56:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:56:43 INFO - PROCESS | 1662 | RSS: Main: 320020480 11:56:43 INFO - PROCESS | 1662 | 11:56:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:56:46 INFO - PROCESS | 1662 | RSS: Main: 315801600 11:56:46 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:56:48 INFO - PROCESS | 1662 | RSS: Main: 359641088 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | __start_tp_report 11:56:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 11:56:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 11:56:48 INFO - PROCESS | 1662 | |i|pagename|runs| 11:56:48 INFO - PROCESS | 1662 | |0;ai-astar;114;115;117;115;114;114;113;111;114;125 11:56:48 INFO - PROCESS | 1662 | |1;audio-beat-detection;175;144;120;188;136;160;156;104;157;108 11:56:48 INFO - PROCESS | 1662 | |2;audio-dft;184;184;183;183;198;258;260;239;180;183 11:56:48 INFO - PROCESS | 1662 | |3;audio-fft;78;74;76;155;58;57;57;67;137;57 11:56:48 INFO - PROCESS | 1662 | |4;audio-oscillator;82;82;129;71;71;91;121;68;69;116 11:56:48 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;89;133;115;89;86;91;87;89;86 11:56:48 INFO - PROCESS | 1662 | |6;imaging-darkroom;95;96;96;120;151;100;96;99;96;96 11:56:48 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;84;84;85;85;85;87;84;107;139 11:56:48 INFO - PROCESS | 1662 | |8;json-parse-financial;55;54;55;55;54;55;54;54;53;54 11:56:48 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;66;72;77;74;83;102;69;70;70 11:56:48 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;86;87;87;85;76;83;88;104;103;80 11:56:48 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;157;151;152;152;135;140;139;141;181;143 11:56:48 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;172;176;176;172;175;172;169;173;174;177 11:56:48 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;69;67;68;70;69;68;70;68;68 11:56:48 INFO - PROCESS | 1662 | __end_tp_report 11:56:48 INFO - PROCESS | 1662 | __start_cc_report 11:56:48 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2011 11:56:48 INFO - PROCESS | 1662 | __end_cc_report 11:56:48 INFO - PROCESS | 1662 | __startTimestamp1472842608608__endTimestamp 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | ------- Summary: start ------- 11:56:48 INFO - PROCESS | 1662 | Number of tests: 14 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:115.20 Median:114.50 stddev:3.77 (3.3%) stddev-sans-first:3.97 11:56:48 INFO - PROCESS | 1662 | Values: 114.0 115.0 117.0 115.0 114.0 114.0 113.0 111.0 114.0 125.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:156.50 stddev:27.88 (17.8%) stddev-sans-first:27.35 11:56:48 INFO - PROCESS | 1662 | Values: 175.0 144.0 120.0 188.0 136.0 160.0 156.0 104.0 157.0 108.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:205.20 Median:191.00 stddev:33.33 (17.4%) stddev-sans-first:34.46 11:56:48 INFO - PROCESS | 1662 | Values: 184.0 184.0 183.0 183.0 198.0 258.0 260.0 239.0 180.0 183.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:81.60 Median:62.50 stddev:35.19 (56.3%) stddev-sans-first:37.30 11:56:48 INFO - PROCESS | 1662 | Values: 78.0 74.0 76.0 155.0 58.0 57.0 57.0 67.0 137.0 57.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:71.00 stddev:23.41 (33.0%) stddev-sans-first:24.65 11:56:48 INFO - PROCESS | 1662 | Values: 82.0 82.0 129.0 71.0 71.0 91.0 121.0 68.0 69.0 116.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:89.00 stddev:15.74 (17.7%) stddev-sans-first:16.52 11:56:48 INFO - PROCESS | 1662 | Values: 89.0 89.0 133.0 115.0 89.0 86.0 91.0 87.0 89.0 86.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:96.00 stddev:17.96 (18.7%) stddev-sans-first:18.72 11:56:48 INFO - PROCESS | 1662 | Values: 95.0 96.0 96.0 120.0 151.0 100.0 96.0 99.0 96.0 96.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:85.00 stddev:17.73 (20.9%) stddev-sans-first:18.65 11:56:48 INFO - PROCESS | 1662 | Values: 86.0 84.0 84.0 85.0 85.0 85.0 87.0 84.0 107.0 139.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.67 (1.2%) stddev-sans-first:0.67 11:56:48 INFO - PROCESS | 1662 | Values: 55.0 54.0 55.0 55.0 54.0 55.0 54.0 54.0 53.0 54.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:74.80 Median:71.00 stddev:10.92 (15.4%) stddev-sans-first:10.99 11:56:48 INFO - PROCESS | 1662 | Values: 65.0 66.0 72.0 77.0 74.0 83.0 102.0 69.0 70.0 70.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:85.50 stddev:9.00 (10.5%) stddev-sans-first:9.52 11:56:48 INFO - PROCESS | 1662 | Values: 86.0 87.0 87.0 85.0 76.0 83.0 88.0 104.0 103.0 80.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:151.50 stddev:13.28 (8.8%) stddev-sans-first:13.77 11:56:48 INFO - PROCESS | 1662 | Values: 157.0 151.0 152.0 152.0 135.0 140.0 139.0 141.0 181.0 143.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.50 stddev:2.46 (1.4%) stddev-sans-first:2.54 11:56:48 INFO - PROCESS | 1662 | Values: 172.0 176.0 176.0 172.0 175.0 172.0 169.0 173.0 174.0 177.0 11:56:48 INFO - PROCESS | 1662 | 11:56:48 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:0.97 (1.4%) stddev-sans-first:1.01 11:56:48 INFO - PROCESS | 1662 | Values: 68.0 69.0 67.0 68.0 70.0 69.0 68.0 70.0 68.0 68.0 11:56:48 INFO - PROCESS | 1662 | -------- Summary: end -------- 11:56:48 INFO - PROCESS | 1662 | 11:56:49 INFO - TEST-INFO | 1662: exit 0 11:56:49 INFO - TEST-OK | kraken | took 59701ms 11:56:49 INFO - SUITE-END | took 1176s 11:56:49 INFO - Completed test suite (00:19:36) 11:56:49 INFO - javascript benchmark 11:56:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.102762254191, 3338.666133386661, 3360.848761805717, 3411.4200585900394, 3439.0886415100003, 5614.961232172051, 5653.4911857932775, 5740.540756739461, 5761.62549434287, 5766.423357664242, 5713.485786279234, 5741.511971482429, 5788.652680839153, 5794.71026448678, 5855.23881895353, 5099.592032637399, 5126.205438157073, 5183.041137389576, 5191.740412979355, 5297.47025297471, 5248.895022099556, 5311.176767601009, 5319.015982043335, 5327.46725327468, 5350.063738845686, 5118.309028281172, 5130.127878260667, 5149.84550463483, 5168.2764412982215, 5232.895342093175, 5152.201408781645, 5163.819266325698, 5173.715445650488, 5182.689038657692, 5198.636095473318, 5209.114450543417, 5212.061828870802, 5221.295125158131, 5221.530062294374, 5224.8693782655355, 5617.578681598897, 5631.2960879890015, 5651.745671444794, 5734.111212762028, 5783.392743761929, 4920.852374428737, 5166.509181627739, 6246.500279977591, 6262.96868515659, 6277.96861015692, 6258.06129080637, 6311.368863113697, 6317.589356691847, 6318.33657465964, 6347.714352854133, 5689.886202276014, 5729.169270455761, 5742.7702891883855, 5815.244018277639, 5848.1227815827115, 5303.708296043755, 6240.53196010292, 6265.404786545271, 6273.027680709497, 6285.120083188357, 5094.745262736922, 6184.5980011299125, 6200.441960223511, 6206.565540412128, 6227.782027629011, 6124.782202784781, 6198.845028874224, 6238.157848690369, 6309.116723658692, 6318.842028949313, 5615.185798059259, 6193.938060619426, 6265.843353916189, 6274.874502509924, 6281.403266689657, 6196.938030619726, 6285.560010799291, 6287.1198032275515, 6290.937090629127, 6323.810285691345, 4878.43897951733, 4895.143349913751, 4981.501849814989, 4999.725015124203, 5030.37120359955, 3723.646253605903, 4425.845095421645, 4431.180231657154, 4496.920739022611, 4551.931721024187, 5747.08046712524, 5749.281339832521, 5757.827265182052, 5767.336756272998, 5776.364599894008, 6106.005662907935, 6204.441600255998, 6308.337624549448, 6325.810225693236, 6352.904706429407, 5696.174054762037, 5729.0260655688635, 5738.8278351649515, 5792.884142317214, 5807.912881306784, 5425.9186112208345, 5449.373322067933, 5456.645318054314, 5459.372172200247, 5471.23402723619, 6243.063540468785, 6321.1782468278825, 6321.810345689637, 6343.492520598434, 6343.936560634427, 6236.438720515175, 6256.311805701278, 6301.684915754102, 6352.396522330371, 6357.936420635734, 6255.468285195628, 6294.464970477561, 6308.936910631019, 6316.052592111037, 6332.113504109337, 6096.512279017757, 6224.875502490016, 6244.750209991549, 6254.249490061222, 6262.874742505033, 6107.219158344477, 6140.596275277727, 6142.314611233224, 6254.906176407449, 6260.530460215593, 5837.0, 6067.120267561268, 6070.3929607038945, 6091.329953705001, 6124.663143527148, 5948.13752005948, 6041.879162416639, 6044.637321760532, 6067.757289708362, 6074.210352654127, 5911.733971971185, 5931.228940237742, 5937.990541608024, 5991.071383935497, 6049.485793707584, 6085.695715214133, 6145.201123853871, 6149.692515374303, 6158.784442544489, 6179.5056395489155, 5786.652800831964, 5919.674417906885, 5961.642301461926, 5967.46292833647, 5986.640801551921], "value": 5688.1897138538125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.9437267527897, 492.6379111353156, 498.4093848789183, 521.7678133230711, 524.2293828072732, 30122.0, 37842.62157378425, 39344.80327598361, 39619.40570891439, 41715.79142104293, 40566.59433405665, 40764.38853417201, 40826.38760418589, 40863.591364086424, 40880.0, 8694.21752042316, 9516.0, 9704.514774261293, 9705.466199359033, 9724.0, 8741.606627701769, 8880.200781929625, 8897.0, 8907.77730556738, 11656.9508744213, 11691.298522088659, 11951.10366722495, 12017.879821201763, 12035.277883326984, 12043.759124817498, 12048.915597596215, 12125.93937030318, 12131.575394861138, 12131.575394861182, 12137.0, 11556.76886462266, 11702.590409335633, 11703.882961170364, 11790.0, 11819.290842549475, 38524.614753852664, 38632.61367386318, 38720.22559548821, 38725.41911871324, 38835.805820971, 3232.595925509311, 3312.565877701282, 3400.4559270516597, 3523.841427135785, 3588.4258518637153, 28040.719592804016, 30296.292233982498, 30594.541081883788, 30662.386752265054, 30988.53517197244, 3189.6012998375204, 3239.400710868497, 3273.770836041478, 3372.3761104195555, 3387.237871478922, 448792.0, 455173.72413138056, 455207.0, 456390.0, 459693.0, 31726.0, 55285.170722439194, 55698.72150639221, 55742.72128639412, 55827.72086139624, 32580.837095814368, 34110.31779364399, 34230.82884585561, 34281.48577771335, 34309.828450858084, 14183.85816141846, 14341.856581434053, 14347.92826035884, 14360.281985900872, 14361.569152925429, 12073.45669444877, 12175.756484870253, 12195.0, 12197.878021219853, 12206.633800985985, 7117.110361204849, 7218.494705370574, 7508.737194198176, 7590.658420371096, 7607.771766847003, 3198.1684761962056, 3228.112269125962, 3239.9676003239665, 3263.0584802042745, 3279.6884295991845, 918.3479729392072, 925.7824411263348, 1020.336781092296, 1028.7788125553043, 1067.1889364083197, 24325.87837060839, 29305.706942930297, 29550.0, 29755.404891902042, 30219.244518887204, 1042.3433237060603, 1070.9143268538498, 1082.2478377527543, 1090.9236353455183, 1162.9476673549711, 14741.0, 15189.6202594936, 15421.614459638375, 15531.456399026205, 15579.806139741553, 591100.0, 596890.0, 597436.0, 597882.0, 598649.0, 29581.26046848846, 36906.44640330344, 36907.26185476276, 36917.446238306446, 36981.630183698355, 1310.2793463594985, 1353.2151352215692, 1371.8833899118686, 1378.7173629405947, 1379.2896658221025, 23724.485979444635, 24259.636105458787, 27337.04320348778, 28961.0, 29451.852740736158, 47327.52672473231, 47652.523474766196, 47733.76133119312, 47743.522564773906, 47849.76075119741, 550541.0, 552893.0, 555576.0, 557017.0, 558672.0, 22777.97499112511, 23248.302550923498, 25125.74874251234, 25611.615825762256, 25890.35274118162, 42656.0, 45205.321920170565, 45527.317090243014, 46040.53959460497, 46171.538284618066, 36197.914062578166, 37267.62732372642, 37531.62468375391, 37605.24789504249, 38652.420213697376, 36832.26335473329, 37537.2492550142, 38022.63664957817, 38820.02949926162, 39040.02399940024, 36879.631203688696, 37022.62977370192, 37767.43348849715, 37802.0, 37815.67645132407, 26959.595606066316, 28025.859870700515, 29014.41971160607, 32221.35557288888, 33665.831670841486, 20947.49628829809, 24397.718141572986, 24518.680092828243, 24665.876670616533, 24749.505009900062], "value": 18140.230692382862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.26960013968915, 371.4261466034977, 381.427858212681, 394.396573242938, 401.5984015984016, 19778.90110549447, 20896.8955155224, 21776.782232177673, 21949.231776887813, 22099.78451185188, 20272.594548109028, 20577.382678519632, 21010.36968890932, 21208.4697882553, 21229.893850530763, 5197.714125723083, 5732.742026608792, 5847.239858818348, 6032.27612686476, 6037.124616930542, 4185.267578173807, 4407.713498622596, 5947.078202878561, 6054.334023257438, 6348.650824204667, 6648.966755166218, 6725.865482690343, 6726.3609957054005, 6730.091437655928, 6745.089412929241, 6401.199850018747, 6652.434543063821, 6709.29552397001, 6715.160604924385, 6724.058631791553, 5830.3586605473365, 5843.152742852305, 5850.06398976166, 7153.463490238253, 7533.510321829063, 28863.0, 29434.705652943623, 29523.55714664282, 29645.258868528243, 30071.248218794706, 2211.137656314039, 2370.7155141382973, 2578.8323758955607, 2704.797140214472, 2992.2369795701957, 15421.845124283042, 15828.920855395765, 16489.587760305967, 16740.66518669632, 17455.65088698219, 1945.9027048647652, 1948.3278268997174, 1972.2111155537757, 2011.647961606713, 2024.7064175694443, 136273.0, 139969.3001534996, 142625.0, 143639.28180359135, 144600.55399446082, 21150.0, 21341.25305614296, 21395.67906481404, 21432.463098754717, 21556.353309400743, 35070.843624073954, 35087.64912350895, 35259.294814103574, 35261.0, 35336.0, 10340.37957722539, 10647.201459890539, 10786.568537258421, 10947.069499092508, 10959.83560246597, 8026.799330016679, 8259.0, 8844.955775221211, 8852.911470885338, 9005.774855628531, 9371.671991480265, 9709.611615535441, 9806.0, 9816.607335706634, 9828.557714902847, 2928.2679330167457, 3180.25264062945, 3559.914226160994, 3645.76302540334, 3785.37541305687, 838.7316058861228, 848.6647774129184, 854.8290341931638, 894.5750768384966, 912.1653686876434, 21196.682049769264, 22042.669359959615, 22130.668039979413, 22184.667229991563, 22206.333809985725, 919.9099067604909, 920.1120918313859, 920.5259291464901, 951.4453691837439, 1032.5353590883983, 10801.351918884891, 11072.833907491395, 11566.0, 11602.303861768321, 11657.825132623017, 597515.0, 599523.0310029922, 611556.0, 611903.0, 613502.0, 63566.046509302396, 77561.73410462479, 86047.0, 125025.0, 129125.70874291325, 869.7086476030474, 926.5228407370209, 975.8826144065006, 993.4089216915924, 999.9250056245811, 19154.52113697169, 19278.13248403797, 19502.707459388403, 19516.121774519896, 19538.511537211685, 43005.56994430016, 53977.0, 54533.0, 55842.72078639581, 56385.0, 69991.65004174947, 77561.06097347611, 79060.418791625, 79163.0, 80085.0, 7182.569045857265, 7202.675879585327, 7604.5437273763755, 7860.174681658572, 8360.414770965974, 10434.582616695247, 12953.805692914802, 13090.345482726016, 13355.732885342433, 13457.192568445924, 11344.546218151181, 11385.03227225695, 11476.885231147582, 11590.0, 11652.009579185864, 11048.060914822008, 11149.777004460027, 11297.938041637679, 11431.59989400367, 11562.88437115618, 11501.48243329069, 11521.539138434704, 11596.710082248012, 11666.591669291534, 11714.004309633776, 6652.567583107082, 7981.40139489529, 8136.7152149674475, 8236.588170591567, 8314.376421768271, 9932.659091022619, 10595.629152979609, 11131.777364452828, 11306.94346528295, 11597.82603260935], "value": 10751.764226689485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.8746356559695, 1509.0719207687273, 1715.5796829776707, 1893.867429279951, 1920.6638838203319, 7953.801154971115, 8231.711890083849, 8420.621072051757, 8947.955260223705, 8975.820483590333, 8416.915830841705, 8695.173958473935, 8706.738797836058, 8797.208251257385, 8850.601722922467, 2376.132711560281, 2410.7950824179966, 2413.6379543068592, 2414.1429792423614, 2414.4808866093786, 2116.407405926343, 2187.507810742578, 2367.3134790910703, 2376.607859703147, 2431.3435372449426, 2311.4683622766775, 2384.8688322142275, 2388.5461762265113, 2394.365493144318, 2394.5091256292503, 2382.1781485387514, 2400.7479214682467, 2402.243293362603, 2409.8433601815827, 2417.9758202417925, 2393.1743548475747, 2403.5433267679086, 2404.8797560121925, 2407.3740827385004, 2410.650455683916, 8834.779130521723, 8908.554572271361, 8914.375993680445, 8914.910850891538, 8922.553872306358, 5215.921761173586, 5396.865078373033, 5400.621956463049, 5403.864903377408, 5407.242985981966, 3408.9488657670154, 3545.982270088659, 3607.603163651996, 3614.1868079682126, 3631.94552081719, 1040.8178568750438, 1075.4461452352045, 1078.66561365976, 1080.3895798873612, 1093.3549205968516, 11541.365224912626, 11592.942035289854, 11613.070954323633, 11618.709532261677, 11643.126765492621, 4524.140413321458, 4644.419447569054, 4830.2513110467935, 4843.418789745253, 4844.588210002189, 9910.35582687123, 9912.00879912003, 9916.801663966824, 9923.900760992297, 9928.453935033498, 4619.422572178478, 5364.678119312854, 5369.275147855068, 5377.462253774669, 5378.273933019071, 4774.379330686988, 4874.707517548887, 4926.556609905054, 4926.778294976734, 4930.580900623416, 3907.199024200032, 3955.248502784462, 3988.222296652183, 3999.240334252919, 4025.9798701006307, 1192.4574318684977, 1241.050596293836, 1280.1742875845027, 1297.7289742949838, 1308.7055412532295, 744.0959234529964, 842.5576572299469, 877.9912200877909, 884.0982198157972, 888.7955770172896, 4859.882227087719, 4884.902301953941, 5700.287464066992, 5816.331121921034, 5822.737976791054, 914.7667344827026, 931.7950050988771, 940.7977284883783, 947.3605316411395, 975.9853602195973, 3797.696184305249, 3832.3868181090893, 3898.9805050974564, 3899.707521935866, 3958.7822669752863, 10206.183505319557, 10230.948845255725, 10603.416812075257, 10674.46627668629, 10832.945835270773, 10792.028717415471, 10796.514156862802, 10805.83791243132, 10813.62152324665, 10815.242932994772, 7680.078390593165, 8240.876386854203, 8340.457870238415, 8350.582470876554, 8353.624086916103, 5200.583953283653, 5300.3639563252655, 5835.124731290341, 5869.706514674163, 6036.094585811988, 9643.951780241054, 9735.1725103367, 10011.048950349705, 10071.446070465901, 10211.336263142872, 7966.522008679266, 10081.899181008095, 10316.17470602365, 10327.638532651321, 10349.74125646865, 7310.451716121206, 7480.3641690456925, 7556.357709594746, 7607.35337496319, 7613.6573854175585, 8330.958345208477, 8498.235158835734, 8507.276881464897, 8510.234078932677, 8523.701670441753, 7335.1197856257595, 7448.478606497493, 7476.327130558276, 7579.810504737426, 7588.924110759042, 6323.272823625158, 7309.305615966475, 7389.150247721581, 7425.486133248352, 7446.292602202782, 6952.130983627047, 6965.233824279427, 7052.647367631701, 7052.929470705432, 7062.788116356517, 4840.806367745251, 4903.583195428429, 4910.533499317559, 4976.4075495842435, 5043.4956504349275, 4790.904181916412, 4799.736014519095, 4836.782344794422, 4893.168161412497, 4930.309756634002], "value": 4576.7178224550125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.99551506727397, 301.4603859092225, 308.27401469539234, 311.2405730018754, 312.551488613839, 5458.4541545845395, 5468.234447177393, 5477.3427188737405, 5493.835184944453, 5523.613347065707, 4136.441580386654, 5106.948930510694, 5317.3619165700175, 5389.757460914258, 5484.643498172616, 3533.469979503069, 3972.5232972043395, 4017.27689015977, 4075.9999999999927, 4095.3242714952, 3817.7973938209375, 4013.3779264214095, 4147.751134931914, 4192.727472714264, 4194.853180138696, 3826.7129965252716, 4042.090529630839, 4082.6529744971713, 4093.4269202311552, 4109.897252568695, 3362.798232106057, 3363.9159021024425, 3576.5708115026105, 4088.8160032798446, 4093.7339072960312, 3061.9081427557207, 3486.477028445728, 3512.2965361799506, 3513.3851575974104, 3513.5608048993877, 4347.869553915973, 5053.0, 5221.39953905302, 5222.73886305687, 5229.686218826844, 4107.466029416157, 4297.35539669049, 4574.4968053514085, 4617.422822147231, 4633.305004249356, 5009.874753131165, 5164.948350516484, 5463.863403414906, 5574.0, 5675.31896172462, 3258.7230085442766, 3673.6142705016123, 3871.1031594518763, 3886.812813492937, 3893.201893611803, 5067.569256613192, 5371.462853714636, 5700.287464066992, 5934.495567876736, 5990.670513121733, 3159.6524382317925, 3257.8371081445835, 3920.2159568086495, 3925.175713100281, 3944.9013774655864, 3556.5732112146193, 3985.4221137934837, 4007.5591684914634, 4022.416749571296, 4042.333015052542, 4132.73137246078, 4469.642428605704, 4627.722336659811, 4703.811847526129, 4718.4809670936165, 4594.724316541018, 5006.499350064964, 5678.14827775837, 5731.799387021518, 5777.728899642072, 1255.7927941889668, 1282.83262231369, 1358.151155527795, 1364.4951367993906, 1368.9178649281075, 851.3699862102, 875.4134729731095, 877.7360600734921, 877.9473231606124, 880.4629176202511, 4569.954300456953, 5176.023786322804, 5400.297961265011, 5427.3215848019, 5469.671819690831, 5536.2249285100115, 5799.869025540063, 5909.113632955005, 5928.103007037706, 5935.792247271324, 4774.856754297377, 5064.797408103635, 5397.892042159214, 5441.59188060897, 5470.617056805985, 2846.5445528715304, 2928.912132636024, 3011.9548206776917, 3075.8923437679573, 3089.1504836170234, 5286.656367336111, 5564.360098588714, 5761.600655613208, 6081.969590152021, 6107.389261073857, 5077.416097148801, 5586.385497595261, 6063.514918806485, 6111.816645500642, 6114.266288045463, 5656.773729050792, 6151.785357271378, 6169.5064394848305, 6176.505879529627, 6182.969085154546, 4605.700629459075, 5176.9741151294, 5232.651103293971, 5902.317839415746, 5957.3446920837805, 4751.40607424072, 5795.82612521625, 5807.128930660435, 5825.300963884361, 5842.415758424124, 5356.0, 5454.772676147835, 5677.807660391364, 6076.66578338178, 6159.599626024294, 3573.5533058367705, 3612.27754449106, 3885.222955408986, 3901.2249426511435, 3932.9213415732097, 5839.562032847469, 5873.063468265867, 5954.345022047484, 5975.7012149391485, 5978.641281523122, 5571.3592936811165, 5739.704118352693, 5758.337791154071, 5811.360750317546, 5813.418658134322, 5003.799848006039, 5753.884922301614, 5764.769409223584, 5786.826395208151, 5798.913016304843, 5003.849884503471, 5404.919016196855, 5591.692456914909, 5658.830235092953, 5681.0400411857845, 5781.768729250783, 5794.246747922743, 5815.313559067823, 5829.6793676346515, 5832.445917637987, 4711.929321060118, 4737.194676904865, 5625.46558076982, 5635.520980716685, 5637.069883469182], "value": 4123.750145999105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.9721912513936, 712.3873468816817, 713.2795876165073, 739.8705226585346, 857.811281518066, 13121.47514099435, 14923.328450219738, 15093.320800563974, 15522.301496432645, 15645.608859778511, 14316.35576399062, 14323.427062917472, 14465.855341446582, 14476.782848257284, 14921.477748278812, 839.1356902390532, 854.6495936665968, 855.995720021399, 856.9700060497884, 857.8670306102533, 683.7470136049668, 698.3749544157976, 839.7858546070744, 854.3848429131046, 863.6329559937033, 887.0951629338103, 887.6804350433839, 888.2139306713573, 889.0664801957951, 889.3818795936827, 880.9955950220208, 881.154092071612, 882.1046637662713, 882.849915514364, 883.6862913665652, 524.9842504724865, 652.5660435810181, 661.2726001398423, 668.1981622053506, 669.993300066998, 14454.349554270084, 14514.70970580593, 14544.0, 14544.490942817056, 14546.92726536371, 13275.53535626258, 13609.387577558935, 13700.862991370059, 13717.245551494663, 13929.742435509037, 10419.687409377731, 12326.12882516585, 12461.501539938392, 12467.251964882045, 12581.245125292511, 4372.934405983913, 4375.234333991571, 4398.648108151372, 4407.140607581523, 4454.153710794939, 94853.5257323709, 114371.0, 114556.0, 115084.4245778774, 115214.84785152209, 9117.772055698528, 10503.474826258656, 10651.337165708574, 10765.569377224823, 10849.186311026704, 21303.78696213049, 21310.254141104986, 21316.36050918475, 21346.0, 21381.67927481089, 7396.667149978264, 7672.309492145622, 7686.346660533805, 7814.57019863913, 7927.4054445915635, 4277.1873344064525, 4287.828486860491, 4295.82816687329, 4342.044750154961, 4490.663200259994, 2989.37223183134, 3429.554157959499, 3556.5554305711785, 3575.070481674731, 3577.856885724542, 479.8643736620556, 628.7864421666137, 645.4803882874237, 649.2630863969429, 649.9892666903012, 536.4822945857188, 544.4419470043158, 554.1687468796805, 579.8721486708396, 607.902735562308, 7885.487443316166, 8466.500695979305, 8904.910950890539, 8915.777105572413, 8947.955260223787, 541.1017710600385, 584.1500616602833, 592.3217915486744, 610.845502001218, 623.5603899251017, 4239.936400953989, 4340.305551111807, 4355.3684715716045, 4406.361077643788, 4415.359772832921, 104328.0, 149576.0, 150625.0, 157077.0, 157451.0, 90787.0, 91324.5433772827, 130020.0, 130942.0, 137352.0, 71603.0, 71828.0, 73390.0, 85651.0, 115861.0, 9292.02433744447, 9443.386179898285, 9602.90397096048, 9701.805963880824, 9756.073247804336, 61969.69015154895, 63248.0, 63984.68007659932, 65832.0, 66286.66856665685, 77778.61110694411, 88723.0, 93450.0, 96842.0, 97856.0, 12755.87244127547, 12781.936090319861, 12805.615831525069, 12914.483420663068, 13173.538926137539, 14387.28063596795, 14445.92777036108, 14496.34766435534, 14753.188574628499, 14866.331015104563, 13324.067315287837, 13379.53171638988, 13474.86525134736, 13506.527271545448, 13509.662258443617, 11202.94398528002, 13250.271235082164, 13291.335433228493, 13487.0, 13529.391177397238, 10754.462276886281, 12863.549775758178, 13006.284654344103, 13234.404451799885, 13553.390097445441, 9316.813663726824, 10663.733406664895, 10801.189910756568, 10998.176959768442, 11078.667639970814, 11008.504617292401, 11312.321260724382, 11405.828912566152, 11520.308781473139, 11524.250923689933], "value": 7579.680072953434, "unit": "score"}], "name": "dromaeo_css", "value": 7358.223960622639, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 117.0, 115.0, 114.0, 114.0, 113.0, 111.0, 114.0, 125.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 144.0, 120.0, 188.0, 136.0, 160.0, 156.0, 104.0, 157.0, 108.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 183.0, 198.0, 258.0, 260.0, 239.0, 180.0, 183.0], "value": 205.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 76.0, 155.0, 58.0, 57.0, 57.0, 67.0, 137.0, 57.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 129.0, 71.0, 71.0, 91.0, 121.0, 68.0, 69.0, 116.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 133.0, 115.0, 89.0, 86.0, 91.0, 87.0, 89.0, 86.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 120.0, 151.0, 100.0, 96.0, 99.0, 96.0, 96.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 85.0, 85.0, 87.0, 84.0, 107.0, 139.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 53.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 72.0, 77.0, 74.0, 83.0, 102.0, 69.0, 70.0, 70.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 85.0, 76.0, 83.0, 88.0, 104.0, 103.0, 80.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 151.0, 152.0, 152.0, 135.0, 140.0, 139.0, 141.0, 181.0, 143.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 176.0, 172.0, 175.0, 172.0, 169.0, 173.0, 174.0, 177.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 70.0, 69.0, 68.0, 70.0, 68.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1537.4999999999998, "alertThreshold": 2.0}]} 11:56:49 INFO - Return code: 0 11:56:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:56:49 INFO - # TBPL SUCCESS # 11:56:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:56:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:56:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:56:49 INFO - 2016-09-02 11:56:49.925 screenresolution[1666:10257] starting screenresolution argv=screenresolution get 11:56:49 INFO - 2016-09-02 11:56:49.928 screenresolution[1666:10257] Display 0: 1600x1200x32@60 11:56:49 INFO - 2016-09-02 11:56:49.933 screenresolution[1667:10260] starting screenresolution argv=screenresolution list 11:56:49 INFO - Available Modes on Display 0 11:56:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:56:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:56:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:56:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:56:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:56:50 INFO - Intel Iris: 11:56:50 INFO - Chipset Model: Intel Iris 11:56:50 INFO - Type: GPU 11:56:50 INFO - Bus: Built-In 11:56:50 INFO - VRAM (Dynamic, Max): 1536 MB 11:56:50 INFO - Vendor: Intel (0x8086) 11:56:50 INFO - Device ID: 0x0a2e 11:56:50 INFO - Revision ID: 0x0009 11:56:50 INFO - Displays: 11:56:50 INFO - Display: 11:56:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:56:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:56:50 INFO - Display Serial Number: 1600x1200 60 11:56:50 INFO - Main Display: Yes 11:56:50 INFO - Mirror: Off 11:56:50 INFO - Online: Yes 11:56:50 INFO - Rotation: Supported 11:56:50 INFO - Return code: 0 11:56:50 INFO - Running post-action listener: _resource_record_post_action 11:56:50 INFO - [mozharness: 2016-09-02 18:56:50.169980Z] Finished run-tests step (success) 11:56:50 INFO - Running post-run listener: _resource_record_post_run 11:56:50 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31525888; Write bytes: 707390976; Read time: 191; Write time: 1996 11:56:50 INFO - TinderboxPrint: CPU usage
53.5% 11:56:50 INFO - TinderboxPrint: I/O read bytes / time
31,525,888 / 191 11:56:50 INFO - TinderboxPrint: I/O write bytes / time
707,390,976 / 1,996 11:56:50 INFO - TinderboxPrint: CPU idle
2,254.2 (46.5%) 11:56:50 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 11:56:50 INFO - TinderboxPrint: CPU user
1,625.2 (33.5%) 11:56:50 INFO - TinderboxPrint: Swap in / out
434,040,832 / 0 11:56:50 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 384201216; Write bytes: 378193920; Read time: 29105; Write time: 771 11:56:50 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30600192; Write bytes: 298710528; Read time: 180; Write time: 1151 11:56:50 INFO - Running post-run listener: _upload_blobber_files 11:56:50 INFO - Blob upload gear active. 11:56:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:56:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:56:51 INFO - (blobuploader) - INFO - Open directory for files ... 11:56:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:56:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:56:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:51 INFO - (blobuploader) - INFO - Done attempting. 11:56:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:56:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:56:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:52 INFO - (blobuploader) - INFO - Done attempting. 11:56:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:56:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:56:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:56:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:56:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:56:55 INFO - (blobuploader) - INFO - Done attempting. 11:56:55 INFO - (blobuploader) - INFO - Iteration through files over. 11:56:55 INFO - Return code: 0 11:56:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:56:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:56:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c35eaf7e642390cdbca853eeddc409355b84068aea74996cb2254a0422490e9bc019277fdaaa3e38cf467b921c386bd03e25a99a42d644f17febfd8e14e9ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4feb47e6b33125b934fa7772858925dc7fd3de42eb7477a84c4c90d2f17b76f01f57d7a8654611e27b96f1f190c07d9fbcc0bd862987e4344812c2420c051ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ccb0d5f49a755e67112dc03160dd47e0af92209b4b7f7755274bac1019fc0e348c0a1bda15eece18e1422a064056a0547b964d0a6e1c4c60e16cc0b0fda253ec"} 11:56:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:56:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:56:55 INFO - Contents: 11:56:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c35eaf7e642390cdbca853eeddc409355b84068aea74996cb2254a0422490e9bc019277fdaaa3e38cf467b921c386bd03e25a99a42d644f17febfd8e14e9ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4feb47e6b33125b934fa7772858925dc7fd3de42eb7477a84c4c90d2f17b76f01f57d7a8654611e27b96f1f190c07d9fbcc0bd862987e4344812c2420c051ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ccb0d5f49a755e67112dc03160dd47e0af92209b4b7f7755274bac1019fc0e348c0a1bda15eece18e1422a064056a0547b964d0a6e1c4c60e16cc0b0fda253ec"} 11:56:55 INFO - Running post-run listener: copy_logs_to_upload_dir 11:56:55 INFO - Copying logs to upload dir... 11:56:55 INFO - mkdir: /builds/slave/test/build/upload/logs 11:56:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.965643 ========= master_lag: 1.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-02 11:56:56.786921) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 11:56:56.790755) ========= 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.b2qZz5Mspx/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.NFmzziZDib/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c35eaf7e642390cdbca853eeddc409355b84068aea74996cb2254a0422490e9bc019277fdaaa3e38cf467b921c386bd03e25a99a42d644f17febfd8e14e9ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4feb47e6b33125b934fa7772858925dc7fd3de42eb7477a84c4c90d2f17b76f01f57d7a8654611e27b96f1f190c07d9fbcc0bd862987e4344812c2420c051ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ccb0d5f49a755e67112dc03160dd47e0af92209b4b7f7755274bac1019fc0e348c0a1bda15eece18e1422a064056a0547b964d0a6e1c4c60e16cc0b0fda253ec"} build_url:https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010972 build_url: 'https://queue.taskcluster.net/v1/task/NdPPzuFwTjGUJ2-dHqOT9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5c35eaf7e642390cdbca853eeddc409355b84068aea74996cb2254a0422490e9bc019277fdaaa3e38cf467b921c386bd03e25a99a42d644f17febfd8e14e9ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b4feb47e6b33125b934fa7772858925dc7fd3de42eb7477a84c4c90d2f17b76f01f57d7a8654611e27b96f1f190c07d9fbcc0bd862987e4344812c2420c051ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ccb0d5f49a755e67112dc03160dd47e0af92209b4b7f7755274bac1019fc0e348c0a1bda15eece18e1422a064056a0547b964d0a6e1c4c60e16cc0b0fda253ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 11:56:56.823740) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 11:56:56.824057) ========= 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.b2qZz5Mspx/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.NFmzziZDib/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.005155 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 11:56:56.872033) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 11:56:56.872329) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 11:56:56.872644) ========= ========= Total master_lag: 1.91 =========