builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0048 starttime: 1470658107.94 results: success (0) buildid: 20160808012421 builduid: f64ec4b55952477e87b269089c3452c5 revision: f8fc21bb232095b7b4c995d259558212cb90a44b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:27.937768) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:27.938208) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:27.938485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:27.963730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:27.964096) ========= 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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-08 05:08:27-- 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% 141M=0s 2016-08-08 05:08:28 (141 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145180 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:28.131709) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:28.132022) ========= 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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.030996 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:28.184203) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:08:28.184551) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f8fc21bb232095b7b4c995d259558212cb90a44b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f8fc21bb232095b7b4c995d259558212cb90a44b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pWpmDS2W8V/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.uLfY8tFtDC/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-08 05:08:28,263 truncating revision to first 12 chars 2016-08-08 05:08:28,263 Setting DEBUG logging. 2016-08-08 05:08:28,263 attempt 1/10 2016-08-08 05:08:28,263 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f8fc21bb2320?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 05:08:29,378 unpacking tar archive at: fx-team-f8fc21bb2320/testing/mozharness/ program finished with exit code 0 elapsedTime=1.396156 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:08:29.602786) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:29.603094) ========= script_repo_revision: f8fc21bb232095b7b4c995d259558212cb90a44b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:29.603470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:29.603755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:08:29.622867) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-08-08 05:08:29.623139) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pWpmDS2W8V/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.uLfY8tFtDC/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:08:29 INFO - MultiFileLogger online at 20160808 05:08:29 in /builds/slave/test 05:08:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:08:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:29 INFO - {'append_to_log': False, 05:08:29 INFO - 'base_work_dir': '/builds/slave/test', 05:08:29 INFO - 'blob_upload_branch': 'Fx-Team', 05:08:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:29 INFO - 'branch': 'Fx-Team', 05:08:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:29 INFO - 'config_files': ('talos/mac_config.py',), 05:08:29 INFO - 'default_actions': ('clobber', 05:08:29 INFO - 'read-buildbot-config', 05:08:29 INFO - 'download-and-extract', 05:08:29 INFO - 'populate-webroot', 05:08:29 INFO - 'create-virtualenv', 05:08:29 INFO - 'install', 05:08:29 INFO - 'run-tests'), 05:08:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:29 INFO - 'download_minidump_stackwalk': True, 05:08:29 INFO - 'download_symbols': 'ondemand', 05:08:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:29 INFO - 'installer_path': 'installer.exe', 05:08:29 INFO - 'log_level': 'info', 05:08:29 INFO - 'log_name': 'talos', 05:08:29 INFO - 'log_to_console': True, 05:08:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:08:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:08:29 INFO - 'opt_config_files': (), 05:08:29 INFO - 'pip_index': False, 05:08:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:29 INFO - 'cmd': ('bash', 05:08:29 INFO - '-c', 05:08:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:29 INFO - 'enabled': True, 05:08:29 INFO - 'halt_on_failure': False, 05:08:29 INFO - 'name': 'check_screen_resolution'},), 05:08:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:29 INFO - 'cmd': ('bash', 05:08:29 INFO - '-c', 05:08:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:29 INFO - 'enabled': True, 05:08:29 INFO - 'halt_on_failure': False, 05:08:29 INFO - 'name': 'check_screen_resolution'},), 05:08:29 INFO - 'run_cmd_checks_enabled': True, 05:08:29 INFO - 'sps_profile': False, 05:08:29 INFO - 'sps_profile_interval': 0, 05:08:29 INFO - 'suite': 'dromaeojs', 05:08:29 INFO - 'system_bits': '32', 05:08:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:29 INFO - 'title': 't-yosemite-r7-0048', 05:08:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:29 INFO - 'use_talos_json': True, 05:08:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:29 INFO - 'work_dir': 'build'} 05:08:29 INFO - [mozharness: 2016-08-08 12:08:29.836014Z] Running clobber step. 05:08:29 INFO - Running pre-action listener: _resource_record_pre_action 05:08:29 INFO - Running main action method: clobber 05:08:29 INFO - rmtree: /builds/slave/test/build 05:08:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:32 INFO - Running post-action listener: _resource_record_post_action 05:08:32 INFO - [mozharness: 2016-08-08 12:08:32.390685Z] Finished clobber step (success) 05:08:32 INFO - [mozharness: 2016-08-08 12:08:32.390812Z] Running read-buildbot-config step. 05:08:32 INFO - Running pre-action listener: _resource_record_pre_action 05:08:32 INFO - Running main action method: read_buildbot_config 05:08:32 INFO - Using buildbot properties: 05:08:32 INFO - { 05:08:32 INFO - "project": "", 05:08:32 INFO - "product": "firefox", 05:08:32 INFO - "script_repo_revision": "production", 05:08:32 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:08:32 INFO - "repository": "", 05:08:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:08:32 INFO - "buildid": "20160808012421", 05:08:32 INFO - "pgo_build": "False", 05:08:32 INFO - "basedir": "/builds/slave/test", 05:08:32 INFO - "buildnumber": 82, 05:08:32 INFO - "slavename": "t-yosemite-r7-0048", 05:08:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:08:32 INFO - "platform": "yosemite_r7", 05:08:32 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:08:32 INFO - "branch": "fx-team", 05:08:32 INFO - "repo_path": "integration/fx-team", 05:08:32 INFO - "revision": "f8fc21bb232095b7b4c995d259558212cb90a44b", 05:08:32 INFO - "stage_platform": "macosx64", 05:08:32 INFO - "builduid": "f64ec4b55952477e87b269089c3452c5", 05:08:32 INFO - "slavebuilddir": "test" 05:08:32 INFO - } 05:08:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:08:32 INFO - Running post-action listener: _resource_record_post_action 05:08:32 INFO - [mozharness: 2016-08-08 12:08:32.394202Z] Finished read-buildbot-config step (success) 05:08:32 INFO - [mozharness: 2016-08-08 12:08:32.394323Z] Running download-and-extract step. 05:08:32 INFO - Running pre-action listener: _resource_record_pre_action 05:08:32 INFO - Running main action method: download_and_extract 05:08:32 INFO - mkdir: /builds/slave/test/build/tests 05:08:32 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')]} 05:08:32 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:08:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:08:33 INFO - Downloaded 1199 bytes. 05:08:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:08:33 INFO - Using the following test package requirements: 05:08:33 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:08:33 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:08:33 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:08:33 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'jsshell-mac.zip'], 05:08:33 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:08:33 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:08:33 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:08:33 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:08:33 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:08:33 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:08:33 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:08:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:33 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:08:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:08:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:08:35 INFO - Downloaded 16320444 bytes. 05:08:35 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:08:41 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 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:41 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:08:41 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:08:42 INFO - Downloaded 16320444 bytes. 05:08:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:49 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:08:49 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:08:51 INFO - Downloaded 14413723 bytes. 05:08:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:51 INFO - trying https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:08:51 INFO - Downloading https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:08:54 INFO - Downloaded 111901101 bytes. 05:08:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:08:54 INFO - mkdir: /builds/slave/test/properties 05:08:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:54 INFO - Contents: 05:08:54 INFO - build_url:https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:08:55 INFO - Running post-action listener: _resource_record_post_action 05:08:55 INFO - Running post-action listener: set_extra_try_arguments 05:08:55 INFO - [mozharness: 2016-08-08 12:08:55.127980Z] Finished download-and-extract step (success) 05:08:55 INFO - [mozharness: 2016-08-08 12:08:55.128131Z] Running populate-webroot step. 05:08:55 INFO - Running pre-action listener: _resource_record_pre_action 05:08:55 INFO - Running main action method: populate_webroot 05:08:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:55 INFO - '--tppagecycles', 05:08:55 INFO - '7'], 05:08:55 INFO - 'tests': ['tsvgm']}}, 05:08:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:08:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:08:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['damp', 'tps']}, 05:08:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'tests': ['damp', 'tps']}, 05:08:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['dromaeo_dom']}, 05:08:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['basic_compositor_video']}, 05:08:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:08:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['a11yr', 05:08:55 INFO - 'ts_paint', 05:08:55 INFO - 'tpaint', 05:08:55 INFO - 'sessionrestore', 05:08:55 INFO - 'sessionrestore_no_auto_restore', 05:08:55 INFO - 'tabpaint']}, 05:08:55 INFO - 'other-e10s': {'tests': ['a11yr', 05:08:55 INFO - 'ts_paint', 05:08:55 INFO - 'tpaint', 05:08:55 INFO - 'sessionrestore', 05:08:55 INFO - 'sessionrestore_no_auto_restore', 05:08:55 INFO - 'tabpaint']}, 05:08:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:08:55 INFO - 'ts_paint', 05:08:55 INFO - 'tpaint', 05:08:55 INFO - 'sessionrestore', 05:08:55 INFO - 'sessionrestore_no_auto_restore', 05:08:55 INFO - 'tabpaint']}, 05:08:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:08:55 INFO - 'ts_paint', 05:08:55 INFO - 'tpaint', 05:08:55 INFO - 'sessionrestore', 05:08:55 INFO - 'sessionrestore_no_auto_restore', 05:08:55 INFO - 'tabpaint']}, 05:08:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['a11yr', 05:08:55 INFO - 'ts_paint', 05:08:55 INFO - 'tpaint', 05:08:55 INFO - 'sessionrestore', 05:08:55 INFO - 'sessionrestore_no_auto_restore', 05:08:55 INFO - 'tabpaint']}, 05:08:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['a11yr', 05:08:55 INFO - 'ts_paint', 05:08:55 INFO - 'tpaint', 05:08:55 INFO - 'sessionrestore', 05:08:55 INFO - 'sessionrestore_no_auto_restore', 05:08:55 INFO - 'tabpaint']}, 05:08:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['tsvgx', 05:08:55 INFO - 'tsvgr_opacity', 05:08:55 INFO - 'tart', 05:08:55 INFO - 'tscrollx', 05:08:55 INFO - 'cart']}, 05:08:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:08:55 INFO - 'tsvgr_opacity', 05:08:55 INFO - 'tart', 05:08:55 INFO - 'tscrollx', 05:08:55 INFO - 'cart']}, 05:08:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'talos_options': ['--disable-e10s'], 05:08:55 INFO - 'tests': ['tp5o']}, 05:08:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'tests': ['tp5o']}, 05:08:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'talos_options': ['--disable-e10s', 05:08:55 INFO - '--xperf_path', 05:08:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:55 INFO - 'tests': ['tp5n']}, 05:08:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:55 INFO - 'talos_options': ['--xperf_path', 05:08:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:55 INFO - 'tests': ['tp5n']}}, 05:08:55 INFO - 'talos.zip': {'path': '', 05:08:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:55 INFO - Running post-action listener: _resource_record_post_action 05:08:55 INFO - [mozharness: 2016-08-08 12:08:55.147348Z] Finished populate-webroot step (success) 05:08:55 INFO - [mozharness: 2016-08-08 12:08:55.147463Z] Running create-virtualenv step. 05:08:55 INFO - Running pre-action listener: _resource_record_pre_action 05:08:55 INFO - Running main action method: create_virtualenv 05:08:55 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:55 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 05:08:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:55 INFO - Using real prefix '/tools/python27' 05:08:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:56 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:58 INFO - Installing pip.................done. 05:08:58 INFO - Return code: 0 05:08:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:58 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:08:58 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 05:08:58 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 05:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:58 INFO - 'HOME': '/Users/cltbld', 05:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:58 INFO - 'LOGNAME': 'cltbld', 05:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:58 INFO - 'MOZ_NO_REMOTE': '1', 05:08:58 INFO - 'NO_EM_RESTART': '1', 05:08:58 INFO - 'PAGER': '/bin/cat', 05:08:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:58 INFO - 'PWD': '/builds/slave/test', 05:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:58 INFO - 'SHELL': '/bin/bash', 05:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:08:58 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:58 INFO - 'USER': 'cltbld', 05:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:58 INFO - 'XPC_FLAGS': '0x0', 05:08:58 INFO - 'XPC_SERVICE_NAME': '0', 05:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:59 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 05:08:59 INFO - Cleaning up... 05:08:59 INFO - Return code: 0 05:08:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:59 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:08:59 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 05:08:59 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 05:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:59 INFO - 'HOME': '/Users/cltbld', 05:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:59 INFO - 'LOGNAME': 'cltbld', 05:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:59 INFO - 'MOZ_NO_REMOTE': '1', 05:08:59 INFO - 'NO_EM_RESTART': '1', 05:08:59 INFO - 'PAGER': '/bin/cat', 05:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:59 INFO - 'PWD': '/builds/slave/test', 05:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:59 INFO - 'SHELL': '/bin/bash', 05:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:08:59 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:59 INFO - 'USER': 'cltbld', 05:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:59 INFO - 'XPC_FLAGS': '0x0', 05:08:59 INFO - 'XPC_SERVICE_NAME': '0', 05:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:59 INFO - Downloading/unpacking psutil>=3.1.1 05:08:59 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 05:08:59 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 05:08:59 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 05:08:59 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 05:08:59 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 05:08:59 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 05:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:02 INFO - Installing collected packages: psutil 05:09:02 INFO - Running setup.py install for psutil 05:09:02 INFO - building 'psutil._psutil_osx' extension 05:09:02 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 05:09:03 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 05:09:03 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 05:09:03 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 05:09:03 INFO - building 'psutil._psutil_posix' extension 05:09:03 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 05:09:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:09:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:09:03 INFO - ^ 05:09:03 INFO - 1 warning generated. 05:09:03 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 05:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:03 INFO - Successfully installed psutil 05:09:03 INFO - Cleaning up... 05:09:03 INFO - Return code: 0 05:09:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:09:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:03 INFO - 'HOME': '/Users/cltbld', 05:09:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:03 INFO - 'LOGNAME': 'cltbld', 05:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:03 INFO - 'MOZ_NO_REMOTE': '1', 05:09:03 INFO - 'NO_EM_RESTART': '1', 05:09:03 INFO - 'PAGER': '/bin/cat', 05:09:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:03 INFO - 'PWD': '/builds/slave/test', 05:09:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:03 INFO - 'SHELL': '/bin/bash', 05:09:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:03 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:03 INFO - 'USER': 'cltbld', 05:09:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:03 INFO - 'XPC_FLAGS': '0x0', 05:09:03 INFO - 'XPC_SERVICE_NAME': '0', 05:09:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:09:03 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 05:09:03 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 05:09:03 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 05:09:03 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 05:09:03 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 05:09:03 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 05:09:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:09:06 INFO - Installing collected packages: mozsystemmonitor 05:09:06 INFO - Running setup.py install for mozsystemmonitor 05:09:06 INFO - Successfully installed mozsystemmonitor 05:09:06 INFO - Cleaning up... 05:09:06 INFO - Return code: 0 05:09:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:09:06 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')]} 05:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:06 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')]} 05:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:06 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09:06 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 05:09:06 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 05:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:06 INFO - 'HOME': '/Users/cltbld', 05:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:06 INFO - 'LOGNAME': 'cltbld', 05:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:06 INFO - 'MOZ_NO_REMOTE': '1', 05:09:06 INFO - 'NO_EM_RESTART': '1', 05:09:06 INFO - 'PAGER': '/bin/cat', 05:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:06 INFO - 'PWD': '/builds/slave/test', 05:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:06 INFO - 'SHELL': '/bin/bash', 05:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:06 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:06 INFO - 'USER': 'cltbld', 05:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:06 INFO - 'XPC_FLAGS': '0x0', 05:09:06 INFO - 'XPC_SERVICE_NAME': '0', 05:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:06 INFO - Downloading/unpacking jsonschema==2.5.1 05:09:06 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 05:09:06 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 05:09:06 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 05:09:06 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 05:09:06 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 05:09:06 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 05:09:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:09 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:09 INFO - warning: no files found matching '*.txt' 05:09:09 INFO - no previously-included directories found matching 'build' 05:09:09 INFO - no previously-included directories found matching 'dist' 05:09:09 INFO - no previously-included directories found matching '.git*' 05:09:09 INFO - Installing collected packages: jsonschema, functools32 05:09:09 INFO - Running setup.py install for functools32 05:09:10 INFO - warning: no files found matching '*.txt' 05:09:10 INFO - no previously-included directories found matching 'build' 05:09:10 INFO - no previously-included directories found matching 'dist' 05:09:10 INFO - no previously-included directories found matching '.git*' 05:09:10 INFO - Successfully installed jsonschema functools32 05:09:10 INFO - Cleaning up... 05:09:10 INFO - Return code: 0 05:09:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:09: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 functools32==3.2.3-2 05:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:10 INFO - 'HOME': '/Users/cltbld', 05:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:10 INFO - 'LOGNAME': 'cltbld', 05:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:10 INFO - 'MOZ_NO_REMOTE': '1', 05:09:10 INFO - 'NO_EM_RESTART': '1', 05:09:10 INFO - 'PAGER': '/bin/cat', 05:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:10 INFO - 'PWD': '/builds/slave/test', 05:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:10 INFO - 'SHELL': '/bin/bash', 05:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:10 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:10 INFO - 'USER': 'cltbld', 05:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:10 INFO - 'XPC_FLAGS': '0x0', 05:09:10 INFO - 'XPC_SERVICE_NAME': '0', 05:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:09:10 INFO - Cleaning up... 05:09:10 INFO - Return code: 0 05:09:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:09: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 blobuploader==1.2.4 05:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:10 INFO - 'HOME': '/Users/cltbld', 05:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:10 INFO - 'LOGNAME': 'cltbld', 05:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:10 INFO - 'MOZ_NO_REMOTE': '1', 05:09:10 INFO - 'NO_EM_RESTART': '1', 05:09:10 INFO - 'PAGER': '/bin/cat', 05:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:10 INFO - 'PWD': '/builds/slave/test', 05:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:10 INFO - 'SHELL': '/bin/bash', 05:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:10 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:10 INFO - 'USER': 'cltbld', 05:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:10 INFO - 'XPC_FLAGS': '0x0', 05:09:10 INFO - 'XPC_SERVICE_NAME': '0', 05:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:10 INFO - Downloading/unpacking blobuploader==1.2.4 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:14 INFO - Downloading docopt-0.6.1.tar.gz 05:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:14 INFO - Running setup.py install for blobuploader 05:09:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:14 INFO - Running setup.py install for requests 05:09:15 INFO - Running setup.py install for docopt 05:09:15 INFO - Successfully installed blobuploader requests docopt 05:09:15 INFO - Cleaning up... 05:09:15 INFO - Return code: 0 05:09:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:15 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09:15 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 05:09:15 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 05:09:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:15 INFO - 'HOME': '/Users/cltbld', 05:09:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:15 INFO - 'LOGNAME': 'cltbld', 05:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:15 INFO - 'MOZ_NO_REMOTE': '1', 05:09:15 INFO - 'NO_EM_RESTART': '1', 05:09:15 INFO - 'PAGER': '/bin/cat', 05:09:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:15 INFO - 'PWD': '/builds/slave/test', 05:09:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:15 INFO - 'SHELL': '/bin/bash', 05:09:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:15 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:15 INFO - 'USER': 'cltbld', 05:09:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:15 INFO - 'XPC_FLAGS': '0x0', 05:09:15 INFO - 'XPC_SERVICE_NAME': '0', 05:09:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:15 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-5NgQqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:15 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-mfrtif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:15 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-NcM2wT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:15 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-qwJVZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:15 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-_2wLuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-d0uZNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-zy43v_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-FHXYg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-EX2wKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-IcvrMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-4jPZ79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-c5FQUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-NbHvsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-kPcnl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:16 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-fHaGw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:17 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-mDkOeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:17 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-fRKFHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:17 INFO - Running setup.py install for manifestparser 05:09:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:17 INFO - Running setup.py install for mozcrash 05:09:17 INFO - Running setup.py install for mozdebug 05:09:17 INFO - Running setup.py install for mozdevice 05:09:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:17 INFO - Running setup.py install for mozfile 05:09:18 INFO - Running setup.py install for mozhttpd 05:09:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Running setup.py install for mozinfo 05:09:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Running setup.py install for mozInstall 05:09:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Running setup.py install for mozleak 05:09:18 INFO - Running setup.py install for mozlog 05:09:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Running setup.py install for moznetwork 05:09:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:18 INFO - Running setup.py install for mozprocess 05:09:19 INFO - Running setup.py install for mozprofile 05:09:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:19 INFO - Running setup.py install for mozrunner 05:09:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:19 INFO - Running setup.py install for mozscreenshot 05:09:19 INFO - Running setup.py install for moztest 05:09:19 INFO - Running setup.py install for mozversion 05:09:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:19 INFO - Cleaning up... 05:09:19 INFO - Return code: 0 05:09:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:19 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09:19 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 05:09:19 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 05:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:19 INFO - 'HOME': '/Users/cltbld', 05:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:19 INFO - 'LOGNAME': 'cltbld', 05:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:19 INFO - 'MOZ_NO_REMOTE': '1', 05:09:19 INFO - 'NO_EM_RESTART': '1', 05:09:19 INFO - 'PAGER': '/bin/cat', 05:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:19 INFO - 'PWD': '/builds/slave/test', 05:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:19 INFO - 'SHELL': '/bin/bash', 05:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:19 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:19 INFO - 'USER': 'cltbld', 05:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:19 INFO - 'XPC_FLAGS': '0x0', 05:09:19 INFO - 'XPC_SERVICE_NAME': '0', 05:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-bNrAII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-36IiEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Oa36m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-A2iZfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-KTk93O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-DaJtcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-_MHNkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:20 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)) 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:20 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-NZ3rKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-iUfp4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-f3HIGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-TaqGwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-JpIcbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Yf5oCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-twKB22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-pGY2L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-XRjRYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:21 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)) 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:21 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-bCfQ5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:21 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)) 05:09:21 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)) 05:09:21 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)) 05:09:21 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)) 05:09:21 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)) 05:09:21 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)) 05:09:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:24 INFO - Downloading blessings-1.6.tar.gz 05:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:24 INFO - Installing collected packages: blessings 05:09:24 INFO - Running setup.py install for blessings 05:09:25 INFO - Successfully installed blessings 05:09:25 INFO - Cleaning up... 05:09:25 INFO - Return code: 0 05:09:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:25 INFO - Reading from file tmpfile_stdout 05:09:25 INFO - Current package versions: 05:09:25 INFO - blessings == 1.6 05:09:25 INFO - blobuploader == 1.2.4 05:09:25 INFO - docopt == 0.6.1 05:09:25 INFO - functools32 == 3.2.3-2 05:09:25 INFO - jsonschema == 2.5.1 05:09:25 INFO - manifestparser == 1.1 05:09:25 INFO - mozInstall == 1.12 05:09:25 INFO - mozcrash == 0.17 05:09:25 INFO - mozdebug == 0.1 05:09:25 INFO - mozdevice == 0.48 05:09:25 INFO - mozfile == 1.2 05:09:25 INFO - mozhttpd == 0.7 05:09:25 INFO - mozinfo == 0.9 05:09:25 INFO - mozleak == 0.1 05:09:25 INFO - mozlog == 3.3 05:09:25 INFO - moznetwork == 0.27 05:09:25 INFO - mozprocess == 0.23 05:09:25 INFO - mozprofile == 0.28 05:09:25 INFO - mozrunner == 6.12 05:09:25 INFO - mozscreenshot == 0.1 05:09:25 INFO - mozsystemmonitor == 0.3 05:09:25 INFO - moztest == 0.7 05:09:25 INFO - mozversion == 1.4 05:09:25 INFO - psutil == 3.1.1 05:09:25 INFO - requests == 1.2.3 05:09:25 INFO - wsgiref == 0.1.2 05:09:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:25 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')]} 05:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:25 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')]} 05:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:25 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09:25 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 05:09:25 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 05:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:25 INFO - 'HOME': '/Users/cltbld', 05:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:25 INFO - 'LOGNAME': 'cltbld', 05:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:25 INFO - 'MOZ_NO_REMOTE': '1', 05:09:25 INFO - 'NO_EM_RESTART': '1', 05:09:25 INFO - 'PAGER': '/bin/cat', 05:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:25 INFO - 'PWD': '/builds/slave/test', 05:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:25 INFO - 'SHELL': '/bin/bash', 05:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:25 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:25 INFO - 'USER': 'cltbld', 05:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:25 INFO - 'XPC_FLAGS': '0x0', 05:09:25 INFO - 'XPC_SERVICE_NAME': '0', 05:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 INFO - Cleaning up... 05:09:26 INFO - Return code: 0 05:09:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:26 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')]} 05:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:26 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')]} 05:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:26 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 0x10eeb5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef4a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd25b4a43f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, '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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 05:09:26 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 05:09:26 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 05:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:26 INFO - 'HOME': '/Users/cltbld', 05:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:26 INFO - 'LOGNAME': 'cltbld', 05:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:26 INFO - 'MOZ_NO_REMOTE': '1', 05:09:26 INFO - 'NO_EM_RESTART': '1', 05:09:26 INFO - 'PAGER': '/bin/cat', 05:09:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:26 INFO - 'PWD': '/builds/slave/test', 05:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:26 INFO - 'SHELL': '/bin/bash', 05:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:09:26 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:26 INFO - 'USER': 'cltbld', 05:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:26 INFO - 'XPC_FLAGS': '0x0', 05:09:26 INFO - 'XPC_SERVICE_NAME': '0', 05:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:26 INFO - Cleaning up... 05:09:26 INFO - Return code: 0 05:09:26 INFO - Running post-action listener: _resource_record_post_action 05:09:26 INFO - Running post-action listener: _start_resource_monitoring 05:09:26 INFO - Starting resource monitoring. 05:09:26 INFO - [mozharness: 2016-08-08 12:09:26.371961Z] Finished create-virtualenv step (success) 05:09:26 INFO - [mozharness: 2016-08-08 12:09:26.372866Z] Running install step. 05:09:26 INFO - Running pre-action listener: _resource_record_pre_action 05:09:26 INFO - Running main action method: install 05:09:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:26 INFO - Reading from file tmpfile_stdout 05:09:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:26 INFO - Reading from file tmpfile_stdout 05:09:26 INFO - Output received: 05:09:26 INFO - Usage: mozinstall [options] installer 05:09:26 INFO - Options: 05:09:26 INFO - -h, --help show this help message and exit 05:09:26 INFO - -d DEST, --destination=DEST 05:09:26 INFO - Directory to install application into. [default: 05:09:26 INFO - "/builds/slave/test"] 05:09:26 INFO - --app=APP Application being installed. [default: firefox] 05:09:26 INFO - mkdir: /builds/slave/test/build/application 05:09:26 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'] 05:09:26 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 05:10:01 INFO - Reading from file tmpfile_stdout 05:10:01 INFO - Output received: 05:10:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:10:01 INFO - Running post-action listener: _resource_record_post_action 05:10:01 INFO - [mozharness: 2016-08-08 12:10:01.758804Z] Finished install step (success) 05:10:01 INFO - [mozharness: 2016-08-08 12:10:01.758949Z] Running run-tests step. 05:10:01 INFO - Running pre-action listener: _resource_record_pre_action 05:10:01 INFO - Running main action method: run_tests 05:10:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:01 INFO - 2016-08-08 05:10:01.773 screenresolution[1639:7620] starting screenresolution argv=screenresolution get 05:10:01 INFO - 2016-08-08 05:10:01.776 screenresolution[1639:7620] Display 0: 1600x1200x32@60 05:10:01 INFO - 2016-08-08 05:10:01.782 screenresolution[1640:7623] starting screenresolution argv=screenresolution list 05:10:01 INFO - Available Modes on Display 0 05:10:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:02 INFO - Intel Iris: 05:10:02 INFO - Chipset Model: Intel Iris 05:10:02 INFO - Type: GPU 05:10:02 INFO - Bus: Built-In 05:10:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:02 INFO - Vendor: Intel (0x8086) 05:10:02 INFO - Device ID: 0x0a2e 05:10:02 INFO - Revision ID: 0x0009 05:10:02 INFO - Displays: 05:10:02 INFO - Display: 05:10:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:02 INFO - Display Serial Number: 1600x1200 60 05:10:02 INFO - Main Display: Yes 05:10:02 INFO - Mirror: Off 05:10:02 INFO - Online: Yes 05:10:02 INFO - Rotation: Supported 05:10:02 INFO - Return code: 0 05:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:02 INFO - Python 2.7.3 05:10:02 INFO - Return code: 0 05:10:02 INFO - grabbing minidump binary from tooltool 05:10: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')]} 05:10:02 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 0x10ee19d30>, '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 0x10eb7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee11b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:02 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 05:10:02 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 05:10:02 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 05:10:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:02 INFO - Return code: 0 05:10:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:10:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:10:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--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 05:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0048 --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 05:10:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pWpmDS2W8V/Render', 05:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:02 INFO - 'HOME': '/Users/cltbld', 05:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:02 INFO - 'LOGNAME': 'cltbld', 05:10:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:02 INFO - 'MOZ_NO_REMOTE': '1', 05:10:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:02 INFO - 'NO_EM_RESTART': '1', 05:10:02 INFO - 'PAGER': '/bin/cat', 05:10:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:02 INFO - 'PWD': '/builds/slave/test', 05:10:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:02 INFO - 'SHELL': '/bin/bash', 05:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uLfY8tFtDC/Listeners', 05:10:02 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 05:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:02 INFO - 'USER': 'cltbld', 05:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:02 INFO - 'XPC_FLAGS': '0x0', 05:10:02 INFO - 'XPC_SERVICE_NAME': '0', 05:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--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 05:10:02 INFO - mozversion application_buildid: 20160808012421 05:10:02 INFO - mozversion application_changeset: f8fc21bb232095b7b4c995d259558212cb90a44b 05:10:02 INFO - mozversion application_display_name: Nightly 05:10:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:02 INFO - mozversion application_name: Firefox 05:10:02 INFO - mozversion application_remotingname: firefox 05:10:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:10:02 INFO - mozversion application_vendor: Mozilla 05:10:02 INFO - mozversion application_version: 51.0a1 05:10:02 INFO - mozversion platform_buildid: 20160808012421 05:10:02 INFO - mozversion platform_changeset: f8fc21bb232095b7b4c995d259558212cb90a44b 05:10:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:10:02 INFO - mozversion platform_version: 51.0a1 05:10:02 INFO - using testdate: 1470658202 05:10:02 INFO - actual date: 1470658202 05:10:02 INFO - starting webserver on 'localhost:49274' 05:10:02 INFO - SUITE-START | Running 2 tests 05:10:02 INFO - TEST-START | dromaeo_css 05:10:02 INFO - operating with platform_type : mac_ 05:10:02 INFO - Initialising browser for dromaeo_css test... 05:10:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpET43GS/profile http://localhost:49274/getInfo.html) 05:10:04 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:10:04 INFO - PROCESS | 1650 | colorDepth:24 05:10:04 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:10:04 INFO - PROCESS | 1650 | __metrics 05:10:04 INFO - PROCESS | 1650 | 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" 05:10:04 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:10:05 INFO - TEST-INFO | 1650: exit 0 05:10:05 INFO - Browser initialized. 05:10:05 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:05 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpET43GS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:17 INFO - PROCESS | 1652 | RSS: Main: 243335168 05:10:17 INFO - PROCESS | 1652 | 05:11:09 INFO - PROCESS | 1652 | 1470658269644 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] 05:13:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 05:13:23 INFO - PROCESS | 1652 | RSS: Main: 381505536 05:13:23 INFO - PROCESS | 1652 | 05:16:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 05:16:27 INFO - PROCESS | 1652 | RSS: Main: 394276864 05:16:27 INFO - PROCESS | 1652 | 05:19:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 05:19:30 INFO - PROCESS | 1652 | RSS: Main: 358637568 05:19:30 INFO - PROCESS | 1652 | 05:22:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 05:22:33 INFO - PROCESS | 1652 | RSS: Main: 353939456 05:22:33 INFO - PROCESS | 1652 | 05:25:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 05:25:37 INFO - PROCESS | 1652 | RSS: Main: 347865088 05:25:37 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 05:28:40 INFO - PROCESS | 1652 | RSS: Main: 483934208 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | __start_tp_report 05:28:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:28:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:28:40 INFO - PROCESS | 1652 | |i|pagename|runs| 05:28:40 INFO - PROCESS | 1652 | |0;dojo.html;3318.734501239901;3349.4138525757985;3349.949750753739;3359.0426728382417;3386.8983930482077;5579.609427340078;5655.717214139292;5733.9713301433485;5736.082226843706;5747.051736463489;5698.373178950312;5730.19777231188;5735.311762588485;5743.913841292374;5749.396313387097;5184.844454666357;5214.947850521484;5241.344831896013;5243.397009343936;5310.309659744228;5297.152455607104;5304.893902121955;5307.177387504943;5328.333958255218;5330.3603567571945;5186.7925282988635;5216.713080780555;5217.713025783581;5218.765155568009;5323.095073837438;5291.523762861361;5291.6031297652635;5310.7610157543;5310.7610157543;5380.192971054334;5129.487051294878;5137.075326441239;5165.793368265265;5215;5223.477652234784;5602.215689803431;5630.915536266918;5659.51894089003;5686.857828554278;5690.2033715279895;6230.813075607739;6236.844078898019;6254.437100660917;6257.092721555395;6261.624302541862;6147.5553244987395;6224.190855188796;6225.564210505267;6231.2522497300615;6315.936840631581;5791.681457519877;5798.130280457881;5804.7097645118;5829.4170582942215;5849.5027922626205;6308.873822523524;6309.116723658692;6315.905261421082;6318.304986451487;6323.810285691437;5124.231365295235;6037.003894357382;6098.32918378978;6158.7228574714245;6180.289266734383;6200.410960958701;6283;6300.905486417708;6301.936980630227;6303.936960630427;6193.969030154911;6197.597156184834;6202.56582039262;6234.127222188807;6247.812565623039;6105.542084343692;6133.08984587446;6271.560990730696;6273.62358258506;6277.96861015692;4938.08645400602;4976.129177393942;4976.477469865685;4984.22744474607;4990.30135780991;4434.068845542408;4455.4876189238;4495.842645507457;4535.727856328631;4579.22153233951;5658.632188907708;5716.542676585863;5724.77100915967;5750.137479378127;5775.740091695884;5085.923711144335;6219.875602487925;6287.339829317947;6290.308066112724;6296.590721603081;5576.0241957657245;5633.267675202197;5646.745896434671;5690.174924635905;5692.117721753135;5392.245085688007;5428.294321738149;5442.537384322377;5447.618666693294;5452.318460192476;6239.7504099837315;6273.65494897785;6306.716197771111;6309.3690630935625;6315.873682526232;6279.277883043508;6293.339199383999;6302.369763023661;6309.7160627770945;6317.431431171217;6256.217972753406;6274.937250627618;6293.150424692608;6307.211598550181;6308.148399965946;6119.746288422617;6163.506919446344;6179.598326108789;6235.3452887446165;6254.249490061222;6213.5339849512375;6233.781817636361;6247.937520624736;6260.092286618506;6278.7488500461295;6134.38656134383;6134.539909506716;6159.353267906805;6159.938400615936;6166.290876549254;5974.970125149346;6018.909716354346;6084.178635884099;6109.586862300737;6117.541184411276;5847.707614619337;6052.818415447544;6053;6071.453569178795;6074.787382441594;6135.250308654353;6144.846378840565;6167.814965551041;6169.043798211284;6173.536984726254;5824.854378640568;5877.676727780014;5929.6145750526075;5967.134765458897;5972.850678733066 05:28:40 INFO - PROCESS | 1652 | |1;ext.html;453.6461559983213;472.7470803120331;479.101684341859;480.03992015968066;517.8446466060184;30879.228019299502;32588.859389921327;40632.59367406329;41534;41657.16685666285;42648.147037059316;42945.35581966273;43024.13951720972;43037;43121.13757724844;9537.95231023844;9678.612855485771;9687.515624218797;9710.368826026286;9729.854052189223;9646.180074693659;9750.414975101481;9764.12122908938;9765.365251258645;9773.755656108584;9748.707538773813;9800;9804.970478099804;9809.558569864337;9827.70516884491;9691.90308096917;9776.169025632758;9780.266480014028;9798.804023919552;9833.90166098337;9611.279154063403;9677.419354838732;9680.806383872283;9684.08001239881;9699.224062075016;40429.989250268685;40791;40831.591684083076;40836.774896753144;40865.1826963462;3392.6776956189124;3410.3179364127272;3416.282580658065;3454.2573346730574;3501.7548771586;31191.908283210207;31618.841905790323;31831;32316;32942;2991.7008299170125;3145.1822526143137;3150.7479401647815;3180.220845892748;3223.09753269085;478914;480001;480894;481088;481394.33725482086;61097.69451152716;61594.69202653958;61710.69144654248;61803.60080195543;61840;35004.299914001575;35389.292214155575;35573.644263557035;65766.67116664482;66234.66882665556;16268.75596866048;16699.08155051484;16709.415170468976;16719.16404179786;16789.16054197285;12817.615471535868;12900.741985160432;12918.677033074247;12919.677008074685;12921.418536165707;15977;18621.348252811356;19162.808371916104;19175.61648767017;19298.32455864066;6481.773137940149;7646.541207527566;7794;7814.21857814214;7900.644470998819;860.7202659135806;930.2697382554662;1032.5353590884133;1052.9420881851572;1071.689210129054;28979.275518112216;30759.077227683207;30807.537886931714;31225.063248102593;31254.843725781226;997.7627741600363;1023.0127926550914;1107.7618312062946;1116.7766446710527;1181.911356648255;15517.612059698598;15792;15820.92089539545;15920.840791592167;16323.428679996381;182665;183267.08366458083;183701;183887.0805645963;184289;58506.414935850094;59099.704501477216;59112;59169.11246331308;59266.70366648139;991.3655260633201;1238.0900038471536;1313.080843409598;1336.8930485561132;1346.3806648941597;30703;31897;31967.200819979687;32445.67554324521;32597.511037333985;50409.49590504048;51046.74476627593;51058;51173.7441312806;51293.7435312821;506779;510568;511579;511774;512388;27064.32339191536;27154.1853744388;28095.578566321114;28105.57841632418;28955.42089158163;48368.27447588219;48477;49103;49123.75438122787;49306.76733081702;39592.60407395889;39724;40005.79997099996;41060.79469602752;41247;39753.80123099366;40037.39943900786;40155.799221003705;40200.597994020856;41379.58620413757;38344.42483362807;38773.41839872348;39148.2170356597;39298.60701393064;39439.60560394359;33862;34527.4820877682;34979.30041399107;35251.294974100885;35413.46879796857;23429.945652446022;25317.74682253154;25470;25824.8708756455;26324.078657246904 05:28:40 INFO - PROCESS | 1652 | |2;jquery.html;312.9186411533001;354.9591796943352;370.6071564142008;378.2850412719959;406.1025134452859;15252.932294739372;17110.743338849912;20366.796332036713;20483.180672773073;20602.48493787656;19886.801131988675;20465.38603841887;20700.068496917636;20876.164953401883;21231.6815247771;5809.9709501452435;5834.912476312858;5908.88182236355;6057.424544668272;6085.969570152143;5847.590668653196;5997.520198384119;6007.15899774032;6007.549433792461;6014.699265036753;4125.381192821071;6263.373662633746;6563.1796025496815;6585.209774827027;6670.93329066708;6377.362263773585;6580.5393622446445;6642.966785166043;6642.9667851660915;6644.402003819655;5763.250777398911;5769.653820770767;5778.162166485836;5783.5084017858535;5790.13148027795;25868.611970820453;26041.69791510436;26075;26109.608355874676;26390.340241493923;2319.8724070176136;2327.8486898351553;2386.3676125826496;2433.415980164748;3009.6388433387915;15333.846661533353;15719.921400392925;15864.286107125206;16014.35942562296;16164.110973896431;1820.7268909663524;1860.8232217939274;1863.8788478748972;1892.3187652445247;1918.56832212751;117183;122411;123573.38213308966;123818;124033.37983310116;10692.305000174963;10709.089727373104;10776;10875.619353322754;11162.888371116347;14051.85948140526;14052.156870587798;14058.086224395382;14060.648483787987;14065.507707230197;8589.184027517376;8720.389572729975;8736.257418119403;8822.51476168804;8885.155910188521;8824.8676269856;8845.955770221237;8853.51305678181;9012.27901767857;9018.684346047858;5481.506664400223;6707.228668703064;6712.42944349726;6716.899246511306;6734.764283250062;1861.6276744651125;1874.390822982536;1874.4282993687057;1874.8593855460895;1894.488488108203;926.8470702334174;949.6296444386695;955.1737746848926;967.7870868408938;1029.2743615750878;21743.565128697337;22838.31485055451;22887.885560572424;22917.65623515615;23032.884835575715;963.9776763064373;975.9707208783748;1014.8629934958686;1077.9191560632985;1094.3051162511704;11127.610533631285;11281;11836.23064500822;11887.88112118885;11893;592421;595690;597534;614340;614351;85470.14529854746;114037;121320.78679213271;121466;126896;954.4798085043668;983.1692220074169;998.6804222648683;1001.3441196016621;1007.496251874063;18962.620747585246;19091.809081909003;19150.521236969187;19173.945433001318;19190.13644385978;51372.715682108246;51510.74244628753;52279.73860130675;54016.72991635017;58946.70526647339;77384.61307693426;83686.58156709178;85185;85499;87478.56260718656;7820.765377038698;7961.203879611992;8099.676012959416;8178.591070446335;8278.77571199556;10222.335548189641;12395.504179832706;12590.244585324543;12981;13039.478420863059;10734.14126869833;11092.722681933015;11171.944140279247;11363.886361136614;11365.48855301497;8376.246137847624;10726.249162558546;10997.450127493434;11053.889461105286;11300.886991130312;10904.85499022623;11115.83326250123;11294.94352528232;11469.713257168638;11563.19057665989;7844.725434609762;7873.606319684108;8138;8318.168183181875;8328.208820162075;9298.581563829508;10913.56345746193;10959.890401096205;11230.21388502797;11318.245671920866 05:28:40 INFO - PROCESS | 1652 | |3;mootools.html;1329.282187618686;1461.9488317908872;1504.0374160537258;1675.1624187906048;1877.859160562958;7043.683034263458;7726.884096738547;8248.83502329953;8726.65093396265;8850.645974161042;8685.17490838371;8795.956020219906;8803.867941980876;8808.29533637309;8814.33892458065;2296.644020176867;2322.349742072214;2326.534693061386;2326.8603883766946;2327.55776402483;2303.815694744425;2335.159342636658;2348.812095032402;2352.8235382346306;2353.976460235401;2300.8734519601417;2319.3621754017613;2325.546518428907;2340.625499920013;2350.341904266808;2276.3284830974903;2307.077169132344;2316.907323707058;2319.686842276288;2321.5472982768365;2294.977050229493;2295.1507942061544;2295.5638428698494;2300.5398920216026;2303.6199027160496;8759.956200219021;8840.778980525474;8852.247558957497;8853.380263381567;8854.955725221333;5110.131277682803;5155.845324640267;5268.367795864521;5274.604404669665;5279.894402111936;3581.426971684518;3637.399829028234;3638.7089032877307;3640.3447379471686;3642.21692336149;1084.5553323137476;1094.885037071112;1117.9217454778168;1118.7426891814928;1121.5681962444437;11580.594679186188;11650.825237621528;11684.298942063504;11710.70723231926;11733.002694770954;4374.62815660666;4615.123126605934;4776.617870570346;4847.515248475124;4894.19245824435;9166.49584272858;9227.90772092284;9264.768380790534;9276.489793061312;9291.535423228812;5207.661502002357;5214.869628259324;5217.8173763918085;5222.556082732935;5223.7910483580235;3786.422126904298;4324.78376081201;4859.465458799529;4877.6585639004925;4880.633952453581;3767.453719210699;3792.943105853414;3797.1266608680157;3840.231953609289;3902.551206611276;985.5269470654126;992.7640088090374;1148.1618418554501;1158.1082566423806;1159.0843233845337;828.7182357998189;872.5637181409295;882.5013867165031;926.9525436257121;939.4128669581512;5582.8325150245555;5652.3217213934595;5984.670843103584;6024.638521688625;6035.366286539938;434.97825108744433;441.39088058479734;451.97740112994217;454.1258078199453;454.21420941770924;2907.738303552648;3819.0087440421335;4061.9593804061583;4079.8368065277054;4208.894777630584;10662.89337106619;10873.238873278795;10981.286216395909;10981.780364392667;11076.556937722562;10915.454227288605;10938.179636527293;10940.671779846618;10951.233413660968;10958;7775.922240777633;8052.315553178046;8271.751847444586;8306.169383061766;8361.790955226168;5910.4089591042275;5962.344142144274;6055.878882422415;6071.423214794588;6135.631862088289;8503.659853605786;10092.444915529717;10161.54273057729;10293.639722609672;10316.329438586468;9358.766030849283;9660.37207581505;9977.10206081456;10277.331973421704;10284.897151028394;7398.741044063432;7573.848523029398;7648.336287162122;7654.578998155155;7666.310032096915;8295.294899933331;8379.07830138672;8475.533845638549;8483;8495.872561911452;7502.699892004259;7576.924230757842;7626.084869815658;7701.92298077012;7733.110692270461;7531.548107113591;7542.396608271215;7554.395648348229;7558.811029724301;7582.810429739301;7009.1589009319205;7022.157341119098;7111.253318401494;7124.857502850017;7125.608091554807;4962.156433406401;5027.472115427974;5066.392032956069;5070.087384270867;5184.740762961912;5019.84940451787;5145.048166089286;5157.0975079361715;5247.816326428557;5273.815416460406 05:28:40 INFO - PROCESS | 1652 | |4;prototype.html;255.82731656132106;294.50229112799354;305.18515563445595;311.4891577812388;314.024952522418;4379.496357918841;5167.5607573356265;5185.533302002819;5186.429492755794;5193.402758682743;4129.7315674481215;4852.4662287148385;4920.53254940781;5118.923216151751;5149.639525233235;3168.572242747234;3369.7978121312676;3933.8819835404984;4000.799960002003;4002.4596679448337;3960.4455376247206;3997.620226078518;4002.6597739192057;4003.959960400388;4020.4572382728397;3073.924126555699;3469.7744646598017;3614.3855544557355;3726.720495962814;3922.8430862765454;3583.4087375583126;3590.2460483298537;3992.86024989124;3998.8600398985895;4005.9554738398733;2727.2636388175083;3461.0828130545424;3466.722662187019;3468.3930312195516;3479.7912125272564;4925.38432695913;5124.436312005676;5127.077126117298;5136.614753893436;5136.974315128437;4538.478075021382;4539.773011349419;4541.704789188725;4560.768592480012;4562.270036794131;4089.85685501009;4656.8602941911795;5190.948090519084;5307.708076055816;5438.3473983121885;3631.727620428452;3670.6696397324367;3807.2676931995875;3812.5996770338993;3818.7517811342173;5089.312942752718;5397.27136836526;5423.701696406696;5694.77220911163;5713.800016999385;3307.536944827726;3519.859205631797;3819.4652748615217;3868.2650296443585;3902.6682731967535;3949.2101579684177;3976.681865450757;3991.002249437641;3995.5615978247806;4012.9598704012587;4384.6711496637245;4555.72665640056;4591.632669386441;4606.470255920545;4610.354550362952;5447.863803404946;5448.945510544923;5467.480589344085;5474.39781624021;5527.198556209327;1121.3404161840522;1145.4902568357168;1312.6915174933883;1340.7854743241035;1342.804903635765;720;837.3343192162246;863.5811631358786;886.8847049883474;908.2098574240453;4194.559571244975;4613.308003799457;4978.626603004716;5042.924356134661;5064.797408103708;5130.846074617767;5467.840203119825;5502.5597952163735;5550.611457197957;5566.443355664482;4488.955110448919;4561.452625684873;4789.05473134027;5070.974645126751;5074.45229205096;2365.834391592572;2880.927976800555;2914.737673609385;2917.6287145041797;2921.590977263185;5227.294315267466;5747.770089196385;5755.971220143872;5760.36635970043;5767.68277744728;5275.314209152785;5619.409961953936;5666.858328541737;5693.686847223443;5696.23100881384;4945.661678367088;5038.269450929594;5666.231955749463;5735.254416925856;5793.828443520659;4602.677812553223;4825;5266.498413253674;5563.830106258643;5567.972160139336;5204.609654276021;5415.323084614423;5453.345598528202;5472.425395333592;5489.505944465017;4243.066525364415;5381.757820898148;5620.615919582732;5726.198332233407;5763.682997435181;3451.964410676737;3581.641835816502;3649.9817500912327;3899.2396482685604;3928.4107383892647;4607.907841843212;4744.5492678195515;4953.777080031479;5467.152591348347;5485.396606373216;4689.859304220879;5343.305370301836;5400.675959442446;5440;5450.2369668247275;4514.435695538058;5193.506616871391;5354.491323324278;5395.325584301962;5431.121505602037;4138.627523522777;5249.191682450024;5249.448807875118;5259.921101183409;5272.2882410874045;4716.056788642217;4843.830465933816;5099.4757594990715;5101.6173786966865;5325.254464374913;4682.648801339981;5083.0430284340055;5096.694198348111;5146.845594632167;5303.5757139429525 05:28:40 INFO - PROCESS | 1652 | |5;yui.html;578.2829291678319;748.6032402826504;777.1179711027982;783.4085265624452;786.4062632712303;14590.78113828292;15195;15199.240037998083;15203.771943420843;15240.390384384626;13422.798658020112;13447.193168409878;13460.326983650828;13505.729885402287;13514.72970540591;687.4122625155495;706.3713295167305;708.040604980252;709.1667290933133;849.2144766091357;705.2277755857328;707.4517249131912;840.8864803251574;863.3611127765471;864.7535452396069;918.8070505193917;922.6908985489869;923.7644400677817;925.1858364639108;927.9721608351726;914.2640174659397;918.1874041473277;918.3387960668375;919.3840127114782;919.6735159018552;537.7956376576876;686.3411125319699;687.6286805125227;687.7214728035103;687.9690413931385;13884;14348.856511434857;14361;14374.20941848198;14423.567292981228;14261.429542818274;14817.629559260997;14878.70242595153;14901.329440175217;14908.254587270701;11595;11919.404029798563;12072.758544829143;12081.15431919766;12378.81431778524;3813.5423749150004;3832.137769001952;3933.5083114610675;3961.4057891316247;3998.1204135090234;113208.43395782968;113313.43343283313;113681;115731.26403103877;116840.41579792046;11197.16021298406;11202.60790872332;11300.208985370946;11461.598844040585;11534.904184102497;22518.549629007328;22625.773742262365;22673;22685;22706.318810435714;8000.599970001476;8140.226678465655;8142.511449312941;8147.551884646401;8210.753677389688;4479.641628669699;4530.411046563926;4536.841210557614;4580.496145424001;4745.810167593327;3470.722342212617;3629.292288003814;3630.909227269339;3636.2182130841998;3638.581563120222;620.203039094761;627.7238015273357;630.9905351419733;633.4425705379329;636.6498425865756;624.5222404860285;633.4995353670548;646.4860435953428;653.8463461086641;687.3504538211342;8676.175763302603;8712.259457946018;8880.09442477256;9062.546872656341;9398.389104708172;606.3057798820327;618.523736722721;619.3775255867914;678.9422899053537;682.1507223506766;4454.354118652777;4603.677175960617;4631.698939568917;4688.109259240733;4721.622270218374;87480.56259718661;98921;118800;149572;162282.56576151366;146408.26795865953;149299;151314.24342878215;153235.70146447813;155914;68961;70382.64808675923;70712;95764;98483.50758246164;8708.647170839145;9551.617935282511;9803.264755143535;9833.311668183445;9897.406155630686;61746;62828.68585657225;63196.73606527936;63760.04359934697;65968;80771.3845723094;86839.1316086831;88102;96515.51742241243;97517;11536.36549989725;12802.743945121232;13010.869891301345;13166.302587193455;13394.330283485591;14784.778228326799;14789.482368117064;14899.702005959603;15031.248437578297;15042.247887605794;13879.653008674864;13886.861131388558;13953.720925581634;14308.451456141005;14318.498852540111;13372.866271337161;13427.395767190304;13442.059055865995;13445.932770336085;13493.595192144252;13178.341082945622;13413.396397161956;13440.93279533596;13783.241921694404;13839.30803459843;10776.353418794897;11142.220044596801;11228.943855280671;11517.82723259135;11956.282623042298;11276.195808670573;11446.969772720495;11451.541938322374;11566.595169169039;11652.766944661229 05:28:40 INFO - PROCESS | 1652 | __end_tp_report 05:28:40 INFO - PROCESS | 1652 | __start_cc_report 05:28:40 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1289 05:28:40 INFO - PROCESS | 1652 | __end_cc_report 05:28:40 INFO - PROCESS | 1652 | __startTimestamp1470659320751__endTimestamp 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:28:40 INFO - PROCESS | 1652 | Number of tests: 6 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5753.67 Median:5972.85 stddev:633.91 (10.6%) stddev-sans-first:606.39 05:28:40 INFO - PROCESS | 1652 | Values: 3318.7 3349.4 3349.9 3359.0 3386.9 5579.6 5655.7 5734.0 5736.1 5747.1 5698.4 5730.2 5735.3 5743.9 5749.4 5184.8 5214.9 5241.3 5243.4 5310.3 5297.2 5304.9 5307.2 5328.3 5330.4 5186.8 5216.7 5217.7 5218.8 5323.1 5291.5 5291.6 5310.8 5310.8 5380.2 5129.5 5137.1 5165.8 5215.0 5223.5 5602.2 5630.9 5659.5 5686.9 5690.2 6230.8 6236.8 6254.4 6257.1 6261.6 6147.6 6224.2 6225.6 6231.3 6315.9 5791.7 5798.1 5804.7 5829.4 5849.5 6308.9 6309.1 6315.9 6318.3 6323.8 5124.2 6037.0 6098.3 6158.7 6180.3 6200.4 6283.0 6300.9 6301.9 6303.9 6194.0 6197.6 6202.6 6234.1 6247.8 6105.5 6133.1 6271.6 6273.6 6278.0 4938.1 4976.1 4976.5 4984.2 4990.3 4434.1 4455.5 4495.8 4535.7 4579.2 5658.6 5716.5 5724.8 5750.1 5775.7 5085.9 6219.9 6287.3 6290.3 6296.6 5576.0 5633.3 5646.7 5690.2 5692.1 5392.2 5428.3 5442.5 5447.6 5452.3 6239.8 6273.7 6306.7 6309.4 6315.9 6279.3 6293.3 6302.4 6309.7 6317.4 6256.2 6274.9 6293.2 6307.2 6308.1 6119.7 6163.5 6179.6 6235.3 6254.2 6213.5 6233.8 6247.9 6260.1 6278.7 6134.4 6134.5 6159.4 6159.9 6166.3 5975.0 6018.9 6084.2 6109.6 6117.5 5847.7 6052.8 6053.0 6071.5 6074.8 6135.3 6144.8 6167.8 6169.0 6173.5 5824.9 5877.7 5929.6 5967.1 5972.9 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:55455.91 Median:40096.60 stddev:111644.01 (278.4%) stddev-sans-first:111880.83 05:28:40 INFO - PROCESS | 1652 | Values: 453.6 472.7 479.1 480.0 517.8 30879.2 32588.9 40632.6 41534.0 41657.2 42648.1 42945.4 43024.1 43037.0 43121.1 9538.0 9678.6 9687.5 9710.4 9729.9 9646.2 9750.4 9764.1 9765.4 9773.8 9748.7 9800.0 9805.0 9809.6 9827.7 9691.9 9776.2 9780.3 9798.8 9833.9 9611.3 9677.4 9680.8 9684.1 9699.2 40430.0 40791.0 40831.6 40836.8 40865.2 3392.7 3410.3 3416.3 3454.3 3501.8 31191.9 31618.8 31831.0 32316.0 32942.0 2991.7 3145.2 3150.7 3180.2 3223.1 478914.0 480001.0 480894.0 481088.0 481394.3 61097.7 61594.7 61710.7 61803.6 61840.0 35004.3 35389.3 35573.6 65766.7 66234.7 16268.8 16699.1 16709.4 16719.2 16789.2 12817.6 12900.7 12918.7 12919.7 12921.4 15977.0 18621.3 19162.8 19175.6 19298.3 6481.8 7646.5 7794.0 7814.2 7900.6 860.7 930.3 1032.5 1052.9 1071.7 28979.3 30759.1 30807.5 31225.1 31254.8 997.8 1023.0 1107.8 1116.8 1181.9 15517.6 15792.0 15820.9 15920.8 16323.4 182665.0 183267.1 183701.0 183887.1 184289.0 58506.4 59099.7 59112.0 59169.1 59266.7 991.4 1238.1 1313.1 1336.9 1346.4 30703.0 31897.0 31967.2 32445.7 32597.5 50409.5 51046.7 51058.0 51173.7 51293.7 506779.0 510568.0 511579.0 511774.0 512388.0 27064.3 27154.2 28095.6 28105.6 28955.4 48368.3 48477.0 49103.0 49123.8 49306.8 39592.6 39724.0 40005.8 41060.8 41247.0 39753.8 40037.4 40155.8 40200.6 41379.6 38344.4 38773.4 39148.2 39298.6 39439.6 33862.0 34527.5 34979.3 35251.3 35413.5 23429.9 25317.7 25470.0 25824.9 26324.1 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35335.87 Median:2409.89 stddev:100395.36 (4166.0%) stddev-sans-first:100642.55 05:28:40 INFO - PROCESS | 1652 | Values: 312.9 355.0 370.6 378.3 406.1 15252.9 17110.7 20366.8 20483.2 20602.5 19886.8 20465.4 20700.1 20876.2 21231.7 5810.0 5834.9 5908.9 6057.4 6086.0 5847.6 5997.5 6007.2 6007.5 6014.7 4125.4 6263.4 6563.2 6585.2 6670.9 6377.4 6580.5 6643.0 6643.0 6644.4 5763.3 5769.7 5778.2 5783.5 5790.1 25868.6 26041.7 26075.0 26109.6 26390.3 2319.9 2327.8 2386.4 2433.4 3009.6 15333.8 15719.9 15864.3 16014.4 16164.1 1820.7 1860.8 1863.9 1892.3 1918.6 117183.0 122411.0 123573.4 123818.0 124033.4 10692.3 10709.1 10776.0 10875.6 11162.9 14051.9 14052.2 14058.1 14060.6 14065.5 8589.2 8720.4 8736.3 8822.5 8885.2 8824.9 8846.0 8853.5 9012.3 9018.7 5481.5 6707.2 6712.4 6716.9 6734.8 1861.6 1874.4 1874.4 1874.9 1894.5 926.8 949.6 955.2 967.8 1029.3 21743.6 22838.3 22887.9 22917.7 23032.9 964.0 976.0 1014.9 1077.9 1094.3 11127.6 11281.0 11836.2 11887.9 11893.0 592421.0 595690.0 597534.0 614340.0 614351.0 85470.1 114037.0 121320.8 121466.0 126896.0 954.5 983.2 998.7 1001.3 1007.5 18962.6 19091.8 19150.5 19173.9 19190.1 51372.7 51510.7 52279.7 54016.7 58946.7 77384.6 83686.6 85185.0 85499.0 87478.6 7820.8 7961.2 8099.7 8178.6 8278.8 10222.3 12395.5 12590.2 12981.0 13039.5 10734.1 11092.7 11171.9 11363.9 11365.5 8376.2 10726.2 10997.5 11053.9 11300.9 10904.9 11115.8 11294.9 11469.7 11563.2 7844.7 7873.6 8138.0 8318.2 8328.2 9298.6 10913.6 10959.9 11230.2 11318.2 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5679.89 Median:5023.66 stddev:3229.10 (64.3%) stddev-sans-first:3221.60 05:28:40 INFO - PROCESS | 1652 | Values: 1329.3 1461.9 1504.0 1675.2 1877.9 7043.7 7726.9 8248.8 8726.7 8850.6 8685.2 8796.0 8803.9 8808.3 8814.3 2296.6 2322.3 2326.5 2326.9 2327.6 2303.8 2335.2 2348.8 2352.8 2354.0 2300.9 2319.4 2325.5 2340.6 2350.3 2276.3 2307.1 2316.9 2319.7 2321.5 2295.0 2295.2 2295.6 2300.5 2303.6 8760.0 8840.8 8852.2 8853.4 8855.0 5110.1 5155.8 5268.4 5274.6 5279.9 3581.4 3637.4 3638.7 3640.3 3642.2 1084.6 1094.9 1117.9 1118.7 1121.6 11580.6 11650.8 11684.3 11710.7 11733.0 4374.6 4615.1 4776.6 4847.5 4894.2 9166.5 9227.9 9264.8 9276.5 9291.5 5207.7 5214.9 5217.8 5222.6 5223.8 3786.4 4324.8 4859.5 4877.7 4880.6 3767.5 3792.9 3797.1 3840.2 3902.6 985.5 992.8 1148.2 1158.1 1159.1 828.7 872.6 882.5 927.0 939.4 5582.8 5652.3 5984.7 6024.6 6035.4 435.0 441.4 452.0 454.1 454.2 2907.7 3819.0 4062.0 4079.8 4208.9 10662.9 10873.2 10981.3 10981.8 11076.6 10915.5 10938.2 10940.7 10951.2 10958.0 7775.9 8052.3 8271.8 8306.2 8361.8 5910.4 5962.3 6055.9 6071.4 6135.6 8503.7 10092.4 10161.5 10293.6 10316.3 9358.8 9660.4 9977.1 10277.3 10284.9 7398.7 7573.8 7648.3 7654.6 7666.3 8295.3 8379.1 8475.5 8483.0 8495.9 7502.7 7576.9 7626.1 7701.9 7733.1 7531.5 7542.4 7554.4 7558.8 7582.8 7009.2 7022.2 7111.3 7124.9 7125.6 4962.2 5027.5 5066.4 5070.1 5184.7 5019.8 5145.0 5157.1 5247.8 5273.8 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4357.32 Median:4886.50 stddev:1331.91 (27.3%) stddev-sans-first:1299.58 05:28:40 INFO - PROCESS | 1652 | Values: 255.8 294.5 305.2 311.5 314.0 4379.5 5167.6 5185.5 5186.4 5193.4 4129.7 4852.5 4920.5 5118.9 5149.6 3168.6 3369.8 3933.9 4000.8 4002.5 3960.4 3997.6 4002.7 4004.0 4020.5 3073.9 3469.8 3614.4 3726.7 3922.8 3583.4 3590.2 3992.9 3998.9 4006.0 2727.3 3461.1 3466.7 3468.4 3479.8 4925.4 5124.4 5127.1 5136.6 5137.0 4538.5 4539.8 4541.7 4560.8 4562.3 4089.9 4656.9 5190.9 5307.7 5438.3 3631.7 3670.7 3807.3 3812.6 3818.8 5089.3 5397.3 5423.7 5694.8 5713.8 3307.5 3519.9 3819.5 3868.3 3902.7 3949.2 3976.7 3991.0 3995.6 4013.0 4384.7 4555.7 4591.6 4606.5 4610.4 5447.9 5448.9 5467.5 5474.4 5527.2 1121.3 1145.5 1312.7 1340.8 1342.8 720.0 837.3 863.6 886.9 908.2 4194.6 4613.3 4978.6 5042.9 5064.8 5130.8 5467.8 5502.6 5550.6 5566.4 4489.0 4561.5 4789.1 5071.0 5074.5 2365.8 2880.9 2914.7 2917.6 2921.6 5227.3 5747.8 5756.0 5760.4 5767.7 5275.3 5619.4 5666.9 5693.7 5696.2 4945.7 5038.3 5666.2 5735.3 5793.8 4602.7 4825.0 5266.5 5563.8 5568.0 5204.6 5415.3 5453.3 5472.4 5489.5 4243.1 5381.8 5620.6 5726.2 5763.7 3452.0 3581.6 3650.0 3899.2 3928.4 4607.9 4744.5 4953.8 5467.2 5485.4 4689.9 5343.3 5400.7 5440.0 5450.2 4514.4 5193.5 5354.5 5395.3 5431.1 4138.6 5249.2 5249.4 5259.9 5272.3 4716.1 4843.8 5099.5 5101.6 5325.3 4682.6 5083.0 5096.7 5146.8 5303.6 05:28:40 INFO - PROCESS | 1652 | 05:28:40 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24128.85 Median:3947.46 stddev:38519.36 (975.8%) stddev-sans-first:38586.83 05:28:40 INFO - PROCESS | 1652 | Values: 578.3 748.6 777.1 783.4 786.4 14590.8 15195.0 15199.2 15203.8 15240.4 13422.8 13447.2 13460.3 13505.7 13514.7 687.4 706.4 708.0 709.2 849.2 705.2 707.5 840.9 863.4 864.8 918.8 922.7 923.8 925.2 928.0 914.3 918.2 918.3 919.4 919.7 537.8 686.3 687.6 687.7 688.0 13884.0 14348.9 14361.0 14374.2 14423.6 14261.4 14817.6 14878.7 14901.3 14908.3 11595.0 11919.4 12072.8 12081.2 12378.8 3813.5 3832.1 3933.5 3961.4 3998.1 113208.4 113313.4 113681.0 115731.3 116840.4 11197.2 11202.6 11300.2 11461.6 11534.9 22518.5 22625.8 22673.0 22685.0 22706.3 8000.6 8140.2 8142.5 8147.6 8210.8 4479.6 4530.4 4536.8 4580.5 4745.8 3470.7 3629.3 3630.9 3636.2 3638.6 620.2 627.7 631.0 633.4 636.6 624.5 633.5 646.5 653.8 687.4 8676.2 8712.3 8880.1 9062.5 9398.4 606.3 618.5 619.4 678.9 682.2 4454.4 4603.7 4631.7 4688.1 4721.6 87480.6 98921.0 118800.0 149572.0 162282.6 146408.3 149299.0 151314.2 153235.7 155914.0 68961.0 70382.6 70712.0 95764.0 98483.5 8708.6 9551.6 9803.3 9833.3 9897.4 61746.0 62828.7 63196.7 63760.0 65968.0 80771.4 86839.1 88102.0 96515.5 97517.0 11536.4 12802.7 13010.9 13166.3 13394.3 14784.8 14789.5 14899.7 15031.2 15042.2 13879.7 13886.9 13953.7 14308.5 14318.5 13372.9 13427.4 13442.1 13445.9 13493.6 13178.3 13413.4 13440.9 13783.2 13839.3 10776.4 11142.2 11228.9 11517.8 11956.3 11276.2 11447.0 11451.5 11566.6 11652.8 05:28:40 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:28:40 INFO - PROCESS | 1652 | 05:28:41 INFO - TEST-INFO | 1652: exit 0 05:28:41 INFO - TEST-OK | dromaeo_css | took 1119038ms 05:28:41 INFO - TEST-START | kraken 05:28:41 INFO - operating with platform_type : mac_ 05:28:41 INFO - Initialising browser for kraken test... 05:28:41 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmppjTpi7/profile http://localhost:49274/getInfo.html) 05:28:43 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:28:43 INFO - PROCESS | 1659 | colorDepth:24 05:28:43 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:28:43 INFO - PROCESS | 1659 | __metrics 05:28:43 INFO - PROCESS | 1659 | 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" 05:28:43 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:44 INFO - TEST-INFO | 1659: exit 0 05:28:44 INFO - Browser initialized. 05:28:44 INFO - Running cycle 1/1 for kraken test... 05:28:44 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmppjTpi7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:56 INFO - PROCESS | 1660 | RSS: Main: 243458048 05:28:56 INFO - PROCESS | 1660 | 05:28:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:00 INFO - PROCESS | 1660 | RSS: Main: 294928384 05:29:00 INFO - PROCESS | 1660 | 05:29:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 05:29:03 INFO - PROCESS | 1660 | RSS: Main: 305045504 05:29:03 INFO - PROCESS | 1660 | 05:29:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 05:29:07 INFO - PROCESS | 1660 | RSS: Main: 395300864 05:29:07 INFO - PROCESS | 1660 | 05:29:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 05:29:10 INFO - PROCESS | 1660 | RSS: Main: 297730048 05:29:10 INFO - PROCESS | 1660 | 05:29:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:12 INFO - PROCESS | 1660 | RSS: Main: 302174208 05:29:12 INFO - PROCESS | 1660 | 05:29:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:16 INFO - PROCESS | 1660 | RSS: Main: 339898368 05:29:16 INFO - PROCESS | 1660 | 05:29:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:20 INFO - PROCESS | 1660 | RSS: Main: 340082688 05:29:20 INFO - PROCESS | 1660 | 05:29:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 05:29:23 INFO - PROCESS | 1660 | RSS: Main: 338731008 05:29:23 INFO - PROCESS | 1660 | 05:29:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:25 INFO - PROCESS | 1660 | RSS: Main: 314269696 05:29:25 INFO - PROCESS | 1660 | 05:29:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:28 INFO - PROCESS | 1660 | RSS: Main: 319479808 05:29:28 INFO - PROCESS | 1660 | 05:29:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:31 INFO - PROCESS | 1660 | RSS: Main: 321744896 05:29:31 INFO - PROCESS | 1660 | 05:29:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:34 INFO - PROCESS | 1660 | RSS: Main: 332976128 05:29:34 INFO - PROCESS | 1660 | 05:29:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:38 INFO - PROCESS | 1660 | RSS: Main: 335335424 05:29:38 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 05:29:40 INFO - PROCESS | 1660 | RSS: Main: 378744832 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | __start_tp_report 05:29:40 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 05:29:40 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 05:29:40 INFO - PROCESS | 1660 | |i|pagename|runs| 05:29:40 INFO - PROCESS | 1660 | |0;ai-astar;100;112;111;113;112;112;113;112;110;113 05:29:40 INFO - PROCESS | 1660 | |1;audio-beat-detection;141;109;105;162;106;110;111;112;178;138 05:29:40 INFO - PROCESS | 1660 | |2;audio-dft;186;182;182;185;184;183;184;182;182;180 05:29:40 INFO - PROCESS | 1660 | |3;audio-fft;79;58;57;108;60;57;64;63;82;60 05:29:40 INFO - PROCESS | 1660 | |4;audio-oscillator;81;69;97;70;76;68;95;71;67;96 05:29:40 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;90;131;88;85;88;85;88;86;89;85 05:29:40 INFO - PROCESS | 1660 | |6;imaging-darkroom;93;93;122;150;145;93;94;94;112;150 05:29:40 INFO - PROCESS | 1660 | |7;imaging-desaturate;83;84;83;84;84;84;86;84;83;85 05:29:40 INFO - PROCESS | 1660 | |8;json-parse-financial;54;52;51;55;52;52;53;52;53;52 05:29:40 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;67;67;68;77;77;71;71;71;70;74 05:29:40 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;84;85;85;87;82;83;83;82;86;81 05:29:40 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;139;138;136;138;138;129;152;152;227;138 05:29:40 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;168;170;169;165;170;166;166;197;191;173 05:29:40 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;68;69;68;68;68;67;67;65;65;64 05:29:40 INFO - PROCESS | 1660 | __end_tp_report 05:29:40 INFO - PROCESS | 1660 | __start_cc_report 05:29:40 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2095 05:29:40 INFO - PROCESS | 1660 | __end_cc_report 05:29:40 INFO - PROCESS | 1660 | __startTimestamp1470659380431__endTimestamp 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | ------- Summary: start ------- 05:29:40 INFO - PROCESS | 1660 | Number of tests: 14 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.91 (3.5%) stddev-sans-first:1.00 05:29:40 INFO - PROCESS | 1660 | Values: 100.0 112.0 111.0 113.0 112.0 112.0 113.0 112.0 110.0 113.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:125.00 stddev:26.17 (20.9%) stddev-sans-first:27.27 05:29:40 INFO - PROCESS | 1660 | Values: 141.0 109.0 105.0 162.0 106.0 110.0 111.0 112.0 178.0 138.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.76 (1.0%) stddev-sans-first:1.50 05:29:40 INFO - PROCESS | 1660 | Values: 186.0 182.0 182.0 185.0 184.0 183.0 184.0 182.0 182.0 180.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:68.80 Median:61.50 stddev:16.40 (26.7%) stddev-sans-first:16.98 05:29:40 INFO - PROCESS | 1660 | Values: 79.0 58.0 57.0 108.0 60.0 57.0 64.0 63.0 82.0 60.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:78.50 stddev:12.44 (15.8%) stddev-sans-first:13.17 05:29:40 INFO - PROCESS | 1660 | Values: 81.0 69.0 97.0 70.0 76.0 68.0 95.0 71.0 67.0 96.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:88.00 stddev:13.99 (15.9%) stddev-sans-first:14.83 05:29:40 INFO - PROCESS | 1660 | Values: 90.0 131.0 88.0 85.0 88.0 85.0 88.0 86.0 89.0 85.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:114.60 Median:93.00 stddev:25.26 (27.2%) stddev-sans-first:25.55 05:29:40 INFO - PROCESS | 1660 | Values: 93.0 93.0 122.0 150.0 145.0 93.0 94.0 94.0 112.0 150.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.94 (1.1%) stddev-sans-first:0.93 05:29:40 INFO - PROCESS | 1660 | Values: 83.0 84.0 83.0 84.0 84.0 84.0 86.0 84.0 83.0 85.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.50 stddev:1.17 (2.2%) stddev-sans-first:1.13 05:29:40 INFO - PROCESS | 1660 | Values: 54.0 52.0 51.0 55.0 52.0 52.0 53.0 52.0 53.0 52.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:3.68 (5.2%) stddev-sans-first:3.56 05:29:40 INFO - PROCESS | 1660 | Values: 67.0 67.0 68.0 77.0 77.0 71.0 71.0 71.0 70.0 74.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.50 stddev:1.93 (2.3%) stddev-sans-first:2.05 05:29:40 INFO - PROCESS | 1660 | Values: 84.0 85.0 85.0 87.0 82.0 83.0 83.0 82.0 86.0 81.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:138.50 stddev:28.39 (20.5%) stddev-sans-first:29.89 05:29:40 INFO - PROCESS | 1660 | Values: 139.0 138.0 136.0 138.0 138.0 129.0 152.0 152.0 227.0 138.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:170.00 stddev:11.15 (6.6%) stddev-sans-first:11.65 05:29:40 INFO - PROCESS | 1660 | Values: 168.0 170.0 169.0 165.0 170.0 166.0 166.0 197.0 191.0 173.0 05:29:40 INFO - PROCESS | 1660 | 05:29:40 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:68.00 stddev:1.66 (2.4%) stddev-sans-first:1.72 05:29:40 INFO - PROCESS | 1660 | Values: 68.0 69.0 68.0 68.0 68.0 67.0 67.0 65.0 65.0 64.0 05:29:40 INFO - PROCESS | 1660 | -------- Summary: end -------- 05:29:40 INFO - PROCESS | 1660 | 05:29:41 INFO - TEST-INFO | 1660: exit 0 05:29:41 INFO - TEST-OK | kraken | took 59894ms 05:29:41 INFO - SUITE-END | took 1178s 05:29:41 INFO - Completed test suite (00:19:39) 05:29:41 INFO - javascript benchmark 05:29:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.734501239901, 3349.4138525757985, 3349.949750753739, 3359.0426728382417, 3386.8983930482077, 5579.609427340078, 5655.717214139292, 5733.9713301433485, 5736.082226843706, 5747.051736463489, 5698.373178950312, 5730.19777231188, 5735.311762588485, 5743.913841292374, 5749.396313387097, 5184.844454666357, 5214.947850521484, 5241.344831896013, 5243.397009343936, 5310.309659744228, 5297.152455607104, 5304.893902121955, 5307.177387504943, 5328.333958255218, 5330.3603567571945, 5186.7925282988635, 5216.713080780555, 5217.713025783581, 5218.765155568009, 5323.095073837438, 5291.523762861361, 5291.6031297652635, 5310.7610157543, 5310.7610157543, 5380.192971054334, 5129.487051294878, 5137.075326441239, 5165.793368265265, 5215.0, 5223.477652234784, 5602.215689803431, 5630.915536266918, 5659.51894089003, 5686.857828554278, 5690.2033715279895, 6230.813075607739, 6236.844078898019, 6254.437100660917, 6257.092721555395, 6261.624302541862, 6147.5553244987395, 6224.190855188796, 6225.564210505267, 6231.2522497300615, 6315.936840631581, 5791.681457519877, 5798.130280457881, 5804.7097645118, 5829.4170582942215, 5849.5027922626205, 6308.873822523524, 6309.116723658692, 6315.905261421082, 6318.304986451487, 6323.810285691437, 5124.231365295235, 6037.003894357382, 6098.32918378978, 6158.7228574714245, 6180.289266734383, 6200.410960958701, 6283.0, 6300.905486417708, 6301.936980630227, 6303.936960630427, 6193.969030154911, 6197.597156184834, 6202.56582039262, 6234.127222188807, 6247.812565623039, 6105.542084343692, 6133.08984587446, 6271.560990730696, 6273.62358258506, 6277.96861015692, 4938.08645400602, 4976.129177393942, 4976.477469865685, 4984.22744474607, 4990.30135780991, 4434.068845542408, 4455.4876189238, 4495.842645507457, 4535.727856328631, 4579.22153233951, 5658.632188907708, 5716.542676585863, 5724.77100915967, 5750.137479378127, 5775.740091695884, 5085.923711144335, 6219.875602487925, 6287.339829317947, 6290.308066112724, 6296.590721603081, 5576.0241957657245, 5633.267675202197, 5646.745896434671, 5690.174924635905, 5692.117721753135, 5392.245085688007, 5428.294321738149, 5442.537384322377, 5447.618666693294, 5452.318460192476, 6239.7504099837315, 6273.65494897785, 6306.716197771111, 6309.3690630935625, 6315.873682526232, 6279.277883043508, 6293.339199383999, 6302.369763023661, 6309.7160627770945, 6317.431431171217, 6256.217972753406, 6274.937250627618, 6293.150424692608, 6307.211598550181, 6308.148399965946, 6119.746288422617, 6163.506919446344, 6179.598326108789, 6235.3452887446165, 6254.249490061222, 6213.5339849512375, 6233.781817636361, 6247.937520624736, 6260.092286618506, 6278.7488500461295, 6134.38656134383, 6134.539909506716, 6159.353267906805, 6159.938400615936, 6166.290876549254, 5974.970125149346, 6018.909716354346, 6084.178635884099, 6109.586862300737, 6117.541184411276, 5847.707614619337, 6052.818415447544, 6053.0, 6071.453569178795, 6074.787382441594, 6135.250308654353, 6144.846378840565, 6167.814965551041, 6169.043798211284, 6173.536984726254, 5824.854378640568, 5877.676727780014, 5929.6145750526075, 5967.134765458897, 5972.850678733066], "value": 5713.3945032297925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.6461559983213, 472.7470803120331, 479.101684341859, 480.03992015968066, 517.8446466060184, 30879.228019299502, 32588.859389921327, 40632.59367406329, 41534.0, 41657.16685666285, 42648.147037059316, 42945.35581966273, 43024.13951720972, 43037.0, 43121.13757724844, 9537.95231023844, 9678.612855485771, 9687.515624218797, 9710.368826026286, 9729.854052189223, 9646.180074693659, 9750.414975101481, 9764.12122908938, 9765.365251258645, 9773.755656108584, 9748.707538773813, 9800.0, 9804.970478099804, 9809.558569864337, 9827.70516884491, 9691.90308096917, 9776.169025632758, 9780.266480014028, 9798.804023919552, 9833.90166098337, 9611.279154063403, 9677.419354838732, 9680.806383872283, 9684.08001239881, 9699.224062075016, 40429.989250268685, 40791.0, 40831.591684083076, 40836.774896753144, 40865.1826963462, 3392.6776956189124, 3410.3179364127272, 3416.282580658065, 3454.2573346730574, 3501.7548771586, 31191.908283210207, 31618.841905790323, 31831.0, 32316.0, 32942.0, 2991.7008299170125, 3145.1822526143137, 3150.7479401647815, 3180.220845892748, 3223.09753269085, 478914.0, 480001.0, 480894.0, 481088.0, 481394.33725482086, 61097.69451152716, 61594.69202653958, 61710.69144654248, 61803.60080195543, 61840.0, 35004.299914001575, 35389.292214155575, 35573.644263557035, 65766.67116664482, 66234.66882665556, 16268.75596866048, 16699.08155051484, 16709.415170468976, 16719.16404179786, 16789.16054197285, 12817.615471535868, 12900.741985160432, 12918.677033074247, 12919.677008074685, 12921.418536165707, 15977.0, 18621.348252811356, 19162.808371916104, 19175.61648767017, 19298.32455864066, 6481.773137940149, 7646.541207527566, 7794.0, 7814.21857814214, 7900.644470998819, 860.7202659135806, 930.2697382554662, 1032.5353590884133, 1052.9420881851572, 1071.689210129054, 28979.275518112216, 30759.077227683207, 30807.537886931714, 31225.063248102593, 31254.843725781226, 997.7627741600363, 1023.0127926550914, 1107.7618312062946, 1116.7766446710527, 1181.911356648255, 15517.612059698598, 15792.0, 15820.92089539545, 15920.840791592167, 16323.428679996381, 182665.0, 183267.08366458083, 183701.0, 183887.0805645963, 184289.0, 58506.414935850094, 59099.704501477216, 59112.0, 59169.11246331308, 59266.70366648139, 991.3655260633201, 1238.0900038471536, 1313.080843409598, 1336.8930485561132, 1346.3806648941597, 30703.0, 31897.0, 31967.200819979687, 32445.67554324521, 32597.511037333985, 50409.49590504048, 51046.74476627593, 51058.0, 51173.7441312806, 51293.7435312821, 506779.0, 510568.0, 511579.0, 511774.0, 512388.0, 27064.32339191536, 27154.1853744388, 28095.578566321114, 28105.57841632418, 28955.42089158163, 48368.27447588219, 48477.0, 49103.0, 49123.75438122787, 49306.76733081702, 39592.60407395889, 39724.0, 40005.79997099996, 41060.79469602752, 41247.0, 39753.80123099366, 40037.39943900786, 40155.799221003705, 40200.597994020856, 41379.58620413757, 38344.42483362807, 38773.41839872348, 39148.2170356597, 39298.60701393064, 39439.60560394359, 33862.0, 34527.4820877682, 34979.30041399107, 35251.294974100885, 35413.46879796857, 23429.945652446022, 25317.74682253154, 25470.0, 25824.8708756455, 26324.078657246904], "value": 19262.778011464703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.9186411533001, 354.9591796943352, 370.6071564142008, 378.2850412719959, 406.1025134452859, 15252.932294739372, 17110.743338849912, 20366.796332036713, 20483.180672773073, 20602.48493787656, 19886.801131988675, 20465.38603841887, 20700.068496917636, 20876.164953401883, 21231.6815247771, 5809.9709501452435, 5834.912476312858, 5908.88182236355, 6057.424544668272, 6085.969570152143, 5847.590668653196, 5997.520198384119, 6007.15899774032, 6007.549433792461, 6014.699265036753, 4125.381192821071, 6263.373662633746, 6563.1796025496815, 6585.209774827027, 6670.93329066708, 6377.362263773585, 6580.5393622446445, 6642.966785166043, 6642.9667851660915, 6644.402003819655, 5763.250777398911, 5769.653820770767, 5778.162166485836, 5783.5084017858535, 5790.13148027795, 25868.611970820453, 26041.69791510436, 26075.0, 26109.608355874676, 26390.340241493923, 2319.8724070176136, 2327.8486898351553, 2386.3676125826496, 2433.415980164748, 3009.6388433387915, 15333.846661533353, 15719.921400392925, 15864.286107125206, 16014.35942562296, 16164.110973896431, 1820.7268909663524, 1860.8232217939274, 1863.8788478748972, 1892.3187652445247, 1918.56832212751, 117183.0, 122411.0, 123573.38213308966, 123818.0, 124033.37983310116, 10692.305000174963, 10709.089727373104, 10776.0, 10875.619353322754, 11162.888371116347, 14051.85948140526, 14052.156870587798, 14058.086224395382, 14060.648483787987, 14065.507707230197, 8589.184027517376, 8720.389572729975, 8736.257418119403, 8822.51476168804, 8885.155910188521, 8824.8676269856, 8845.955770221237, 8853.51305678181, 9012.27901767857, 9018.684346047858, 5481.506664400223, 6707.228668703064, 6712.42944349726, 6716.899246511306, 6734.764283250062, 1861.6276744651125, 1874.390822982536, 1874.4282993687057, 1874.8593855460895, 1894.488488108203, 926.8470702334174, 949.6296444386695, 955.1737746848926, 967.7870868408938, 1029.2743615750878, 21743.565128697337, 22838.31485055451, 22887.885560572424, 22917.65623515615, 23032.884835575715, 963.9776763064373, 975.9707208783748, 1014.8629934958686, 1077.9191560632985, 1094.3051162511704, 11127.610533631285, 11281.0, 11836.23064500822, 11887.88112118885, 11893.0, 592421.0, 595690.0, 597534.0, 614340.0, 614351.0, 85470.14529854746, 114037.0, 121320.78679213271, 121466.0, 126896.0, 954.4798085043668, 983.1692220074169, 998.6804222648683, 1001.3441196016621, 1007.496251874063, 18962.620747585246, 19091.809081909003, 19150.521236969187, 19173.945433001318, 19190.13644385978, 51372.715682108246, 51510.74244628753, 52279.73860130675, 54016.72991635017, 58946.70526647339, 77384.61307693426, 83686.58156709178, 85185.0, 85499.0, 87478.56260718656, 7820.765377038698, 7961.203879611992, 8099.676012959416, 8178.591070446335, 8278.77571199556, 10222.335548189641, 12395.504179832706, 12590.244585324543, 12981.0, 13039.478420863059, 10734.14126869833, 11092.722681933015, 11171.944140279247, 11363.886361136614, 11365.48855301497, 8376.246137847624, 10726.249162558546, 10997.450127493434, 11053.889461105286, 11300.886991130312, 10904.85499022623, 11115.83326250123, 11294.94352528232, 11469.713257168638, 11563.19057665989, 7844.725434609762, 7873.606319684108, 8138.0, 8318.168183181875, 8328.208820162075, 9298.581563829508, 10913.56345746193, 10959.890401096205, 11230.21388502797, 11318.245671920866], "value": 9892.448307686485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.282187618686, 1461.9488317908872, 1504.0374160537258, 1675.1624187906048, 1877.859160562958, 7043.683034263458, 7726.884096738547, 8248.83502329953, 8726.65093396265, 8850.645974161042, 8685.17490838371, 8795.956020219906, 8803.867941980876, 8808.29533637309, 8814.33892458065, 2296.644020176867, 2322.349742072214, 2326.534693061386, 2326.8603883766946, 2327.55776402483, 2303.815694744425, 2335.159342636658, 2348.812095032402, 2352.8235382346306, 2353.976460235401, 2300.8734519601417, 2319.3621754017613, 2325.546518428907, 2340.625499920013, 2350.341904266808, 2276.3284830974903, 2307.077169132344, 2316.907323707058, 2319.686842276288, 2321.5472982768365, 2294.977050229493, 2295.1507942061544, 2295.5638428698494, 2300.5398920216026, 2303.6199027160496, 8759.956200219021, 8840.778980525474, 8852.247558957497, 8853.380263381567, 8854.955725221333, 5110.131277682803, 5155.845324640267, 5268.367795864521, 5274.604404669665, 5279.894402111936, 3581.426971684518, 3637.399829028234, 3638.7089032877307, 3640.3447379471686, 3642.21692336149, 1084.5553323137476, 1094.885037071112, 1117.9217454778168, 1118.7426891814928, 1121.5681962444437, 11580.594679186188, 11650.825237621528, 11684.298942063504, 11710.70723231926, 11733.002694770954, 4374.62815660666, 4615.123126605934, 4776.617870570346, 4847.515248475124, 4894.19245824435, 9166.49584272858, 9227.90772092284, 9264.768380790534, 9276.489793061312, 9291.535423228812, 5207.661502002357, 5214.869628259324, 5217.8173763918085, 5222.556082732935, 5223.7910483580235, 3786.422126904298, 4324.78376081201, 4859.465458799529, 4877.6585639004925, 4880.633952453581, 3767.453719210699, 3792.943105853414, 3797.1266608680157, 3840.231953609289, 3902.551206611276, 985.5269470654126, 992.7640088090374, 1148.1618418554501, 1158.1082566423806, 1159.0843233845337, 828.7182357998189, 872.5637181409295, 882.5013867165031, 926.9525436257121, 939.4128669581512, 5582.8325150245555, 5652.3217213934595, 5984.670843103584, 6024.638521688625, 6035.366286539938, 434.97825108744433, 441.39088058479734, 451.97740112994217, 454.1258078199453, 454.21420941770924, 2907.738303552648, 3819.0087440421335, 4061.9593804061583, 4079.8368065277054, 4208.894777630584, 10662.89337106619, 10873.238873278795, 10981.286216395909, 10981.780364392667, 11076.556937722562, 10915.454227288605, 10938.179636527293, 10940.671779846618, 10951.233413660968, 10958.0, 7775.922240777633, 8052.315553178046, 8271.751847444586, 8306.169383061766, 8361.790955226168, 5910.4089591042275, 5962.344142144274, 6055.878882422415, 6071.423214794588, 6135.631862088289, 8503.659853605786, 10092.444915529717, 10161.54273057729, 10293.639722609672, 10316.329438586468, 9358.766030849283, 9660.37207581505, 9977.10206081456, 10277.331973421704, 10284.897151028394, 7398.741044063432, 7573.848523029398, 7648.336287162122, 7654.578998155155, 7666.310032096915, 8295.294899933331, 8379.07830138672, 8475.533845638549, 8483.0, 8495.872561911452, 7502.699892004259, 7576.924230757842, 7626.084869815658, 7701.92298077012, 7733.110692270461, 7531.548107113591, 7542.396608271215, 7554.395648348229, 7558.811029724301, 7582.810429739301, 7009.1589009319205, 7022.157341119098, 7111.253318401494, 7124.857502850017, 7125.608091554807, 4962.156433406401, 5027.472115427974, 5066.392032956069, 5070.087384270867, 5184.740762961912, 5019.84940451787, 5145.048166089286, 5157.0975079361715, 5247.816326428557, 5273.815416460406], "value": 4462.816815818895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.82731656132106, 294.50229112799354, 305.18515563445595, 311.4891577812388, 314.024952522418, 4379.496357918841, 5167.5607573356265, 5185.533302002819, 5186.429492755794, 5193.402758682743, 4129.7315674481215, 4852.4662287148385, 4920.53254940781, 5118.923216151751, 5149.639525233235, 3168.572242747234, 3369.7978121312676, 3933.8819835404984, 4000.799960002003, 4002.4596679448337, 3960.4455376247206, 3997.620226078518, 4002.6597739192057, 4003.959960400388, 4020.4572382728397, 3073.924126555699, 3469.7744646598017, 3614.3855544557355, 3726.720495962814, 3922.8430862765454, 3583.4087375583126, 3590.2460483298537, 3992.86024989124, 3998.8600398985895, 4005.9554738398733, 2727.2636388175083, 3461.0828130545424, 3466.722662187019, 3468.3930312195516, 3479.7912125272564, 4925.38432695913, 5124.436312005676, 5127.077126117298, 5136.614753893436, 5136.974315128437, 4538.478075021382, 4539.773011349419, 4541.704789188725, 4560.768592480012, 4562.270036794131, 4089.85685501009, 4656.8602941911795, 5190.948090519084, 5307.708076055816, 5438.3473983121885, 3631.727620428452, 3670.6696397324367, 3807.2676931995875, 3812.5996770338993, 3818.7517811342173, 5089.312942752718, 5397.27136836526, 5423.701696406696, 5694.77220911163, 5713.800016999385, 3307.536944827726, 3519.859205631797, 3819.4652748615217, 3868.2650296443585, 3902.6682731967535, 3949.2101579684177, 3976.681865450757, 3991.002249437641, 3995.5615978247806, 4012.9598704012587, 4384.6711496637245, 4555.72665640056, 4591.632669386441, 4606.470255920545, 4610.354550362952, 5447.863803404946, 5448.945510544923, 5467.480589344085, 5474.39781624021, 5527.198556209327, 1121.3404161840522, 1145.4902568357168, 1312.6915174933883, 1340.7854743241035, 1342.804903635765, 720.0, 837.3343192162246, 863.5811631358786, 886.8847049883474, 908.2098574240453, 4194.559571244975, 4613.308003799457, 4978.626603004716, 5042.924356134661, 5064.797408103708, 5130.846074617767, 5467.840203119825, 5502.5597952163735, 5550.611457197957, 5566.443355664482, 4488.955110448919, 4561.452625684873, 4789.05473134027, 5070.974645126751, 5074.45229205096, 2365.834391592572, 2880.927976800555, 2914.737673609385, 2917.6287145041797, 2921.590977263185, 5227.294315267466, 5747.770089196385, 5755.971220143872, 5760.36635970043, 5767.68277744728, 5275.314209152785, 5619.409961953936, 5666.858328541737, 5693.686847223443, 5696.23100881384, 4945.661678367088, 5038.269450929594, 5666.231955749463, 5735.254416925856, 5793.828443520659, 4602.677812553223, 4825.0, 5266.498413253674, 5563.830106258643, 5567.972160139336, 5204.609654276021, 5415.323084614423, 5453.345598528202, 5472.425395333592, 5489.505944465017, 4243.066525364415, 5381.757820898148, 5620.615919582732, 5726.198332233407, 5763.682997435181, 3451.964410676737, 3581.641835816502, 3649.9817500912327, 3899.2396482685604, 3928.4107383892647, 4607.907841843212, 4744.5492678195515, 4953.777080031479, 5467.152591348347, 5485.396606373216, 4689.859304220879, 5343.305370301836, 5400.675959442446, 5440.0, 5450.2369668247275, 4514.435695538058, 5193.506616871391, 5354.491323324278, 5395.325584301962, 5431.121505602037, 4138.627523522777, 5249.191682450024, 5249.448807875118, 5259.921101183409, 5272.2882410874045, 4716.056788642217, 4843.830465933816, 5099.4757594990715, 5101.6173786966865, 5325.254464374913, 4682.648801339981, 5083.0430284340055, 5096.694198348111, 5146.845594632167, 5303.5757139429525], "value": 3932.202223696253, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.2829291678319, 748.6032402826504, 777.1179711027982, 783.4085265624452, 786.4062632712303, 14590.78113828292, 15195.0, 15199.240037998083, 15203.771943420843, 15240.390384384626, 13422.798658020112, 13447.193168409878, 13460.326983650828, 13505.729885402287, 13514.72970540591, 687.4122625155495, 706.3713295167305, 708.040604980252, 709.1667290933133, 849.2144766091357, 705.2277755857328, 707.4517249131912, 840.8864803251574, 863.3611127765471, 864.7535452396069, 918.8070505193917, 922.6908985489869, 923.7644400677817, 925.1858364639108, 927.9721608351726, 914.2640174659397, 918.1874041473277, 918.3387960668375, 919.3840127114782, 919.6735159018552, 537.7956376576876, 686.3411125319699, 687.6286805125227, 687.7214728035103, 687.9690413931385, 13884.0, 14348.856511434857, 14361.0, 14374.20941848198, 14423.567292981228, 14261.429542818274, 14817.629559260997, 14878.70242595153, 14901.329440175217, 14908.254587270701, 11595.0, 11919.404029798563, 12072.758544829143, 12081.15431919766, 12378.81431778524, 3813.5423749150004, 3832.137769001952, 3933.5083114610675, 3961.4057891316247, 3998.1204135090234, 113208.43395782968, 113313.43343283313, 113681.0, 115731.26403103877, 116840.41579792046, 11197.16021298406, 11202.60790872332, 11300.208985370946, 11461.598844040585, 11534.904184102497, 22518.549629007328, 22625.773742262365, 22673.0, 22685.0, 22706.318810435714, 8000.599970001476, 8140.226678465655, 8142.511449312941, 8147.551884646401, 8210.753677389688, 4479.641628669699, 4530.411046563926, 4536.841210557614, 4580.496145424001, 4745.810167593327, 3470.722342212617, 3629.292288003814, 3630.909227269339, 3636.2182130841998, 3638.581563120222, 620.203039094761, 627.7238015273357, 630.9905351419733, 633.4425705379329, 636.6498425865756, 624.5222404860285, 633.4995353670548, 646.4860435953428, 653.8463461086641, 687.3504538211342, 8676.175763302603, 8712.259457946018, 8880.09442477256, 9062.546872656341, 9398.389104708172, 606.3057798820327, 618.523736722721, 619.3775255867914, 678.9422899053537, 682.1507223506766, 4454.354118652777, 4603.677175960617, 4631.698939568917, 4688.109259240733, 4721.622270218374, 87480.56259718661, 98921.0, 118800.0, 149572.0, 162282.56576151366, 146408.26795865953, 149299.0, 151314.24342878215, 153235.70146447813, 155914.0, 68961.0, 70382.64808675923, 70712.0, 95764.0, 98483.50758246164, 8708.647170839145, 9551.617935282511, 9803.264755143535, 9833.311668183445, 9897.406155630686, 61746.0, 62828.68585657225, 63196.73606527936, 63760.04359934697, 65968.0, 80771.3845723094, 86839.1316086831, 88102.0, 96515.51742241243, 97517.0, 11536.36549989725, 12802.743945121232, 13010.869891301345, 13166.302587193455, 13394.330283485591, 14784.778228326799, 14789.482368117064, 14899.702005959603, 15031.248437578297, 15042.247887605794, 13879.653008674864, 13886.861131388558, 13953.720925581634, 14308.451456141005, 14318.498852540111, 13372.866271337161, 13427.395767190304, 13442.059055865995, 13445.932770336085, 13493.595192144252, 13178.341082945622, 13413.396397161956, 13440.93279533596, 13783.241921694404, 13839.30803459843, 10776.353418794897, 11142.220044596801, 11228.943855280671, 11517.82723259135, 11956.282623042298, 11276.195808670573, 11446.969772720495, 11451.541938322374, 11566.595169169039, 11652.766944661229], "value": 7759.078746842948, "unit": "score"}], "name": "dromaeo_css", "value": 7275.061428026037, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 111.0, 113.0, 112.0, 112.0, 113.0, 112.0, 110.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 105.0, 162.0, 106.0, 110.0, 111.0, 112.0, 178.0, 138.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 182.0, 185.0, 184.0, 183.0, 184.0, 182.0, 182.0, 180.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 57.0, 108.0, 60.0, 57.0, 64.0, 63.0, 82.0, 60.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 97.0, 70.0, 76.0, 68.0, 95.0, 71.0, 67.0, 96.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 131.0, 88.0, 85.0, 88.0, 85.0, 88.0, 86.0, 89.0, 85.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 122.0, 150.0, 145.0, 93.0, 94.0, 94.0, 112.0, 150.0], "value": 114.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 84.0, 84.0, 84.0, 86.0, 84.0, 83.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 55.0, 52.0, 52.0, 53.0, 52.0, 53.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 77.0, 77.0, 71.0, 71.0, 71.0, 70.0, 74.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 87.0, 82.0, 83.0, 83.0, 82.0, 86.0, 81.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 136.0, 138.0, 138.0, 129.0, 152.0, 152.0, 227.0, 138.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 169.0, 165.0, 170.0, 166.0, 166.0, 197.0, 191.0, 173.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 68.0, 67.0, 67.0, 65.0, 65.0, 64.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1455.7, "alertThreshold": 2.0}]} 05:29:41 INFO - Return code: 0 05:29:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:41 INFO - # TBPL SUCCESS # 05:29:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:41 INFO - 2016-08-08 05:29:41.769 screenresolution[1664:9865] starting screenresolution argv=screenresolution get 05:29:41 INFO - 2016-08-08 05:29:41.771 screenresolution[1664:9865] Display 0: 1600x1200x32@60 05:29:41 INFO - 2016-08-08 05:29:41.777 screenresolution[1665:9868] starting screenresolution argv=screenresolution list 05:29:41 INFO - Available Modes on Display 0 05:29:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:42 INFO - Intel Iris: 05:29:42 INFO - Chipset Model: Intel Iris 05:29:42 INFO - Type: GPU 05:29:42 INFO - Bus: Built-In 05:29:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:42 INFO - Vendor: Intel (0x8086) 05:29:42 INFO - Device ID: 0x0a2e 05:29:42 INFO - Revision ID: 0x0009 05:29:42 INFO - Displays: 05:29:42 INFO - Display: 05:29:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:42 INFO - Display Serial Number: 1600x1200 60 05:29:42 INFO - Main Display: Yes 05:29:42 INFO - Mirror: Off 05:29:42 INFO - Online: Yes 05:29:42 INFO - Rotation: Supported 05:29:42 INFO - Return code: 0 05:29:42 INFO - Running post-action listener: _resource_record_post_action 05:29:42 INFO - [mozharness: 2016-08-08 12:29:42.015637Z] Finished run-tests step (success) 05:29:42 INFO - Running post-run listener: _resource_record_post_run 05:29:42 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 38302720; Write bytes: 706812416; Read time: 509; Write time: 2073 05:29:42 INFO - TinderboxPrint: CPU usage
53.3% 05:29:42 INFO - TinderboxPrint: I/O read bytes / time
38,302,720 / 509 05:29:42 INFO - TinderboxPrint: I/O write bytes / time
706,812,416 / 2,073 05:29:42 INFO - TinderboxPrint: CPU idle
2,271.5 (46.7%) 05:29:42 INFO - TinderboxPrint: CPU system
969.7 (19.9%) 05:29:42 INFO - TinderboxPrint: CPU user
1,620.5 (33.3%) 05:29:42 INFO - TinderboxPrint: Swap in / out
445,239,296 / 0 05:29:42 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 405879296; Write bytes: 396019712; Read time: 29678; Write time: 765 05:29:42 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 30911488; Write bytes: 294879744; Read time: 421; Write time: 1272 05:29:42 INFO - Running post-run listener: _upload_blobber_files 05:29:42 INFO - Blob upload gear active. 05:29:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:42 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 05:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:44 INFO - (blobuploader) - INFO - Done attempting. 05:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:45 INFO - (blobuploader) - INFO - Done attempting. 05:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:49 INFO - (blobuploader) - INFO - Done attempting. 05:29:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:49 INFO - Return code: 0 05:29:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e61d3874d9577aaf3d62a72350490e3b0c5ad9cbc0fdd664617f7cb9c9d1d63add365ef840fcb2b641e2298fd9c45ded8ecb781b7681c784400cd639664365f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e69f3ef11fe4ac9b290ce23cab02ff9bb03a4313c5eac6d88b21c6118a37e6bb5837c8ae0d560314504229a41109bb63daa71211ad78c00a72c6d15bc9390b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8f9eb5e15378687d7255ffda6284a62386f2647b644456a8f5eaa8671b135f3e9866e2a203b3785d86df075972b229b7eb9152a0a74162a68fab03f44353306"} 05:29:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:49 INFO - Contents: 05:29:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e61d3874d9577aaf3d62a72350490e3b0c5ad9cbc0fdd664617f7cb9c9d1d63add365ef840fcb2b641e2298fd9c45ded8ecb781b7681c784400cd639664365f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e69f3ef11fe4ac9b290ce23cab02ff9bb03a4313c5eac6d88b21c6118a37e6bb5837c8ae0d560314504229a41109bb63daa71211ad78c00a72c6d15bc9390b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8f9eb5e15378687d7255ffda6284a62386f2647b644456a8f5eaa8671b135f3e9866e2a203b3785d86df075972b229b7eb9152a0a74162a68fab03f44353306"} 05:29:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:49 INFO - Copying logs to upload dir... 05:29:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.607041 ========= master_lag: 0.52 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-08-08 05:29:49.753509) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-08 05:29:49.763975) ========= 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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e61d3874d9577aaf3d62a72350490e3b0c5ad9cbc0fdd664617f7cb9c9d1d63add365ef840fcb2b641e2298fd9c45ded8ecb781b7681c784400cd639664365f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e69f3ef11fe4ac9b290ce23cab02ff9bb03a4313c5eac6d88b21c6118a37e6bb5837c8ae0d560314504229a41109bb63daa71211ad78c00a72c6d15bc9390b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8f9eb5e15378687d7255ffda6284a62386f2647b644456a8f5eaa8671b135f3e9866e2a203b3785d86df075972b229b7eb9152a0a74162a68fab03f44353306"} build_url:https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/fc98D7qMTQqc1zlksVxdQA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e61d3874d9577aaf3d62a72350490e3b0c5ad9cbc0fdd664617f7cb9c9d1d63add365ef840fcb2b641e2298fd9c45ded8ecb781b7681c784400cd639664365f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e69f3ef11fe4ac9b290ce23cab02ff9bb03a4313c5eac6d88b21c6118a37e6bb5837c8ae0d560314504229a41109bb63daa71211ad78c00a72c6d15bc9390b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8f9eb5e15378687d7255ffda6284a62386f2647b644456a8f5eaa8671b135f3e9866e2a203b3785d86df075972b229b7eb9152a0a74162a68fab03f44353306"}' ========= master_lag: 1.53 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-08 05:29:51.301335) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:29:51.301704) ========= 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.pWpmDS2W8V/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.uLfY8tFtDC/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.005335 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:29:51.328175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:29:51.328478) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:29:51.330941) ========= ========= Total master_lag: 2.14 =========