builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0382 starttime: 1472179233.82 results: success (0) buildid: 20160825170018 builduid: fd1fa71f8550462d955c6df4a7961fdf revision: 8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:33.823864) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:33.824283) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:33.824555) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:33.847933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:33.848215) ========= 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.6c5IUpdCNe/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.WYOM5CvviE/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-25 19:40:33-- 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% 57.6M=0s 2016-08-25 19:40:33 (57.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129490 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.000163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.000472) ========= 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.6c5IUpdCNe/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.WYOM5CvviE/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.020642 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.050272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.050612) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6c5IUpdCNe/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.WYOM5CvviE/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-25 19:40:34,135 truncating revision to first 12 chars 2016-08-25 19:40:34,135 Setting DEBUG logging. 2016-08-25 19:40:34,135 attempt 1/10 2016-08-25 19:40:34,135 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8f6cbb5a8f4a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 19:40:34,466 unpacking tar archive at: mozilla-central-8f6cbb5a8f4a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587645 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.660138) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.660437) ========= script_repo_revision: 8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.660852) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.661117) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 19:40:34.679320) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-25 19:40:34.679584) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6c5IUpdCNe/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.WYOM5CvviE/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:40:34 INFO - MultiFileLogger online at 20160825 19:40:34 in /builds/slave/test 19:40:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:40:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:40:34 INFO - {'append_to_log': False, 19:40:34 INFO - 'base_work_dir': '/builds/slave/test', 19:40:34 INFO - 'blob_upload_branch': 'Firefox', 19:40:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:40:34 INFO - 'branch': 'Firefox', 19:40:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:40:34 INFO - 'config_files': ('talos/mac_config.py',), 19:40:34 INFO - 'default_actions': ('clobber', 19:40:34 INFO - 'read-buildbot-config', 19:40:34 INFO - 'download-and-extract', 19:40:34 INFO - 'populate-webroot', 19:40:34 INFO - 'create-virtualenv', 19:40:34 INFO - 'install', 19:40:34 INFO - 'run-tests'), 19:40:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:40:34 INFO - 'download_minidump_stackwalk': True, 19:40:34 INFO - 'download_symbols': 'ondemand', 19:40:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:40:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:40:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:40:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:40:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:40:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:40:34 INFO - 'installer_path': 'installer.exe', 19:40:34 INFO - 'log_level': 'info', 19:40:34 INFO - 'log_name': 'talos', 19:40:34 INFO - 'log_to_console': True, 19:40:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:40:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:40:34 INFO - 'opt_config_files': (), 19:40:34 INFO - 'pip_index': False, 19:40:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:40:34 INFO - 'cmd': ('bash', 19:40:34 INFO - '-c', 19:40:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:40:34 INFO - 'enabled': True, 19:40:34 INFO - 'halt_on_failure': False, 19:40:34 INFO - 'name': 'check_screen_resolution'},), 19:40:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:40:34 INFO - 'cmd': ('bash', 19:40:34 INFO - '-c', 19:40:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:40:34 INFO - 'enabled': True, 19:40:34 INFO - 'halt_on_failure': False, 19:40:34 INFO - 'name': 'check_screen_resolution'},), 19:40:34 INFO - 'run_cmd_checks_enabled': True, 19:40:34 INFO - 'sps_profile': False, 19:40:34 INFO - 'sps_profile_interval': 0, 19:40:34 INFO - 'suite': 'dromaeojs', 19:40:34 INFO - 'system_bits': '32', 19:40:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:40:34 INFO - 'title': 't-yosemite-r7-0382', 19:40:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:40:34 INFO - 'use_talos_json': True, 19:40:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:40:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:40:34 INFO - 'work_dir': 'build'} 19:40:34 INFO - [mozharness: 2016-08-26 02:40:34.899601Z] Running clobber step. 19:40:34 INFO - Running pre-action listener: _resource_record_pre_action 19:40:34 INFO - Running main action method: clobber 19:40:34 INFO - rmtree: /builds/slave/test/build 19:40:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:40:36 INFO - Running post-action listener: _resource_record_post_action 19:40:36 INFO - [mozharness: 2016-08-26 02:40:36.355365Z] Finished clobber step (success) 19:40:36 INFO - [mozharness: 2016-08-26 02:40:36.355641Z] Running read-buildbot-config step. 19:40:36 INFO - Running pre-action listener: _resource_record_pre_action 19:40:36 INFO - Running main action method: read_buildbot_config 19:40:36 INFO - Using buildbot properties: 19:40:36 INFO - { 19:40:36 INFO - "project": "", 19:40:36 INFO - "product": "firefox", 19:40:36 INFO - "script_repo_revision": "production", 19:40:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:40:36 INFO - "repository": "", 19:40:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:40:36 INFO - "buildid": "20160825170018", 19:40:36 INFO - "pgo_build": "False", 19:40:36 INFO - "basedir": "/builds/slave/test", 19:40:36 INFO - "buildnumber": 43, 19:40:36 INFO - "slavename": "t-yosemite-r7-0382", 19:40:36 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:40:36 INFO - "platform": "yosemite_r7", 19:40:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:40:36 INFO - "branch": "mozilla-central", 19:40:36 INFO - "repo_path": "mozilla-central", 19:40:36 INFO - "revision": "8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253", 19:40:36 INFO - "stage_platform": "macosx64", 19:40:36 INFO - "builduid": "fd1fa71f8550462d955c6df4a7961fdf", 19:40:36 INFO - "slavebuilddir": "test" 19:40:36 INFO - } 19:40:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:40:36 INFO - Running post-action listener: _resource_record_post_action 19:40:36 INFO - [mozharness: 2016-08-26 02:40:36.361206Z] Finished read-buildbot-config step (success) 19:40:36 INFO - [mozharness: 2016-08-26 02:40:36.361390Z] Running download-and-extract step. 19:40:36 INFO - Running pre-action listener: _resource_record_pre_action 19:40:36 INFO - Running main action method: download_and_extract 19:40:36 INFO - mkdir: /builds/slave/test/build/tests 19:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:40:36 INFO - trying https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:40:36 INFO - Downloading https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:37 INFO - Downloaded 1199 bytes. 19:40:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:40:37 INFO - Using the following test package requirements: 19:40:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:40:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:40:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:40:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'jsshell-mac.zip'], 19:40:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:40:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:40:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:40:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:40:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:40:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:40:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:40:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:40:37 INFO - trying https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:40:37 INFO - Downloading https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:39 INFO - Downloaded 16365534 bytes. 19:40:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:40:46 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 19:40:46 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')]} 19:40:46 INFO - trying https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:40:46 INFO - Downloading https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:47 INFO - Downloaded 16365534 bytes. 19:40:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:40:53 INFO - trying https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:40:53 INFO - Downloading https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:40:56 INFO - Downloaded 14413981 bytes. 19:40:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:40:56 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')]} 19:40:56 INFO - trying https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:40:56 INFO - Downloading https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:40:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/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 19:41:00 INFO - Downloaded 111826777 bytes. 19:41:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:41:00 INFO - mkdir: /builds/slave/test/properties 19:41:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:41:00 INFO - Writing to file /builds/slave/test/properties/build_url 19:41:00 INFO - Contents: 19:41:00 INFO - build_url:https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:41:00 INFO - Running post-action listener: _resource_record_post_action 19:41:00 INFO - Running post-action listener: set_extra_try_arguments 19:41:00 INFO - [mozharness: 2016-08-26 02:41:00.806513Z] Finished download-and-extract step (success) 19:41:00 INFO - [mozharness: 2016-08-26 02:41:00.806678Z] Running populate-webroot step. 19:41:00 INFO - Running pre-action listener: _resource_record_pre_action 19:41:00 INFO - Running main action method: populate_webroot 19:41:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:41:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:41:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:41:00 INFO - '--tppagecycles', 19:41:00 INFO - '7'], 19:41:00 INFO - 'tests': ['tsvgm']}}, 19:41:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:41:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:41:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:41:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:41:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:41:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:41:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['damp', 'tps']}, 19:41:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'tests': ['damp', 'tps']}, 19:41:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['dromaeo_dom']}, 19:41:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:41:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['basic_compositor_video']}, 19:41:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:41:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['a11yr', 19:41:00 INFO - 'ts_paint', 19:41:00 INFO - 'tpaint', 19:41:00 INFO - 'sessionrestore', 19:41:00 INFO - 'sessionrestore_no_auto_restore', 19:41:00 INFO - 'tabpaint']}, 19:41:00 INFO - 'other-e10s': {'tests': ['a11yr', 19:41:00 INFO - 'ts_paint', 19:41:00 INFO - 'tpaint', 19:41:00 INFO - 'sessionrestore', 19:41:00 INFO - 'sessionrestore_no_auto_restore', 19:41:00 INFO - 'tabpaint']}, 19:41:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:41:00 INFO - 'ts_paint', 19:41:00 INFO - 'tpaint', 19:41:00 INFO - 'sessionrestore', 19:41:00 INFO - 'sessionrestore_no_auto_restore', 19:41:00 INFO - 'tabpaint']}, 19:41:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:41:00 INFO - 'ts_paint', 19:41:00 INFO - 'tpaint', 19:41:00 INFO - 'sessionrestore', 19:41:00 INFO - 'sessionrestore_no_auto_restore', 19:41:00 INFO - 'tabpaint']}, 19:41:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['a11yr', 19:41:00 INFO - 'ts_paint', 19:41:00 INFO - 'tpaint', 19:41:00 INFO - 'sessionrestore', 19:41:00 INFO - 'sessionrestore_no_auto_restore', 19:41:00 INFO - 'tabpaint']}, 19:41:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['a11yr', 19:41:00 INFO - 'ts_paint', 19:41:00 INFO - 'tpaint', 19:41:00 INFO - 'sessionrestore', 19:41:00 INFO - 'sessionrestore_no_auto_restore', 19:41:00 INFO - 'tabpaint']}, 19:41:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['tsvgx', 19:41:00 INFO - 'tsvgr_opacity', 19:41:00 INFO - 'tart', 19:41:00 INFO - 'tscrollx', 19:41:00 INFO - 'cart']}, 19:41:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:41:00 INFO - 'tsvgr_opacity', 19:41:00 INFO - 'tart', 19:41:00 INFO - 'tscrollx', 19:41:00 INFO - 'cart']}, 19:41:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'talos_options': ['--disable-e10s'], 19:41:00 INFO - 'tests': ['tp5o']}, 19:41:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'tests': ['tp5o']}, 19:41:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'talos_options': ['--disable-e10s', 19:41:00 INFO - '--xperf_path', 19:41:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:41:00 INFO - 'tests': ['tp5n']}, 19:41:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:00 INFO - 'talos_options': ['--xperf_path', 19:41:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:41:00 INFO - 'tests': ['tp5n']}}, 19:41:00 INFO - 'talos.zip': {'path': '', 19:41:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:41:00 INFO - Running post-action listener: _resource_record_post_action 19:41:00 INFO - [mozharness: 2016-08-26 02:41:00.825234Z] Finished populate-webroot step (success) 19:41:00 INFO - [mozharness: 2016-08-26 02:41:00.825365Z] Running create-virtualenv step. 19:41:00 INFO - Running pre-action listener: _resource_record_pre_action 19:41:00 INFO - Running main action method: create_virtualenv 19:41:00 INFO - Creating virtualenv /builds/slave/test/build/venv 19:41:00 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 19:41:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:41:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:41:00 INFO - Using real prefix '/tools/python27' 19:41:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:41:01 INFO - Installing distribute.............................................................................................................................................................................................done. 19:41:04 INFO - Installing pip.................done. 19:41:04 INFO - Return code: 0 19:41:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:41:04 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')]} 19:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:04 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')]} 19:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:04 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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:04 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 19:41:04 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 19:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:04 INFO - 'HOME': '/Users/cltbld', 19:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:04 INFO - 'LOGNAME': 'cltbld', 19:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:04 INFO - 'MOZ_NO_REMOTE': '1', 19:41:04 INFO - 'NO_EM_RESTART': '1', 19:41:04 INFO - 'PAGER': '/bin/cat', 19:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:04 INFO - 'PWD': '/builds/slave/test', 19:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:04 INFO - 'SHELL': '/bin/bash', 19:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:04 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:04 INFO - 'USER': 'cltbld', 19:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:04 INFO - 'XPC_FLAGS': '0x0', 19:41:04 INFO - 'XPC_SERVICE_NAME': '0', 19:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:04 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 19:41:04 INFO - Cleaning up... 19:41:04 INFO - Return code: 0 19:41:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:41:04 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')]} 19:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:04 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')]} 19:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:04 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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:04 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 19:41:04 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 19:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:04 INFO - 'HOME': '/Users/cltbld', 19:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:04 INFO - 'LOGNAME': 'cltbld', 19:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:04 INFO - 'MOZ_NO_REMOTE': '1', 19:41:04 INFO - 'NO_EM_RESTART': '1', 19:41:04 INFO - 'PAGER': '/bin/cat', 19:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:04 INFO - 'PWD': '/builds/slave/test', 19:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:04 INFO - 'SHELL': '/bin/bash', 19:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:04 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:04 INFO - 'USER': 'cltbld', 19:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:04 INFO - 'XPC_FLAGS': '0x0', 19:41:04 INFO - 'XPC_SERVICE_NAME': '0', 19:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:05 INFO - Downloading/unpacking psutil>=3.1.1 19:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:41:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:41:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:41:07 INFO - Installing collected packages: psutil 19:41:07 INFO - Running setup.py install for psutil 19:41:07 INFO - building 'psutil._psutil_osx' extension 19:41:07 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 19:41:08 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 19:41:08 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 19:41:08 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 19:41:08 INFO - building 'psutil._psutil_posix' extension 19:41:08 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 19:41:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:41:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:41:08 INFO - ^ 19:41:08 INFO - 1 warning generated. 19:41:08 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 19:41:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:41:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:41:08 INFO - Successfully installed psutil 19:41:08 INFO - Cleaning up... 19:41:08 INFO - Return code: 0 19:41:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:08 INFO - 'HOME': '/Users/cltbld', 19:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:08 INFO - 'LOGNAME': 'cltbld', 19:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:08 INFO - 'MOZ_NO_REMOTE': '1', 19:41:08 INFO - 'NO_EM_RESTART': '1', 19:41:08 INFO - 'PAGER': '/bin/cat', 19:41:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:08 INFO - 'PWD': '/builds/slave/test', 19:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:08 INFO - 'SHELL': '/bin/bash', 19:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:08 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:08 INFO - 'USER': 'cltbld', 19:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:08 INFO - 'XPC_FLAGS': '0x0', 19:41:08 INFO - 'XPC_SERVICE_NAME': '0', 19:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:41:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:41:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:41:11 INFO - Installing collected packages: mozsystemmonitor 19:41:11 INFO - Running setup.py install for mozsystemmonitor 19:41:11 INFO - Successfully installed mozsystemmonitor 19:41:11 INFO - Cleaning up... 19:41:11 INFO - Return code: 0 19:41:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:11 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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:11 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 19:41:11 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 19:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:11 INFO - 'HOME': '/Users/cltbld', 19:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:11 INFO - 'LOGNAME': 'cltbld', 19:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:11 INFO - 'MOZ_NO_REMOTE': '1', 19:41:11 INFO - 'NO_EM_RESTART': '1', 19:41:11 INFO - 'PAGER': '/bin/cat', 19:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:11 INFO - 'PWD': '/builds/slave/test', 19:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:11 INFO - 'SHELL': '/bin/bash', 19:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:11 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:11 INFO - 'USER': 'cltbld', 19:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:11 INFO - 'XPC_FLAGS': '0x0', 19:41:11 INFO - 'XPC_SERVICE_NAME': '0', 19:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:11 INFO - Downloading/unpacking jsonschema==2.5.1 19:41:11 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 19:41:11 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 19:41:11 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 19:41:11 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 19:41:11 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 19:41:11 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 19:41:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:41:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:41:14 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 19:41:14 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 19:41:14 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 19:41:14 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 19:41:14 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 19:41:14 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 19:41:14 INFO - Downloading functools32-3.2.3-2.tar.gz 19:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:41:14 INFO - warning: no files found matching '*.txt' 19:41:14 INFO - no previously-included directories found matching 'build' 19:41:14 INFO - no previously-included directories found matching 'dist' 19:41:14 INFO - no previously-included directories found matching '.git*' 19:41:14 INFO - Installing collected packages: jsonschema, functools32 19:41:14 INFO - Running setup.py install for functools32 19:41:14 INFO - warning: no files found matching '*.txt' 19:41:14 INFO - no previously-included directories found matching 'build' 19:41:14 INFO - no previously-included directories found matching 'dist' 19:41:14 INFO - no previously-included directories found matching '.git*' 19:41:14 INFO - Successfully installed jsonschema functools32 19:41:14 INFO - Cleaning up... 19:41:15 INFO - Return code: 0 19:41:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:41: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')]} 19:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41: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')]} 19:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:15 INFO - 'HOME': '/Users/cltbld', 19:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:15 INFO - 'LOGNAME': 'cltbld', 19:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:15 INFO - 'MOZ_NO_REMOTE': '1', 19:41:15 INFO - 'NO_EM_RESTART': '1', 19:41:15 INFO - 'PAGER': '/bin/cat', 19:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:15 INFO - 'PWD': '/builds/slave/test', 19:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:15 INFO - 'SHELL': '/bin/bash', 19:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:15 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:15 INFO - 'USER': 'cltbld', 19:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:15 INFO - 'XPC_FLAGS': '0x0', 19:41:15 INFO - 'XPC_SERVICE_NAME': '0', 19:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:41:15 INFO - Cleaning up... 19:41:15 INFO - Return code: 0 19:41:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:41: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')]} 19:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41: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')]} 19:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:15 INFO - 'HOME': '/Users/cltbld', 19:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:15 INFO - 'LOGNAME': 'cltbld', 19:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:15 INFO - 'MOZ_NO_REMOTE': '1', 19:41:15 INFO - 'NO_EM_RESTART': '1', 19:41:15 INFO - 'PAGER': '/bin/cat', 19:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:15 INFO - 'PWD': '/builds/slave/test', 19:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:15 INFO - 'SHELL': '/bin/bash', 19:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:15 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:15 INFO - 'USER': 'cltbld', 19:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:15 INFO - 'XPC_FLAGS': '0x0', 19:41:15 INFO - 'XPC_SERVICE_NAME': '0', 19:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:15 INFO - Downloading/unpacking blobuploader==1.2.4 19:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:19 INFO - Downloading blobuploader-1.2.4.tar.gz 19:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:41:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:41:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:20 INFO - Downloading docopt-0.6.1.tar.gz 19:41:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:41:20 INFO - Installing collected packages: blobuploader, requests, docopt 19:41:20 INFO - Running setup.py install for blobuploader 19:41:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:41:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:41:20 INFO - Running setup.py install for requests 19:41:20 INFO - Running setup.py install for docopt 19:41:21 INFO - Successfully installed blobuploader requests docopt 19:41:21 INFO - Cleaning up... 19:41:21 INFO - Return code: 0 19:41:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:21 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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:21 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 19:41:21 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 19:41:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:21 INFO - 'HOME': '/Users/cltbld', 19:41:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:21 INFO - 'LOGNAME': 'cltbld', 19:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:21 INFO - 'MOZ_NO_REMOTE': '1', 19:41:21 INFO - 'NO_EM_RESTART': '1', 19:41:21 INFO - 'PAGER': '/bin/cat', 19:41:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:21 INFO - 'PWD': '/builds/slave/test', 19:41:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:21 INFO - 'SHELL': '/bin/bash', 19:41:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:21 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:21 INFO - 'USER': 'cltbld', 19:41:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:21 INFO - 'XPC_FLAGS': '0x0', 19:41:21 INFO - 'XPC_SERVICE_NAME': '0', 19:41:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:41:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-3rL_Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:41:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QpQszD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:41:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-J7L5Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:41:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-peNQTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:41:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0mT_Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:41:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-g7GT_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-VT9SD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0MDQWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-jpcO_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-GdNQ0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-I8qrY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6u3cTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-rzk2c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-gd8B7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-wChN3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:41:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-xg3NjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:41:23 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QvHmun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:41:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:41:23 INFO - Running setup.py install for manifestparser 19:41:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:41:23 INFO - Running setup.py install for mozcrash 19:41:23 INFO - Running setup.py install for mozdebug 19:41:23 INFO - Running setup.py install for mozdevice 19:41:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:41:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:41:23 INFO - Running setup.py install for mozfile 19:41:23 INFO - Running setup.py install for mozhttpd 19:41:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Running setup.py install for mozinfo 19:41:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Running setup.py install for mozInstall 19:41:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Running setup.py install for mozleak 19:41:24 INFO - Running setup.py install for mozlog 19:41:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Running setup.py install for moznetwork 19:41:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:41:24 INFO - Running setup.py install for mozprocess 19:41:24 INFO - Running setup.py install for mozprofile 19:41:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:41:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:41:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:41:25 INFO - Running setup.py install for mozrunner 19:41:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:41:25 INFO - Running setup.py install for mozscreenshot 19:41:25 INFO - Running setup.py install for moztest 19:41:25 INFO - Running setup.py install for mozversion 19:41:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:41:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:41:25 INFO - Cleaning up... 19:41:25 INFO - Return code: 0 19:41:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:41: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')]} 19:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41: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')]} 19:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:25 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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:25 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 19:41:25 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 19:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:25 INFO - 'HOME': '/Users/cltbld', 19:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:25 INFO - 'LOGNAME': 'cltbld', 19:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:25 INFO - 'MOZ_NO_REMOTE': '1', 19:41:25 INFO - 'NO_EM_RESTART': '1', 19:41:25 INFO - 'PAGER': '/bin/cat', 19:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:25 INFO - 'PWD': '/builds/slave/test', 19:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:25 INFO - 'SHELL': '/bin/bash', 19:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:25 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:25 INFO - 'USER': 'cltbld', 19:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:25 INFO - 'XPC_FLAGS': '0x0', 19:41:25 INFO - 'XPC_SERVICE_NAME': '0', 19:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-qfGdg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-TmREVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-qhHCyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-VzKx7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pXK4Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-An3pT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6ZEBbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-D5mXNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Zda_L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:41:26 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)) 19:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:41:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-uz5_Az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0hnOnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pxGd44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-qBPKhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6QaKzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4bUzKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8JZ6n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:41:27 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)) 19:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:41:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-zd4GVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:41:27 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)) 19:41:27 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)) 19:41:27 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)) 19:41:27 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)) 19:41:27 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)) 19:41:27 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)) 19:41:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:30 INFO - Downloading blessings-1.6.tar.gz 19:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:41:30 INFO - Installing collected packages: blessings 19:41:30 INFO - Running setup.py install for blessings 19:41:30 INFO - Successfully installed blessings 19:41:30 INFO - Cleaning up... 19:41:30 INFO - Return code: 0 19:41:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:41:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:41:30 INFO - Reading from file tmpfile_stdout 19:41:30 INFO - Current package versions: 19:41:30 INFO - blessings == 1.6 19:41:30 INFO - blobuploader == 1.2.4 19:41:30 INFO - docopt == 0.6.1 19:41:30 INFO - functools32 == 3.2.3-2 19:41:30 INFO - jsonschema == 2.5.1 19:41:30 INFO - manifestparser == 1.1 19:41:30 INFO - mozInstall == 1.12 19:41:30 INFO - mozcrash == 0.17 19:41:30 INFO - mozdebug == 0.1 19:41:30 INFO - mozdevice == 0.48 19:41:30 INFO - mozfile == 1.2 19:41:30 INFO - mozhttpd == 0.7 19:41:30 INFO - mozinfo == 0.9 19:41:30 INFO - mozleak == 0.1 19:41:30 INFO - mozlog == 3.3 19:41:30 INFO - moznetwork == 0.27 19:41:30 INFO - mozprocess == 0.23 19:41:30 INFO - mozprofile == 0.28 19:41:30 INFO - mozrunner == 6.12 19:41:30 INFO - mozscreenshot == 0.1 19:41:30 INFO - mozsystemmonitor == 0.3 19:41:30 INFO - moztest == 0.7 19:41:30 INFO - mozversion == 1.4 19:41:30 INFO - psutil == 3.1.1 19:41:30 INFO - requests == 1.2.3 19:41:30 INFO - wsgiref == 0.1.2 19:41:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:30 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 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:30 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 19:41:30 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 19:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:30 INFO - 'HOME': '/Users/cltbld', 19:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:30 INFO - 'LOGNAME': 'cltbld', 19:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:30 INFO - 'MOZ_NO_REMOTE': '1', 19:41:30 INFO - 'NO_EM_RESTART': '1', 19:41:30 INFO - 'PAGER': '/bin/cat', 19:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:30 INFO - 'PWD': '/builds/slave/test', 19:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:30 INFO - 'SHELL': '/bin/bash', 19:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:30 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:30 INFO - 'USER': 'cltbld', 19:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:30 INFO - 'XPC_FLAGS': '0x0', 19:41:30 INFO - 'XPC_SERVICE_NAME': '0', 19:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 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)) 19:41:31 INFO - Cleaning up... 19:41:31 INFO - Return code: 0 19:41:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcad3488f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, '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.6c5IUpdCNe/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.WYOM5CvviE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 19:41:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:41:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:41:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:31 INFO - 'HOME': '/Users/cltbld', 19:41:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:31 INFO - 'LOGNAME': 'cltbld', 19:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:31 INFO - 'MOZ_NO_REMOTE': '1', 19:41:31 INFO - 'NO_EM_RESTART': '1', 19:41:31 INFO - 'PAGER': '/bin/cat', 19:41:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:31 INFO - 'PWD': '/builds/slave/test', 19:41:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:31 INFO - 'SHELL': '/bin/bash', 19:41:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:41:31 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:41:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:31 INFO - 'USER': 'cltbld', 19:41:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:31 INFO - 'XPC_FLAGS': '0x0', 19:41:31 INFO - 'XPC_SERVICE_NAME': '0', 19:41:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:41:31 INFO - Cleaning up... 19:41:31 INFO - Return code: 0 19:41:31 INFO - Running post-action listener: _resource_record_post_action 19:41:31 INFO - Running post-action listener: _start_resource_monitoring 19:41:31 INFO - Starting resource monitoring. 19:41:31 INFO - [mozharness: 2016-08-26 02:41:31.421258Z] Finished create-virtualenv step (success) 19:41:31 INFO - [mozharness: 2016-08-26 02:41:31.422259Z] Running install step. 19:41:31 INFO - Running pre-action listener: _resource_record_pre_action 19:41:31 INFO - Running main action method: install 19:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:41:31 INFO - Reading from file tmpfile_stdout 19:41:31 INFO - Detecting whether we're running mozinstall >=1.0... 19:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:41:31 INFO - Reading from file tmpfile_stdout 19:41:31 INFO - Output received: 19:41:31 INFO - Usage: mozinstall [options] installer 19:41:31 INFO - Options: 19:41:31 INFO - -h, --help show this help message and exit 19:41:31 INFO - -d DEST, --destination=DEST 19:41:31 INFO - Directory to install application into. [default: 19:41:31 INFO - "/builds/slave/test"] 19:41:31 INFO - --app=APP Application being installed. [default: firefox] 19:41:31 INFO - mkdir: /builds/slave/test/build/application 19:41:31 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'] 19:41:31 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 19:42:05 INFO - Reading from file tmpfile_stdout 19:42:05 INFO - Output received: 19:42:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:42:05 INFO - Running post-action listener: _resource_record_post_action 19:42:05 INFO - [mozharness: 2016-08-26 02:42:05.751887Z] Finished install step (success) 19:42:05 INFO - [mozharness: 2016-08-26 02:42:05.752026Z] Running run-tests step. 19:42:05 INFO - Running pre-action listener: _resource_record_pre_action 19:42:05 INFO - Running main action method: run_tests 19:42:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:42:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:42:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:42:05 INFO - 2016-08-25 19:42:05.766 screenresolution[1941:15642] starting screenresolution argv=screenresolution get 19:42:05 INFO - 2016-08-25 19:42:05.769 screenresolution[1941:15642] Display 0: 1600x1200x32@60 19:42:05 INFO - 2016-08-25 19:42:05.775 screenresolution[1942:15644] starting screenresolution argv=screenresolution list 19:42:05 INFO - Available Modes on Display 0 19:42:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:42:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:42:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:42:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:42:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:42:06 INFO - Intel Iris: 19:42:06 INFO - Chipset Model: Intel Iris 19:42:06 INFO - Type: GPU 19:42:06 INFO - Bus: Built-In 19:42:06 INFO - VRAM (Dynamic, Max): 1536 MB 19:42:06 INFO - Vendor: Intel (0x8086) 19:42:06 INFO - Device ID: 0x0a2e 19:42:06 INFO - Revision ID: 0x0009 19:42:06 INFO - Displays: 19:42:06 INFO - Display: 19:42:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:42:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:42:06 INFO - Display Serial Number: 1600x1200 60 19:42:06 INFO - Main Display: Yes 19:42:06 INFO - Mirror: Off 19:42:06 INFO - Online: Yes 19:42:06 INFO - Rotation: Supported 19:42:06 INFO - Return code: 0 19:42:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:42:06 INFO - Python 2.7.3 19:42:06 INFO - Return code: 0 19:42:06 INFO - grabbing minidump binary from tooltool 19:42: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')]} 19:42:06 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 0x10851ed30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108516b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:42:06 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 19:42:06 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 19:42:06 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 19:42:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:42:06 INFO - Return code: 0 19:42:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:42:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:42:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:42:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:42:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:42:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 19:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 19:42:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6c5IUpdCNe/Render', 19:42:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:42:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:42:06 INFO - 'HOME': '/Users/cltbld', 19:42:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:42:06 INFO - 'LOGNAME': 'cltbld', 19:42:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:42:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:42:06 INFO - 'MOZ_NO_REMOTE': '1', 19:42:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:42:06 INFO - 'NO_EM_RESTART': '1', 19:42:06 INFO - 'PAGER': '/bin/cat', 19:42:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:42:06 INFO - 'PWD': '/builds/slave/test', 19:42:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:42:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:42:06 INFO - 'SHELL': '/bin/bash', 19:42:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WYOM5CvviE/Listeners', 19:42:06 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 19:42:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:42:06 INFO - 'USER': 'cltbld', 19:42:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:42:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:42:06 INFO - 'XPC_FLAGS': '0x0', 19:42:06 INFO - 'XPC_SERVICE_NAME': '0', 19:42:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:42:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 19:42:06 INFO - mozversion application_buildid: 20160825170018 19:42:06 INFO - mozversion application_changeset: 8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253 19:42:06 INFO - mozversion application_display_name: Nightly 19:42:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:42:06 INFO - mozversion application_name: Firefox 19:42:06 INFO - mozversion application_remotingname: firefox 19:42:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:42:06 INFO - mozversion application_vendor: Mozilla 19:42:06 INFO - mozversion application_version: 51.0a1 19:42:06 INFO - mozversion platform_buildid: 20160825170018 19:42:06 INFO - mozversion platform_changeset: 8f6cbb5a8f4a748cd9fd104e58e840ca6e86b253 19:42:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:42:06 INFO - mozversion platform_version: 51.0a1 19:42:06 INFO - using testdate: 1472179326 19:42:06 INFO - actual date: 1472179326 19:42:06 INFO - starting webserver on 'localhost:52471' 19:42:06 INFO - SUITE-START | Running 2 tests 19:42:06 INFO - TEST-START | dromaeo_css 19:42:06 INFO - operating with platform_type : mac_ 19:42:06 INFO - Initialising browser for dromaeo_css test... 19:42:06 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpU3pr4b/profile http://localhost:52471/getInfo.html) 19:42:08 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 19:42:08 INFO - PROCESS | 1952 | colorDepth:24 19:42:08 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 19:42:08 INFO - PROCESS | 1952 | __metrics 19:42:08 INFO - PROCESS | 1952 | 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" 19:42:08 INFO - PROCESS | 1952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:42:09 INFO - TEST-INFO | 1952: exit 0 19:42:09 INFO - Browser initialized. 19:42:09 INFO - Running cycle 1/1 for dromaeo_css test... 19:42:09 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpU3pr4b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:21 INFO - PROCESS | 1954 | RSS: Main: 233574400 19:42:21 INFO - PROCESS | 1954 | 19:43:12 INFO - PROCESS | 1954 | 1472179392427 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] 19:45:24 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52471/tests/dromaeo/cssquery-ext.html) 19:45:25 INFO - PROCESS | 1954 | RSS: Main: 380674048 19:45:25 INFO - PROCESS | 1954 | 19:48:29 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-ext.html (next: http://localhost:52471/tests/dromaeo/cssquery-jquery.html) 19:48:29 INFO - PROCESS | 1954 | RSS: Main: 370872320 19:48:29 INFO - PROCESS | 1954 | 19:51:32 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52471/tests/dromaeo/cssquery-mootools.html) 19:51:32 INFO - PROCESS | 1954 | RSS: Main: 346955776 19:51:32 INFO - PROCESS | 1954 | 19:54:35 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52471/tests/dromaeo/cssquery-prototype.html) 19:54:36 INFO - PROCESS | 1954 | RSS: Main: 334733312 19:54:36 INFO - PROCESS | 1954 | 19:57:39 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52471/tests/dromaeo/cssquery-yui.html) 19:57:40 INFO - PROCESS | 1954 | RSS: Main: 326639616 19:57:40 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-yui.html (next: http://localhost:52471/tests/dromaeo/cssquery-dojo.html) 20:00:43 INFO - PROCESS | 1954 | RSS: Main: 397529088 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | __start_tp_report 20:00:43 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 20:00:43 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 20:00:43 INFO - PROCESS | 1954 | |i|pagename|runs| 20:00:43 INFO - PROCESS | 1954 | |0;dojo.html;2763.378239896023;3309.1065412338685;3313.2710803623195;3370.0900756795663;3414.9658503414976;5642.351129620085;5711.971440142799;5752.453516915897;5804.332501762294;5851.707414629267;5704.344000439951;5747.051736463479;5785.566082543815;5846.181534585162;5907.734151963161;5151.793928242866;5197.48025197481;5238.52853243208;5240.580753539708;5383.838484845442;5356.892862142755;5375.865603359908;5381.165919282498;5384;5400.351957765074;4580.358749775034;5224.085784987612;5263.973525162595;5296.390915044762;5315.920261196085;5265.6840589564745;5269.736513174326;5296.523312901857;5369.301990741218;5393.568514518829;5243.632945693803;5288.973555132238;5310.309659744247;5318.25544423781;5342.332208473941;5687.573431992617;5755.251817263771;5765.509931655814;5875.148103524965;5960.314563825173;5546;6247.9062814057825;6302.306746257908;6303.968480157616;6352.110704501373;6020.217371741689;6247;6293.150424692653;6325.177726895474;6332.588381755171;5638.379778224392;5689.943100569024;5921.467067963904;5927.881442371128;5976.043832986745;6265.812025639147;6292.968535157386;6317.905231421532;6393.424591786761;6402.071699603532;6288.968555157195;6298.401651843067;6310.747570097236;6315.526335524854;6316.715747791361;6174.351693072253;6266.091416744546;6338.461230795434;6353.14232578596;6366.84082897922;6166.290876549165;6210.782622608187;6232.719527621268;6338.207724034496;6351.650659213788;6340.144080549159;6342.302346741854;6345.397187267202;6346.904796428057;6347;5054.595632349403;5068.062408454374;5068.822591209364;5085.644004919694;5122.9743851281255;4395.120975804852;4508.0533088051225;4542.068875880436;4568.132054909556;4575.17646823575;5756.827295181151;5776.1624564437925;5777.220075289866;5785.82642520725;5796.536277097822;6210.5031597473;6242.15730876335;6311.021791622214;6328.177336946167;6340.873182536324;5810.88378232433;5811;5817.563682723812;5842.532597392198;5882.529397648178;5474.206240095164;5484.890302193934;5505.504504594605;5506.889862202734;5514.255575497256;6250.374962503804;6294.244690637152;6297.779577714758;6329.082283068929;6602.60384376939;6276.152719382825;6333.778317758856;6340;6340.1440805490665;6342.619442833445;6430.871382572229;6448.516361272829;6459.6770161490795;6469.514786390945;6469.967650161719;6139.8601398601395;6143.020634991936;6181.443670069715;6194.81415557534;6254.8123556293385;6208.317085120543;6210.944139496387;6231.971724665379;6286.528510361833;6317.6841157941;6236.75052997875;6237.313895471404;6255.40573645503;6255.530835187288;6273.99616061428;5789.45000224978;6063.848403789764;6068.7269072892705;6119;6146.0473626587955;4931;6106.430331452127;6146.78486252979;6157.261128664589;6158.630482171084;5434.2120392542065;6226.813195604139;6243.469305109116;6247.219097612799;6262.3111457738705;5925.466707996128;6074.331823499323;6109.480694141048;6126.479249263863;6128.295246046761 20:00:43 INFO - PROCESS | 1954 | |1;ext.html;483.4029972983366;507.98730031749204;516.8707823044239;518.9853835751104;548.2571116137636;29872.103836884875;39924.600753992454;40322;40444.78665640028;42099.57900420995;41673.37489937653;42025;42089.368659470136;42112;42202;8525.275351595123;9510.952445237781;9678.080582344666;9686.9515652422;9707.75730606737;8628.65485380584;11558.13314001449;11790.35153066581;11802.46888890002;12086.274823510574;11510.424478776069;11537.134714896416;11543.65369038926;11577.53689852405;11650.184487085908;11744.706382340424;11849.763004739943;11864.762704745857;11871.881281187163;11872.584459543874;11665.241759285704;11729.53081876724;11749.295042297405;11750.23623464472;11757.35334556599;31720.841395793104;38851.805740971395;39126.217475650614;39240;39246.411303830464;2792.581112833071;3324.3517514084765;3408.642092580268;3423.4864770284407;3507.6141624421293;30531;31566.526502102486;31754.888578899627;31793.841030794698;32111.19722006945;2959.8224106553675;3045.284358175827;3091.721745042957;3127.5465057566753;3128.8435578221224;473096;473524;475023;476912;479453.6027319876;55642.88714225693;55724.72137639286;55863.44136558582;55871.44128558744;55916;58330.708346458;58423.83152336929;58447.27856296495;58463;58512.41487585155;15847.841521584867;16099;16101.355945762272;16108.516744497683;16109.838901611069;11321.547138114547;11569.942150289195;12450.75098498025;12589;12686.429110690042;14546.4908728194;15108.169050702318;15111.4710985115;15113.773293400607;15139.243037848284;5556.277683901067;6459.483241340681;6474.12599299098;6524.64114473699;6526.967365163144;845.5856630251255;901.9729408117767;924.7780532672244;947.9004704506066;1121.4897221764077;27574.724252757216;29223.269418264714;29260.853695731385;30022.549661755093;30178.396432071673;1039.9740006499899;1063.2663462211049;1083.34999000599;1101.752105776194;1143.7197886517856;14444.205568693822;15114.773278400833;15168;15206.771898421312;15557.377704891904;595504;600503;604851.9514804796;606107;607259;54773.45226547684;55716.16425753617;56035;58387.12419313714;58429.707851461324;1348.4067010515341;1384.9238291893842;1410.0482174532149;1411.567259231901;1413.8232720909887;29856.742927894004;30398.696013039586;30422.54366184553;30800.38399231958;31314.686853131177;48472.5152748468;48477.75761121172;48533.75733121312;48629.27056094091;48714.26928596145;473827;475146;478813;479422;480169;25093.372665683502;25679.871600641876;38236.42645360266;38396.42405363866;38527.6147238524;37045.44431833471;37838.810805945795;38095;44413.55586444094;47030.76484617555;32523.674763252064;33673;33778.15554611154;37318.44022339613;39122.80438597903;31421.685783141875;37711;37867;38959.27285090017;39115.413268800425;36474.08814779545;36754.63245367512;36966.07584810293;37111.81444092762;37639.247215054995;28420.715792841806;33222.66777332292;33418.331633367685;34194.82902585471;34285.82857085699;24038.038478460665;24746.50506989814;25150.24549263525;25397.746022539537;25517.87241063783 20:00:43 INFO - PROCESS | 1954 | |2;jquery.html;343.42819206021977;344.2564061626886;374.10215482841176;377.64312724475366;399.73817149766893;15319;16960.321587136514;20543;20993.790062099375;21158;21030.369088927317;21383;21412.036458359373;21451.249206277782;21699;5886.470217680408;5937.67342796146;6075.119107729377;6108.572399932006;6195.349488303731;5927.61470504418;6046.606970546923;6046.939530604704;6047.879042419149;6050.909236361458;4159.417681524589;6402.903956440658;6682.19813622366;6729.158855143107;6801.421879140279;5804;6217.751289948396;6718.49611279156;6758.797236082901;6765.492588055916;5812.941870581282;5818.854528636775;5824.563157763142;5826.271716035496;5830.717242206708;26320.473590528272;26662.466750665073;26982.650867456745;27184;27328.45343093147;2347.9060837566476;2530.5318516074585;2540.958207135084;2904.7385735283924;3116.329989052342;15501.069935803887;15968.281427335798;16133;16170.191490425432;16185;1650.867930565552;1788.856891448681;1862.6926557118195;1876.315144972089;1877.4836919847014;114617.85382146155;115843.42078289554;116889;117974;118761;8299.336053115736;8976.730698078938;10377.481125943672;11112.944435277772;11264.54941802335;11399.601013964471;13799.724005520035;13823;13846.584602461942;13863.4454621814;8374.958125209336;8664.133586641412;8697.739067827975;8855.557222138867;8905.376623636283;8547.74356769298;8580;8616.095309992486;8763;8888.288936885034;6556.213254409501;6644.435223006002;6754.290799466084;6761.966190169018;6773.966130169318;1780.2522940364818;1812.4834422189613;1812.8912265264125;1816.6729988602178;1818.8726789124898;860.8493513635088;884.9292056635454;911.9863202051974;943.9764005899771;1036.7770929250248;22023.559528809656;22363.329100127023;22820;22858.314250572508;23212.651810222862;887.1660638999319;915.3912648088947;917.7705573606598;925.2043242811311;935.1677007463227;9600.951995239979;11014.408761166143;11874.821877671842;12040.337781421931;12054.684961635621;553067.2346638321;555992;566490;566588;571051;117764;118320.18178473151;119298;120390.79609203972;121367;990.5988074829561;991.9107280344804;1024.0936770957755;1026.1585499890168;1027.6146445083093;19542.60914781725;20012.768421416855;20056.679759359733;20071.29750458727;20166.193352265745;52692.473075268754;53167.76834114697;53490.465095350104;54711.632209195086;55049.1742623869;74900;75144.49711005858;75796.62101689457;75865.482690347;77604.13312793188;5383.488568585978;6507.381798729113;7102.786916392516;7927.484713493604;7969.282764551218;9969.950150249202;12932.224066556037;13200.009999250287;13655.931720341334;13699.931500342434;11163.106951444028;11409.439378336478;11610.129240306842;11640.126990475917;11654;11312.20814542974;11439.485223164811;11462.369569673749;11536.711582210512;11757.88242117602;11257.71855703614;11279.379634120201;11286.322820630789;11719.88280117188;11816.82274765862;7626.504277221963;7812.92187078122;7980.680772769257;7984;7985.5208687478935;11172.329660220414;11347;11378.715532111764;11509.827352589551;11561.653150405502 20:00:43 INFO - PROCESS | 1954 | |3;mootools.html;1315.572438957339;1512.0398546922704;1517.355124072269;1712.837280458356;1886.9150888210029;7681.078270607528;8337.291330236929;8687.478751274912;8697.739067827959;8769.649214031431;8508.702195423162;8601.741947741562;8611.397202195849;8612.56937153143;8631.91368086319;2340.391498210469;2343.3790045637875;2382.821288403368;2383.1063351243283;2384.618461046233;1988.1749074134218;2318.7913087822094;2359.23324919401;2377.6909001829736;2379.8929048192786;2340.754220806816;2359.1978727232736;2363.61000434928;2371.5968285391436;2384.606539920911;2316.328264803205;2341.613633750429;2347.4131467133216;2349.788519033278;2352.388379021458;2324.035525257016;2329.2546385156757;2330.5804955108074;2336.439254578906;2339.9938026648533;8485.95757021211;8627.439216450974;8631.525266110362;8708.69519566812;8709.303255739589;5147.356580427447;5327.600429967729;5350.759215835296;5353.705546194958;5360.249565060894;3542.2030043240325;3568.411212149992;3596.1728802375337;3627.2020155565733;3630.364686179908;914.2274777812737;1050.369778133114;1054.7152268887435;1064.8455973883742;1067.8344856547249;11306.830397544043;11362.94318528402;11405.657830265105;11434;11434;4343.4136391586735;4390.4511936008;4553.362529245908;4564.246899261651;4577.931331030038;8965.058668839809;8965.596548155349;8978.057303983118;8997.730068097837;8999.82000359989;5185.22221666753;5191.870203244874;5201.063808514504;5214.227162764602;5216.947830521646;4319.416878721395;4331.97834010828;4462.709923854939;4845.248986407112;4885.316055752198;3177.4280629486916;3667.8716244931297;3680.282344942764;3807.162424266712;3853.9807300963316;1146.0602306109076;1152.2107356460851;1155.5608868629868;1166.3351889422947;1166.7899778039864;759.4645774728914;867.9479231246146;896.6234181643595;905.298393744853;918.9678361257457;5663.122216056518;5816.680082595498;5933.050711886078;6132.6627035513475;6141.785037523665;841.4614646626164;899.8425275576748;963.8987906269881;971.3977334052933;1012.7265638277623;3893.474380958591;3912.8434862605177;3969.4839670843185;4065.2682517146604;4080.429918491309;9857.802843943224;10442.895571044344;10616.203784716177;10628.468576571295;10673.573057077785;10274.023967723055;10582.682519524427;10584.89415105839;10590.735231619272;10613.84079238812;7952.085510166289;8203.794905127419;8217;8223.01323841143;8228.876566851503;5710.17202505626;5772.134179873053;5918.496927761016;5953.523718102455;6002.909956350745;9933;9971.800563988534;9982.301238913205;10026.398416095057;10037.49812509357;9745.951270243604;10088.092071713581;10204.591816327264;10235.539400726837;10238.027387398482;6436.420722135031;6633.701483433354;6795.762148324785;6915.861682766417;7050.894236586353;8163.591820409075;8251.2986396157;8395.916040839758;8400.285975692135;8412.896775806048;6150.661713605795;6229.626222426669;7342.963285183753;7395.075615548057;7541.6229188539255;7306.926930730625;7308.159561650478;7314.96342518284;7370.96314518424;7403.851922961403;5970.820875373745;6768.661566921536;6906.274841141608;6998.230194678683;7011.579305241702;4038.4548086008012;4931.9753401232765;4993.950060499494;5055.241713742968;5131.923021154611;4121.196366708463;4935.062338155883;5031.823886163967;5090.490950905028;5097.8215762448135 20:00:43 INFO - PROCESS | 1954 | |4;prototype.html;213.7595205393933;253.42851869035323;303.5780265431051;303.60076499403294;313.95453141040326;5368.409474957756;5450.482204190605;5454.59090568208;5467.168498552985;5475.972620136904;5053.570446512051;5128.948710512894;5301;5376.596755243353;5448.564114870811;3363.360961417323;4052.635262826345;4091.2022155679656;4117;4122.793860306981;3410.539577157077;4085.387191921206;4086.9169670037463;4103.1793641271715;4103.4870641169855;3810.9047273818237;3969.1466334738025;4044.47421835161;4070.816813243396;4088.9386659200136;3419;3726.981365093184;3736.2153947670886;3845.7501312073687;4045.3325201341745;3491.5460990071383;3493.3537295600395;3508.6491350864962;3514.5255390522207;3523.7181025518153;4944.554990050877;5234.42421333653;5261.894762104775;5302.522772950414;5322.7072511011875;3841.442990766351;4543.45478542577;4637.837675681369;4642.535746425364;4656.930146047812;4717.0330082333385;5291.814786482493;5670.865826834608;5696.3733989261145;5714.257146570919;3634.818259087035;3815.8855234342736;3843.865464708722;3854.7687138771485;3872.1287710265246;4859.246816743411;5396.244525766396;5407.702576358299;5541.418151094158;5866.91724437827;3845.653891149754;3853.9229215416094;3860.208657225262;3868.6905047596124;3882.6893848492055;3732.0856390184576;3915.197384536163;3927.3716205407;3939.4878665773267;3962.187751510933;3859.86134090444;4352.8349782807;4559.3844830948065;4585.28928016158;4623.630109591225;4951.009798040406;5653.717314134276;5697.401772813878;5718.713289509827;5883.735231914574;1186.7064899259676;1186.8991135753386;1251.787196176652;1266.208619612742;1309.476209516182;761.672480833247;866.4324867211986;890.701614958996;892.6474042753076;902.6660135749814;4460.881081486268;4905.583025442806;5166.276721259027;5227.921581176285;5273.703935040599;4695.389599352063;5684.687342196136;5770.394108618535;5802.1296805479515;5878.059510478303;4548.727076375428;4963.950360496421;5187.896242075138;5266.2890509781455;5344.877575709144;2612.555865502869;2831.697419187156;2967.1543610071026;2991.848138466706;3008.8946886858853;5057.848264552069;5502.862428439241;5738.311402631711;5823.359430462646;5835.766569337179;5392.110301800159;5786.334571524244;5800.796972105956;5802.3037235531165;5840.474357307863;5098.796048158107;5346.358436987586;5790.536757059425;5888.587798854211;5909.084091965667;4884.584810291165;5274.072889546185;5542.501174894118;5600.299962504687;5681.818181818195;4295;4708.458527269408;5019.84940451787;5611.5230205433;5629.0712032514175;4573.314002899591;5598.776048957996;5887.735051922759;5897.970510147422;5983.521318294439;3228.9021732611027;3646.392829127187;3818.312703713358;3849.230153969161;3893.279743247508;5682.43175682445;5692.573057020657;5713.485926229636;5713.485926229636;5715.457031581993;4857.198562237332;5508.3114610673665;5547.611667183259;5564.638298510584;5572.498475137257;4387.890302742457;4816;4941.728204948762;5291.1798671206025;5504.80733174337;4996.975015124901;5235.424103348552;5404.648697834628;5423;5522.530584900168;4543.568361005699;5066.746662666926;5509.933774834437;5620.606557541097;5678.971605141948;5470.72646367672;5531.861703457445;5578.274824272981;5584.944150558443;5592.636478628876 20:00:43 INFO - PROCESS | 1954 | |5;yui.html;655.8524332025295;735.7351353512735;741.90355253817;750.4409215134725;766.4136935244538;13869.445222191112;14357.425702971868;14472.63818404539;14904.18027008514;14933.551993440187;12892;13227.20636761795;13227.272500012496;13238.470461181565;13257.270850103241;844.311885813062;868.7784614923186;869.2871845087031;869.4739682492065;869.9864657673802;674.8582797612507;696.3523922751839;702.7751119641716;703.152700995302;869.1135042256894;724.8550289942007;885.5218182181615;914.0448231597996;916.1662886773038;925.4863550729342;913.3332667152949;914.1498406481969;915.56053094515;915.6795121707415;916.1617120334886;672.7813460625267;673.8820706376365;673.9191297044387;675.7702381190419;676.1716896801379;11662.475188616449;12135.817962730565;12145.939270303592;12148.878511214863;12152.57465988686;10387.857335693068;12688.936555317257;12700.936495317557;13170.736585268336;13459.057865949388;10925.726856828564;11094.223404361697;11207.189555551151;11456.369899655516;11579.710507237301;3306.8511916963794;3343.782654127474;3386.6782655647676;3466.046837119787;3466.0641626760885;75332;109599.45200274028;114590;116521;117441;10162.695119146438;10178;10380.844287335545;10620;10625.096866766256;20435.182592696125;20754.58490830175;20795.68806467904;20823.375298741063;20836.583268334547;7142.250063743336;7190.7483238086415;7364.2635736427;7436.553806771611;7448.068991376078;3907.5310962684093;3936.6653834423823;3973.6423721864594;4005.7796821175116;4155.625993660585;3318.7179089777637;3386.7121294689737;3430.468277417004;3437.7937323760652;3441.5525981622227;637.5377851058039;642.0625886206097;643.0036032090529;657.4674513643964;661.5435349608755;505.6435213174703;537.8413368056433;542.6445678080863;613.0191693290699;641.2593454559991;8737.344699147588;8746.781330466789;8814.294856411472;8834.337424693174;8960;416.45183040494817;550.8292429346915;602.4638072115821;642.787879999599;644.7195469970578;3301.5542901707963;3525.259695463981;3645.927081458356;3927.56796752357;3931.3906344516645;148011;153554;156207;163890.18054909888;171594.14202928907;66524;91823;94181.5290923541;126742;135124.97312540124;122284;123487.3825630866;124121.75878241102;124613.13080303621;127843.36078319549;8266.214709602578;8369.288610468178;8390.832183356177;8515.604823489759;8613.784655383666;58733.70633146807;59676.308883544894;61572.076418854645;62623;66412;77440.61279693566;90338;93747.53126234325;95727.52136239554;96954;12636.30500322491;12720.427580759073;12802.039847011325;12840.293783841978;12991;14511.564653060424;14550;14558.563243102724;14673.486427975397;15166.31751571204;13450.192988420726;13456.394462249027;13466.797998030233;13599.260203443737;13963.720725585634;13268.46926122983;13332.466701331838;13418;13951.37218825135;14252.770290506389;13124.409401577142;13449.865501345254;13464.865351346361;13574.321283935962;13731.519396821062;10634;10664.10066911039;10735.94632026835;10945.890541094806;11339.943300283447;10846.620368287073;10860;10902.836457452986;10995.175361847734;11114.444277785917 20:00:43 INFO - PROCESS | 1954 | __end_tp_report 20:00:43 INFO - PROCESS | 1954 | __start_cc_report 20:00:43 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,1372 20:00:43 INFO - PROCESS | 1954 | __end_cc_report 20:00:43 INFO - PROCESS | 1954 | __startTimestamp1472180443781__endTimestamp 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | ------- Summary: start ------- 20:00:43 INFO - PROCESS | 1954 | Number of tests: 6 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | [#0] dojo.html Cycles:165 Average:5807.56 Median:6068.73 stddev:666.96 (11.0%) stddev-sans-first:624.80 20:00:43 INFO - PROCESS | 1954 | Values: 2763.4 3309.1 3313.3 3370.1 3415.0 5642.4 5712.0 5752.5 5804.3 5851.7 5704.3 5747.1 5785.6 5846.2 5907.7 5151.8 5197.5 5238.5 5240.6 5383.8 5356.9 5375.9 5381.2 5384.0 5400.4 4580.4 5224.1 5264.0 5296.4 5315.9 5265.7 5269.7 5296.5 5369.3 5393.6 5243.6 5289.0 5310.3 5318.3 5342.3 5687.6 5755.3 5765.5 5875.1 5960.3 5546.0 6247.9 6302.3 6304.0 6352.1 6020.2 6247.0 6293.2 6325.2 6332.6 5638.4 5689.9 5921.5 5927.9 5976.0 6265.8 6293.0 6317.9 6393.4 6402.1 6289.0 6298.4 6310.7 6315.5 6316.7 6174.4 6266.1 6338.5 6353.1 6366.8 6166.3 6210.8 6232.7 6338.2 6351.7 6340.1 6342.3 6345.4 6346.9 6347.0 5054.6 5068.1 5068.8 5085.6 5123.0 4395.1 4508.1 4542.1 4568.1 4575.2 5756.8 5776.2 5777.2 5785.8 5796.5 6210.5 6242.2 6311.0 6328.2 6340.9 5810.9 5811.0 5817.6 5842.5 5882.5 5474.2 5484.9 5505.5 5506.9 5514.3 6250.4 6294.2 6297.8 6329.1 6602.6 6276.2 6333.8 6340.0 6340.1 6342.6 6430.9 6448.5 6459.7 6469.5 6470.0 6139.9 6143.0 6181.4 6194.8 6254.8 6208.3 6210.9 6232.0 6286.5 6317.7 6236.8 6237.3 6255.4 6255.5 6274.0 5789.5 6063.8 6068.7 6119.0 6146.0 4931.0 6106.4 6146.8 6157.3 6158.6 5434.2 6226.8 6243.5 6247.2 6262.3 5925.5 6074.3 6109.5 6126.5 6128.3 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | [#1] ext.html Cycles:180 Average:65461.31 Median:18854.66 stddev:139133.80 (737.9%) stddev-sans-first:139438.58 20:00:43 INFO - PROCESS | 1954 | Values: 483.4 508.0 516.9 519.0 548.3 29872.1 39924.6 40322.0 40444.8 42099.6 41673.4 42025.0 42089.4 42112.0 42202.0 8525.3 9511.0 9678.1 9687.0 9707.8 8628.7 11558.1 11790.4 11802.5 12086.3 11510.4 11537.1 11543.7 11577.5 11650.2 11744.7 11849.8 11864.8 11871.9 11872.6 11665.2 11729.5 11749.3 11750.2 11757.4 31720.8 38851.8 39126.2 39240.0 39246.4 2792.6 3324.4 3408.6 3423.5 3507.6 30531.0 31566.5 31754.9 31793.8 32111.2 2959.8 3045.3 3091.7 3127.5 3128.8 473096.0 473524.0 475023.0 476912.0 479453.6 55642.9 55724.7 55863.4 55871.4 55916.0 58330.7 58423.8 58447.3 58463.0 58512.4 15847.8 16099.0 16101.4 16108.5 16109.8 11321.5 11569.9 12450.8 12589.0 12686.4 14546.5 15108.2 15111.5 15113.8 15139.2 5556.3 6459.5 6474.1 6524.6 6527.0 845.6 902.0 924.8 947.9 1121.5 27574.7 29223.3 29260.9 30022.5 30178.4 1040.0 1063.3 1083.3 1101.8 1143.7 14444.2 15114.8 15168.0 15206.8 15557.4 595504.0 600503.0 604852.0 606107.0 607259.0 54773.5 55716.2 56035.0 58387.1 58429.7 1348.4 1384.9 1410.0 1411.6 1413.8 29856.7 30398.7 30422.5 30800.4 31314.7 48472.5 48477.8 48533.8 48629.3 48714.3 473827.0 475146.0 478813.0 479422.0 480169.0 25093.4 25679.9 38236.4 38396.4 38527.6 37045.4 37838.8 38095.0 44413.6 47030.8 32523.7 33673.0 33778.2 37318.4 39122.8 31421.7 37711.0 37867.0 38959.3 39115.4 36474.1 36754.6 36966.1 37111.8 37639.2 28420.7 33222.7 33418.3 34194.8 34285.8 24038.0 24746.5 25150.2 25397.7 25517.9 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | [#2] jquery.html Cycles:180 Average:34073.70 Median:27083.33 stddev:93907.26 (346.7%) stddev-sans-first:94136.54 20:00:43 INFO - PROCESS | 1954 | Values: 343.4 344.3 374.1 377.6 399.7 15319.0 16960.3 20543.0 20993.8 21158.0 21030.4 21383.0 21412.0 21451.2 21699.0 5886.5 5937.7 6075.1 6108.6 6195.3 5927.6 6046.6 6046.9 6047.9 6050.9 4159.4 6402.9 6682.2 6729.2 6801.4 5804.0 6217.8 6718.5 6758.8 6765.5 5812.9 5818.9 5824.6 5826.3 5830.7 26320.5 26662.5 26982.7 27184.0 27328.5 2347.9 2530.5 2541.0 2904.7 3116.3 15501.1 15968.3 16133.0 16170.2 16185.0 1650.9 1788.9 1862.7 1876.3 1877.5 114617.9 115843.4 116889.0 117974.0 118761.0 8299.3 8976.7 10377.5 11112.9 11264.5 11399.6 13799.7 13823.0 13846.6 13863.4 8375.0 8664.1 8697.7 8855.6 8905.4 8547.7 8580.0 8616.1 8763.0 8888.3 6556.2 6644.4 6754.3 6762.0 6774.0 1780.3 1812.5 1812.9 1816.7 1818.9 860.8 884.9 912.0 944.0 1036.8 22023.6 22363.3 22820.0 22858.3 23212.7 887.2 915.4 917.8 925.2 935.2 9601.0 11014.4 11874.8 12040.3 12054.7 553067.2 555992.0 566490.0 566588.0 571051.0 117764.0 118320.2 119298.0 120390.8 121367.0 990.6 991.9 1024.1 1026.2 1027.6 19542.6 20012.8 20056.7 20071.3 20166.2 52692.5 53167.8 53490.5 54711.6 55049.2 74900.0 75144.5 75796.6 75865.5 77604.1 5383.5 6507.4 7102.8 7927.5 7969.3 9970.0 12932.2 13200.0 13655.9 13699.9 11163.1 11409.4 11610.1 11640.1 11654.0 11312.2 11439.5 11462.4 11536.7 11757.9 11257.7 11279.4 11286.3 11719.9 11816.8 7626.5 7812.9 7980.7 7984.0 7985.5 11172.3 11347.0 11378.7 11509.8 11561.7 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | [#3] mootools.html Cycles:180 Average:5580.65 Median:5043.53 stddev:3125.64 (62.0%) stddev-sans-first:3117.97 20:00:43 INFO - PROCESS | 1954 | Values: 1315.6 1512.0 1517.4 1712.8 1886.9 7681.1 8337.3 8687.5 8697.7 8769.6 8508.7 8601.7 8611.4 8612.6 8631.9 2340.4 2343.4 2382.8 2383.1 2384.6 1988.2 2318.8 2359.2 2377.7 2379.9 2340.8 2359.2 2363.6 2371.6 2384.6 2316.3 2341.6 2347.4 2349.8 2352.4 2324.0 2329.3 2330.6 2336.4 2340.0 8486.0 8627.4 8631.5 8708.7 8709.3 5147.4 5327.6 5350.8 5353.7 5360.2 3542.2 3568.4 3596.2 3627.2 3630.4 914.2 1050.4 1054.7 1064.8 1067.8 11306.8 11362.9 11405.7 11434.0 11434.0 4343.4 4390.5 4553.4 4564.2 4577.9 8965.1 8965.6 8978.1 8997.7 8999.8 5185.2 5191.9 5201.1 5214.2 5216.9 4319.4 4332.0 4462.7 4845.2 4885.3 3177.4 3667.9 3680.3 3807.2 3854.0 1146.1 1152.2 1155.6 1166.3 1166.8 759.5 867.9 896.6 905.3 919.0 5663.1 5816.7 5933.1 6132.7 6141.8 841.5 899.8 963.9 971.4 1012.7 3893.5 3912.8 3969.5 4065.3 4080.4 9857.8 10442.9 10616.2 10628.5 10673.6 10274.0 10582.7 10584.9 10590.7 10613.8 7952.1 8203.8 8217.0 8223.0 8228.9 5710.2 5772.1 5918.5 5953.5 6002.9 9933.0 9971.8 9982.3 10026.4 10037.5 9746.0 10088.1 10204.6 10235.5 10238.0 6436.4 6633.7 6795.8 6915.9 7050.9 8163.6 8251.3 8395.9 8400.3 8412.9 6150.7 6229.6 7343.0 7395.1 7541.6 7306.9 7308.2 7315.0 7371.0 7403.9 5970.8 6768.7 6906.3 6998.2 7011.6 4038.5 4932.0 4994.0 5055.2 5131.9 4121.2 4935.1 5031.8 5090.5 5097.8 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | [#4] prototype.html Cycles:180 Average:4492.20 Median:5055.71 stddev:1386.51 (27.4%) stddev-sans-first:1352.70 20:00:43 INFO - PROCESS | 1954 | Values: 213.8 253.4 303.6 303.6 314.0 5368.4 5450.5 5454.6 5467.2 5476.0 5053.6 5128.9 5301.0 5376.6 5448.6 3363.4 4052.6 4091.2 4117.0 4122.8 3410.5 4085.4 4086.9 4103.2 4103.5 3810.9 3969.1 4044.5 4070.8 4088.9 3419.0 3727.0 3736.2 3845.8 4045.3 3491.5 3493.4 3508.6 3514.5 3523.7 4944.6 5234.4 5261.9 5302.5 5322.7 3841.4 4543.5 4637.8 4642.5 4656.9 4717.0 5291.8 5670.9 5696.4 5714.3 3634.8 3815.9 3843.9 3854.8 3872.1 4859.2 5396.2 5407.7 5541.4 5866.9 3845.7 3853.9 3860.2 3868.7 3882.7 3732.1 3915.2 3927.4 3939.5 3962.2 3859.9 4352.8 4559.4 4585.3 4623.6 4951.0 5653.7 5697.4 5718.7 5883.7 1186.7 1186.9 1251.8 1266.2 1309.5 761.7 866.4 890.7 892.6 902.7 4460.9 4905.6 5166.3 5227.9 5273.7 4695.4 5684.7 5770.4 5802.1 5878.1 4548.7 4964.0 5187.9 5266.3 5344.9 2612.6 2831.7 2967.2 2991.8 3008.9 5057.8 5502.9 5738.3 5823.4 5835.8 5392.1 5786.3 5800.8 5802.3 5840.5 5098.8 5346.4 5790.5 5888.6 5909.1 4884.6 5274.1 5542.5 5600.3 5681.8 4295.0 4708.5 5019.8 5611.5 5629.1 4573.3 5598.8 5887.7 5898.0 5983.5 3228.9 3646.4 3818.3 3849.2 3893.3 5682.4 5692.6 5713.5 5713.5 5715.5 4857.2 5508.3 5547.6 5564.6 5572.5 4387.9 4816.0 4941.7 5291.2 5504.8 4997.0 5235.4 5404.6 5423.0 5522.5 4543.6 5066.7 5509.9 5620.6 5679.0 5470.7 5531.9 5578.3 5584.9 5592.6 20:00:43 INFO - PROCESS | 1954 | 20:00:43 INFO - PROCESS | 1954 | [#5] yui.html Cycles:180 Average:24320.05 Median:3386.70 stddev:39971.60 (1180.3%) stddev-sans-first:40044.24 20:00:43 INFO - PROCESS | 1954 | Values: 655.9 735.7 741.9 750.4 766.4 13869.4 14357.4 14472.6 14904.2 14933.6 12892.0 13227.2 13227.3 13238.5 13257.3 844.3 868.8 869.3 869.5 870.0 674.9 696.4 702.8 703.2 869.1 724.9 885.5 914.0 916.2 925.5 913.3 914.1 915.6 915.7 916.2 672.8 673.9 673.9 675.8 676.2 11662.5 12135.8 12145.9 12148.9 12152.6 10387.9 12688.9 12700.9 13170.7 13459.1 10925.7 11094.2 11207.2 11456.4 11579.7 3306.9 3343.8 3386.7 3466.0 3466.1 75332.0 109599.5 114590.0 116521.0 117441.0 10162.7 10178.0 10380.8 10620.0 10625.1 20435.2 20754.6 20795.7 20823.4 20836.6 7142.3 7190.7 7364.3 7436.6 7448.1 3907.5 3936.7 3973.6 4005.8 4155.6 3318.7 3386.7 3430.5 3437.8 3441.6 637.5 642.1 643.0 657.5 661.5 505.6 537.8 542.6 613.0 641.3 8737.3 8746.8 8814.3 8834.3 8960.0 416.5 550.8 602.5 642.8 644.7 3301.6 3525.3 3645.9 3927.6 3931.4 148011.0 153554.0 156207.0 163890.2 171594.1 66524.0 91823.0 94181.5 126742.0 135125.0 122284.0 123487.4 124121.8 124613.1 127843.4 8266.2 8369.3 8390.8 8515.6 8613.8 58733.7 59676.3 61572.1 62623.0 66412.0 77440.6 90338.0 93747.5 95727.5 96954.0 12636.3 12720.4 12802.0 12840.3 12991.0 14511.6 14550.0 14558.6 14673.5 15166.3 13450.2 13456.4 13466.8 13599.3 13963.7 13268.5 13332.5 13418.0 13951.4 14252.8 13124.4 13449.9 13464.9 13574.3 13731.5 10634.0 10664.1 10735.9 10945.9 11339.9 10846.6 10860.0 10902.8 10995.2 11114.4 20:00:43 INFO - PROCESS | 1954 | -------- Summary: end -------- 20:00:43 INFO - PROCESS | 1954 | 20:00:44 INFO - TEST-INFO | 1954: exit 0 20:00:44 INFO - TEST-OK | dromaeo_css | took 1118153ms 20:00:44 INFO - TEST-START | kraken 20:00:44 INFO - operating with platform_type : mac_ 20:00:44 INFO - Initialising browser for kraken test... 20:00:44 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpmDJoAk/profile http://localhost:52471/getInfo.html) 20:00:46 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 20:00:46 INFO - PROCESS | 1958 | colorDepth:24 20:00:46 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 20:00:46 INFO - PROCESS | 1958 | __metrics 20:00:46 INFO - PROCESS | 1958 | 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" 20:00:46 INFO - PROCESS | 1958 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:00:47 INFO - TEST-INFO | 1958: exit 0 20:00:47 INFO - Browser initialized. 20:00:47 INFO - Running cycle 1/1 for kraken test... 20:00:47 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpmDJoAk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:00:59 INFO - PROCESS | 1959 | RSS: Main: 233922560 20:00:59 INFO - PROCESS | 1959 | 20:01:02 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-beat-detection) 20:01:03 INFO - PROCESS | 1959 | RSS: Main: 292016128 20:01:03 INFO - PROCESS | 1959 | 20:01:05 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-dft) 20:01:06 INFO - PROCESS | 1959 | RSS: Main: 288436224 20:01:06 INFO - PROCESS | 1959 | 20:01:10 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-fft) 20:01:10 INFO - PROCESS | 1959 | RSS: Main: 376872960 20:01:10 INFO - PROCESS | 1959 | 20:01:12 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-oscillator) 20:01:13 INFO - PROCESS | 1959 | RSS: Main: 279179264 20:01:13 INFO - PROCESS | 1959 | 20:01:15 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52471/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:01:16 INFO - PROCESS | 1959 | RSS: Main: 283627520 20:01:16 INFO - PROCESS | 1959 | 20:01:18 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52471/tests/kraken/driver.html?testName=imaging-darkroom) 20:01:19 INFO - PROCESS | 1959 | RSS: Main: 323952640 20:01:19 INFO - PROCESS | 1959 | 20:01:22 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52471/tests/kraken/driver.html?testName=imaging-desaturate) 20:01:23 INFO - PROCESS | 1959 | RSS: Main: 322625536 20:01:23 INFO - PROCESS | 1959 | 20:01:25 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52471/tests/kraken/driver.html?testName=json-parse-financial) 20:01:26 INFO - PROCESS | 1959 | RSS: Main: 324034560 20:01:26 INFO - PROCESS | 1959 | 20:01:28 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52471/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:01:28 INFO - PROCESS | 1959 | RSS: Main: 298389504 20:01:28 INFO - PROCESS | 1959 | 20:01:30 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:01:31 INFO - PROCESS | 1959 | RSS: Main: 303910912 20:01:31 INFO - PROCESS | 1959 | 20:01:33 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:01:34 INFO - PROCESS | 1959 | RSS: Main: 302235648 20:01:34 INFO - PROCESS | 1959 | 20:01:37 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:01:37 INFO - PROCESS | 1959 | RSS: Main: 322277376 20:01:37 INFO - PROCESS | 1959 | 20:01:40 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:01:41 INFO - PROCESS | 1959 | RSS: Main: 318304256 20:01:41 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52471/tests/kraken/driver.html?testName=ai-astar) 20:01:43 INFO - PROCESS | 1959 | RSS: Main: 361406464 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | __start_tp_report 20:01:43 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 20:01:43 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 20:01:43 INFO - PROCESS | 1959 | |i|pagename|runs| 20:01:43 INFO - PROCESS | 1959 | |0;ai-astar;112;112;131;112;112;113;111;112;110;111 20:01:43 INFO - PROCESS | 1959 | |1;audio-beat-detection;148;109;132;162;106;129;125;104;160;108 20:01:43 INFO - PROCESS | 1959 | |2;audio-dft;184;184;183;182;186;184;182;185;186;184 20:01:43 INFO - PROCESS | 1959 | |3;audio-fft;76;93;105;176;57;59;62;69;144;72 20:01:43 INFO - PROCESS | 1959 | |4;audio-oscillator;82;82;134;70;73;81;118;68;70;119 20:01:43 INFO - PROCESS | 1959 | |5;imaging-gaussian-blur;87;85;87;84;88;84;89;88;92;84 20:01:43 INFO - PROCESS | 1959 | |6;imaging-darkroom;94;93;94;94;93;97;141;94;94;93 20:01:43 INFO - PROCESS | 1959 | |7;imaging-desaturate;85;86;85;85;85;84;84;86;83;84 20:01:43 INFO - PROCESS | 1959 | |8;json-parse-financial;56;56;55;58;52;53;53;53;62;67 20:01:43 INFO - PROCESS | 1959 | |9;json-stringify-tinderbox;67;71;69;68;73;76;71;70;74;74 20:01:43 INFO - PROCESS | 1959 | |10;stanford-crypto-aes;85;85;89;92;106;104;106;105;89;91 20:01:43 INFO - PROCESS | 1959 | |11;stanford-crypto-ccm;138;141;136;139;143;136;131;155;181;157 20:01:43 INFO - PROCESS | 1959 | |12;stanford-crypto-pbkdf2;176;220;172;169;168;170;170;169;168;169 20:01:43 INFO - PROCESS | 1959 | |13;stanford-crypto-sha256-iterative;68;66;69;68;67;67;67;67;66;67 20:01:43 INFO - PROCESS | 1959 | __end_tp_report 20:01:43 INFO - PROCESS | 1959 | __start_cc_report 20:01:43 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,2042 20:01:43 INFO - PROCESS | 1959 | __end_cc_report 20:01:43 INFO - PROCESS | 1959 | __startTimestamp1472180503411__endTimestamp 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | ------- Summary: start ------- 20:01:43 INFO - PROCESS | 1959 | Number of tests: 14 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#0] ai-astar Cycles:10 Average:113.60 Median:112.00 stddev:6.17 (5.5%) stddev-sans-first:6.51 20:01:43 INFO - PROCESS | 1959 | Values: 112.0 112.0 131.0 112.0 112.0 113.0 111.0 112.0 110.0 111.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:130.50 stddev:22.18 (17.0%) stddev-sans-first:22.35 20:01:43 INFO - PROCESS | 1959 | Values: 148.0 109.0 132.0 162.0 106.0 129.0 125.0 104.0 160.0 108.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.41 (0.8%) stddev-sans-first:1.50 20:01:43 INFO - PROCESS | 1959 | Values: 184.0 184.0 183.0 182.0 186.0 184.0 182.0 185.0 186.0 184.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#3] audio-fft Cycles:10 Average:91.30 Median:65.50 stddev:39.89 (60.9%) stddev-sans-first:41.93 20:01:43 INFO - PROCESS | 1959 | Values: 76.0 93.0 105.0 176.0 57.0 59.0 62.0 69.0 144.0 72.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:71.50 stddev:24.36 (34.1%) stddev-sans-first:25.68 20:01:43 INFO - PROCESS | 1959 | Values: 82.0 82.0 134.0 70.0 73.0 81.0 118.0 68.0 70.0 119.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:2.62 (3.0%) stddev-sans-first:2.77 20:01:43 INFO - PROCESS | 1959 | Values: 87.0 85.0 87.0 84.0 88.0 84.0 89.0 88.0 92.0 84.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:94.00 stddev:14.91 (15.9%) stddev-sans-first:15.71 20:01:43 INFO - PROCESS | 1959 | Values: 94.0 93.0 94.0 94.0 93.0 97.0 141.0 94.0 94.0 93.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 20:01:43 INFO - PROCESS | 1959 | Values: 85.0 86.0 85.0 85.0 85.0 84.0 84.0 86.0 83.0 84.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.00 stddev:4.74 (8.5%) stddev-sans-first:5.03 20:01:43 INFO - PROCESS | 1959 | Values: 56.0 56.0 55.0 58.0 52.0 53.0 53.0 53.0 62.0 67.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:2.91 (4.0%) stddev-sans-first:2.64 20:01:43 INFO - PROCESS | 1959 | Values: 67.0 71.0 69.0 68.0 73.0 76.0 71.0 70.0 74.0 74.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:87.00 stddev:8.94 (10.3%) stddev-sans-first:8.69 20:01:43 INFO - PROCESS | 1959 | Values: 85.0 85.0 89.0 92.0 106.0 104.0 106.0 105.0 89.0 91.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:142.00 stddev:14.90 (10.5%) stddev-sans-first:15.54 20:01:43 INFO - PROCESS | 1959 | Values: 138.0 141.0 136.0 139.0 143.0 136.0 131.0 155.0 181.0 157.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:170.00 stddev:15.95 (9.4%) stddev-sans-first:16.92 20:01:43 INFO - PROCESS | 1959 | Values: 176.0 220.0 172.0 169.0 168.0 170.0 170.0 169.0 168.0 169.0 20:01:43 INFO - PROCESS | 1959 | 20:01:43 INFO - PROCESS | 1959 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 20:01:43 INFO - PROCESS | 1959 | Values: 68.0 66.0 69.0 68.0 67.0 67.0 67.0 67.0 66.0 67.0 20:01:43 INFO - PROCESS | 1959 | -------- Summary: end -------- 20:01:43 INFO - PROCESS | 1959 | 20:01:44 INFO - TEST-INFO | 1959: exit 0 20:01:44 INFO - TEST-OK | kraken | took 59959ms 20:01:44 INFO - SUITE-END | took 1178s 20:01:44 INFO - Completed test suite (00:19:38) 20:01:44 INFO - javascript benchmark 20:01:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2763.378239896023, 3309.1065412338685, 3313.2710803623195, 3370.0900756795663, 3414.9658503414976, 5642.351129620085, 5711.971440142799, 5752.453516915897, 5804.332501762294, 5851.707414629267, 5704.344000439951, 5747.051736463479, 5785.566082543815, 5846.181534585162, 5907.734151963161, 5151.793928242866, 5197.48025197481, 5238.52853243208, 5240.580753539708, 5383.838484845442, 5356.892862142755, 5375.865603359908, 5381.165919282498, 5384.0, 5400.351957765074, 4580.358749775034, 5224.085784987612, 5263.973525162595, 5296.390915044762, 5315.920261196085, 5265.6840589564745, 5269.736513174326, 5296.523312901857, 5369.301990741218, 5393.568514518829, 5243.632945693803, 5288.973555132238, 5310.309659744247, 5318.25544423781, 5342.332208473941, 5687.573431992617, 5755.251817263771, 5765.509931655814, 5875.148103524965, 5960.314563825173, 5546.0, 6247.9062814057825, 6302.306746257908, 6303.968480157616, 6352.110704501373, 6020.217371741689, 6247.0, 6293.150424692653, 6325.177726895474, 6332.588381755171, 5638.379778224392, 5689.943100569024, 5921.467067963904, 5927.881442371128, 5976.043832986745, 6265.812025639147, 6292.968535157386, 6317.905231421532, 6393.424591786761, 6402.071699603532, 6288.968555157195, 6298.401651843067, 6310.747570097236, 6315.526335524854, 6316.715747791361, 6174.351693072253, 6266.091416744546, 6338.461230795434, 6353.14232578596, 6366.84082897922, 6166.290876549165, 6210.782622608187, 6232.719527621268, 6338.207724034496, 6351.650659213788, 6340.144080549159, 6342.302346741854, 6345.397187267202, 6346.904796428057, 6347.0, 5054.595632349403, 5068.062408454374, 5068.822591209364, 5085.644004919694, 5122.9743851281255, 4395.120975804852, 4508.0533088051225, 4542.068875880436, 4568.132054909556, 4575.17646823575, 5756.827295181151, 5776.1624564437925, 5777.220075289866, 5785.82642520725, 5796.536277097822, 6210.5031597473, 6242.15730876335, 6311.021791622214, 6328.177336946167, 6340.873182536324, 5810.88378232433, 5811.0, 5817.563682723812, 5842.532597392198, 5882.529397648178, 5474.206240095164, 5484.890302193934, 5505.504504594605, 5506.889862202734, 5514.255575497256, 6250.374962503804, 6294.244690637152, 6297.779577714758, 6329.082283068929, 6602.60384376939, 6276.152719382825, 6333.778317758856, 6340.0, 6340.1440805490665, 6342.619442833445, 6430.871382572229, 6448.516361272829, 6459.6770161490795, 6469.514786390945, 6469.967650161719, 6139.8601398601395, 6143.020634991936, 6181.443670069715, 6194.81415557534, 6254.8123556293385, 6208.317085120543, 6210.944139496387, 6231.971724665379, 6286.528510361833, 6317.6841157941, 6236.75052997875, 6237.313895471404, 6255.40573645503, 6255.530835187288, 6273.99616061428, 5789.45000224978, 6063.848403789764, 6068.7269072892705, 6119.0, 6146.0473626587955, 4931.0, 6106.430331452127, 6146.78486252979, 6157.261128664589, 6158.630482171084, 5434.2120392542065, 6226.813195604139, 6243.469305109116, 6247.219097612799, 6262.3111457738705, 5925.466707996128, 6074.331823499323, 6109.480694141048, 6126.479249263863, 6128.295246046761], "value": 5763.044973429949, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.4029972983366, 507.98730031749204, 516.8707823044239, 518.9853835751104, 548.2571116137636, 29872.103836884875, 39924.600753992454, 40322.0, 40444.78665640028, 42099.57900420995, 41673.37489937653, 42025.0, 42089.368659470136, 42112.0, 42202.0, 8525.275351595123, 9510.952445237781, 9678.080582344666, 9686.9515652422, 9707.75730606737, 8628.65485380584, 11558.13314001449, 11790.35153066581, 11802.46888890002, 12086.274823510574, 11510.424478776069, 11537.134714896416, 11543.65369038926, 11577.53689852405, 11650.184487085908, 11744.706382340424, 11849.763004739943, 11864.762704745857, 11871.881281187163, 11872.584459543874, 11665.241759285704, 11729.53081876724, 11749.295042297405, 11750.23623464472, 11757.35334556599, 31720.841395793104, 38851.805740971395, 39126.217475650614, 39240.0, 39246.411303830464, 2792.581112833071, 3324.3517514084765, 3408.642092580268, 3423.4864770284407, 3507.6141624421293, 30531.0, 31566.526502102486, 31754.888578899627, 31793.841030794698, 32111.19722006945, 2959.8224106553675, 3045.284358175827, 3091.721745042957, 3127.5465057566753, 3128.8435578221224, 473096.0, 473524.0, 475023.0, 476912.0, 479453.6027319876, 55642.88714225693, 55724.72137639286, 55863.44136558582, 55871.44128558744, 55916.0, 58330.708346458, 58423.83152336929, 58447.27856296495, 58463.0, 58512.41487585155, 15847.841521584867, 16099.0, 16101.355945762272, 16108.516744497683, 16109.838901611069, 11321.547138114547, 11569.942150289195, 12450.75098498025, 12589.0, 12686.429110690042, 14546.4908728194, 15108.169050702318, 15111.4710985115, 15113.773293400607, 15139.243037848284, 5556.277683901067, 6459.483241340681, 6474.12599299098, 6524.64114473699, 6526.967365163144, 845.5856630251255, 901.9729408117767, 924.7780532672244, 947.9004704506066, 1121.4897221764077, 27574.724252757216, 29223.269418264714, 29260.853695731385, 30022.549661755093, 30178.396432071673, 1039.9740006499899, 1063.2663462211049, 1083.34999000599, 1101.752105776194, 1143.7197886517856, 14444.205568693822, 15114.773278400833, 15168.0, 15206.771898421312, 15557.377704891904, 595504.0, 600503.0, 604851.9514804796, 606107.0, 607259.0, 54773.45226547684, 55716.16425753617, 56035.0, 58387.12419313714, 58429.707851461324, 1348.4067010515341, 1384.9238291893842, 1410.0482174532149, 1411.567259231901, 1413.8232720909887, 29856.742927894004, 30398.696013039586, 30422.54366184553, 30800.38399231958, 31314.686853131177, 48472.5152748468, 48477.75761121172, 48533.75733121312, 48629.27056094091, 48714.26928596145, 473827.0, 475146.0, 478813.0, 479422.0, 480169.0, 25093.372665683502, 25679.871600641876, 38236.42645360266, 38396.42405363866, 38527.6147238524, 37045.44431833471, 37838.810805945795, 38095.0, 44413.55586444094, 47030.76484617555, 32523.674763252064, 33673.0, 33778.15554611154, 37318.44022339613, 39122.80438597903, 31421.685783141875, 37711.0, 37867.0, 38959.27285090017, 39115.413268800425, 36474.08814779545, 36754.63245367512, 36966.07584810293, 37111.81444092762, 37639.247215054995, 28420.715792841806, 33222.66777332292, 33418.331633367685, 34194.82902585471, 34285.82857085699, 24038.038478460665, 24746.50506989814, 25150.24549263525, 25397.746022539537, 25517.87241063783], "value": 19700.645748325318, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.42819206021977, 344.2564061626886, 374.10215482841176, 377.64312724475366, 399.73817149766893, 15319.0, 16960.321587136514, 20543.0, 20993.790062099375, 21158.0, 21030.369088927317, 21383.0, 21412.036458359373, 21451.249206277782, 21699.0, 5886.470217680408, 5937.67342796146, 6075.119107729377, 6108.572399932006, 6195.349488303731, 5927.61470504418, 6046.606970546923, 6046.939530604704, 6047.879042419149, 6050.909236361458, 4159.417681524589, 6402.903956440658, 6682.19813622366, 6729.158855143107, 6801.421879140279, 5804.0, 6217.751289948396, 6718.49611279156, 6758.797236082901, 6765.492588055916, 5812.941870581282, 5818.854528636775, 5824.563157763142, 5826.271716035496, 5830.717242206708, 26320.473590528272, 26662.466750665073, 26982.650867456745, 27184.0, 27328.45343093147, 2347.9060837566476, 2530.5318516074585, 2540.958207135084, 2904.7385735283924, 3116.329989052342, 15501.069935803887, 15968.281427335798, 16133.0, 16170.191490425432, 16185.0, 1650.867930565552, 1788.856891448681, 1862.6926557118195, 1876.315144972089, 1877.4836919847014, 114617.85382146155, 115843.42078289554, 116889.0, 117974.0, 118761.0, 8299.336053115736, 8976.730698078938, 10377.481125943672, 11112.944435277772, 11264.54941802335, 11399.601013964471, 13799.724005520035, 13823.0, 13846.584602461942, 13863.4454621814, 8374.958125209336, 8664.133586641412, 8697.739067827975, 8855.557222138867, 8905.376623636283, 8547.74356769298, 8580.0, 8616.095309992486, 8763.0, 8888.288936885034, 6556.213254409501, 6644.435223006002, 6754.290799466084, 6761.966190169018, 6773.966130169318, 1780.2522940364818, 1812.4834422189613, 1812.8912265264125, 1816.6729988602178, 1818.8726789124898, 860.8493513635088, 884.9292056635454, 911.9863202051974, 943.9764005899771, 1036.7770929250248, 22023.559528809656, 22363.329100127023, 22820.0, 22858.314250572508, 23212.651810222862, 887.1660638999319, 915.3912648088947, 917.7705573606598, 925.2043242811311, 935.1677007463227, 9600.951995239979, 11014.408761166143, 11874.821877671842, 12040.337781421931, 12054.684961635621, 553067.2346638321, 555992.0, 566490.0, 566588.0, 571051.0, 117764.0, 118320.18178473151, 119298.0, 120390.79609203972, 121367.0, 990.5988074829561, 991.9107280344804, 1024.0936770957755, 1026.1585499890168, 1027.6146445083093, 19542.60914781725, 20012.768421416855, 20056.679759359733, 20071.29750458727, 20166.193352265745, 52692.473075268754, 53167.76834114697, 53490.465095350104, 54711.632209195086, 55049.1742623869, 74900.0, 75144.49711005858, 75796.62101689457, 75865.482690347, 77604.13312793188, 5383.488568585978, 6507.381798729113, 7102.786916392516, 7927.484713493604, 7969.282764551218, 9969.950150249202, 12932.224066556037, 13200.009999250287, 13655.931720341334, 13699.931500342434, 11163.106951444028, 11409.439378336478, 11610.129240306842, 11640.126990475917, 11654.0, 11312.20814542974, 11439.485223164811, 11462.369569673749, 11536.711582210512, 11757.88242117602, 11257.71855703614, 11279.379634120201, 11286.322820630789, 11719.88280117188, 11816.82274765862, 7626.504277221963, 7812.92187078122, 7980.680772769257, 7984.0, 7985.5208687478935, 11172.329660220414, 11347.0, 11378.715532111764, 11509.827352589551, 11561.653150405502], "value": 9835.789349245835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.572438957339, 1512.0398546922704, 1517.355124072269, 1712.837280458356, 1886.9150888210029, 7681.078270607528, 8337.291330236929, 8687.478751274912, 8697.739067827959, 8769.649214031431, 8508.702195423162, 8601.741947741562, 8611.397202195849, 8612.56937153143, 8631.91368086319, 2340.391498210469, 2343.3790045637875, 2382.821288403368, 2383.1063351243283, 2384.618461046233, 1988.1749074134218, 2318.7913087822094, 2359.23324919401, 2377.6909001829736, 2379.8929048192786, 2340.754220806816, 2359.1978727232736, 2363.61000434928, 2371.5968285391436, 2384.606539920911, 2316.328264803205, 2341.613633750429, 2347.4131467133216, 2349.788519033278, 2352.388379021458, 2324.035525257016, 2329.2546385156757, 2330.5804955108074, 2336.439254578906, 2339.9938026648533, 8485.95757021211, 8627.439216450974, 8631.525266110362, 8708.69519566812, 8709.303255739589, 5147.356580427447, 5327.600429967729, 5350.759215835296, 5353.705546194958, 5360.249565060894, 3542.2030043240325, 3568.411212149992, 3596.1728802375337, 3627.2020155565733, 3630.364686179908, 914.2274777812737, 1050.369778133114, 1054.7152268887435, 1064.8455973883742, 1067.8344856547249, 11306.830397544043, 11362.94318528402, 11405.657830265105, 11434.0, 11434.0, 4343.4136391586735, 4390.4511936008, 4553.362529245908, 4564.246899261651, 4577.931331030038, 8965.058668839809, 8965.596548155349, 8978.057303983118, 8997.730068097837, 8999.82000359989, 5185.22221666753, 5191.870203244874, 5201.063808514504, 5214.227162764602, 5216.947830521646, 4319.416878721395, 4331.97834010828, 4462.709923854939, 4845.248986407112, 4885.316055752198, 3177.4280629486916, 3667.8716244931297, 3680.282344942764, 3807.162424266712, 3853.9807300963316, 1146.0602306109076, 1152.2107356460851, 1155.5608868629868, 1166.3351889422947, 1166.7899778039864, 759.4645774728914, 867.9479231246146, 896.6234181643595, 905.298393744853, 918.9678361257457, 5663.122216056518, 5816.680082595498, 5933.050711886078, 6132.6627035513475, 6141.785037523665, 841.4614646626164, 899.8425275576748, 963.8987906269881, 971.3977334052933, 1012.7265638277623, 3893.474380958591, 3912.8434862605177, 3969.4839670843185, 4065.2682517146604, 4080.429918491309, 9857.802843943224, 10442.895571044344, 10616.203784716177, 10628.468576571295, 10673.573057077785, 10274.023967723055, 10582.682519524427, 10584.89415105839, 10590.735231619272, 10613.84079238812, 7952.085510166289, 8203.794905127419, 8217.0, 8223.01323841143, 8228.876566851503, 5710.17202505626, 5772.134179873053, 5918.496927761016, 5953.523718102455, 6002.909956350745, 9933.0, 9971.800563988534, 9982.301238913205, 10026.398416095057, 10037.49812509357, 9745.951270243604, 10088.092071713581, 10204.591816327264, 10235.539400726837, 10238.027387398482, 6436.420722135031, 6633.701483433354, 6795.762148324785, 6915.861682766417, 7050.894236586353, 8163.591820409075, 8251.2986396157, 8395.916040839758, 8400.285975692135, 8412.896775806048, 6150.661713605795, 6229.626222426669, 7342.963285183753, 7395.075615548057, 7541.6229188539255, 7306.926930730625, 7308.159561650478, 7314.96342518284, 7370.96314518424, 7403.851922961403, 5970.820875373745, 6768.661566921536, 6906.274841141608, 6998.230194678683, 7011.579305241702, 4038.4548086008012, 4931.9753401232765, 4993.950060499494, 5055.241713742968, 5131.923021154611, 4121.196366708463, 4935.062338155883, 5031.823886163967, 5090.490950905028, 5097.8215762448135], "value": 4485.424373513922, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [213.7595205393933, 253.42851869035323, 303.5780265431051, 303.60076499403294, 313.95453141040326, 5368.409474957756, 5450.482204190605, 5454.59090568208, 5467.168498552985, 5475.972620136904, 5053.570446512051, 5128.948710512894, 5301.0, 5376.596755243353, 5448.564114870811, 3363.360961417323, 4052.635262826345, 4091.2022155679656, 4117.0, 4122.793860306981, 3410.539577157077, 4085.387191921206, 4086.9169670037463, 4103.1793641271715, 4103.4870641169855, 3810.9047273818237, 3969.1466334738025, 4044.47421835161, 4070.816813243396, 4088.9386659200136, 3419.0, 3726.981365093184, 3736.2153947670886, 3845.7501312073687, 4045.3325201341745, 3491.5460990071383, 3493.3537295600395, 3508.6491350864962, 3514.5255390522207, 3523.7181025518153, 4944.554990050877, 5234.42421333653, 5261.894762104775, 5302.522772950414, 5322.7072511011875, 3841.442990766351, 4543.45478542577, 4637.837675681369, 4642.535746425364, 4656.930146047812, 4717.0330082333385, 5291.814786482493, 5670.865826834608, 5696.3733989261145, 5714.257146570919, 3634.818259087035, 3815.8855234342736, 3843.865464708722, 3854.7687138771485, 3872.1287710265246, 4859.246816743411, 5396.244525766396, 5407.702576358299, 5541.418151094158, 5866.91724437827, 3845.653891149754, 3853.9229215416094, 3860.208657225262, 3868.6905047596124, 3882.6893848492055, 3732.0856390184576, 3915.197384536163, 3927.3716205407, 3939.4878665773267, 3962.187751510933, 3859.86134090444, 4352.8349782807, 4559.3844830948065, 4585.28928016158, 4623.630109591225, 4951.009798040406, 5653.717314134276, 5697.401772813878, 5718.713289509827, 5883.735231914574, 1186.7064899259676, 1186.8991135753386, 1251.787196176652, 1266.208619612742, 1309.476209516182, 761.672480833247, 866.4324867211986, 890.701614958996, 892.6474042753076, 902.6660135749814, 4460.881081486268, 4905.583025442806, 5166.276721259027, 5227.921581176285, 5273.703935040599, 4695.389599352063, 5684.687342196136, 5770.394108618535, 5802.1296805479515, 5878.059510478303, 4548.727076375428, 4963.950360496421, 5187.896242075138, 5266.2890509781455, 5344.877575709144, 2612.555865502869, 2831.697419187156, 2967.1543610071026, 2991.848138466706, 3008.8946886858853, 5057.848264552069, 5502.862428439241, 5738.311402631711, 5823.359430462646, 5835.766569337179, 5392.110301800159, 5786.334571524244, 5800.796972105956, 5802.3037235531165, 5840.474357307863, 5098.796048158107, 5346.358436987586, 5790.536757059425, 5888.587798854211, 5909.084091965667, 4884.584810291165, 5274.072889546185, 5542.501174894118, 5600.299962504687, 5681.818181818195, 4295.0, 4708.458527269408, 5019.84940451787, 5611.5230205433, 5629.0712032514175, 4573.314002899591, 5598.776048957996, 5887.735051922759, 5897.970510147422, 5983.521318294439, 3228.9021732611027, 3646.392829127187, 3818.312703713358, 3849.230153969161, 3893.279743247508, 5682.43175682445, 5692.573057020657, 5713.485926229636, 5713.485926229636, 5715.457031581993, 4857.198562237332, 5508.3114610673665, 5547.611667183259, 5564.638298510584, 5572.498475137257, 4387.890302742457, 4816.0, 4941.728204948762, 5291.1798671206025, 5504.80733174337, 4996.975015124901, 5235.424103348552, 5404.648697834628, 5423.0, 5522.530584900168, 4543.568361005699, 5066.746662666926, 5509.933774834437, 5620.606557541097, 5678.971605141948, 5470.72646367672, 5531.861703457445, 5578.274824272981, 5584.944150558443, 5592.636478628876], "value": 4037.879136433773, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.8524332025295, 735.7351353512735, 741.90355253817, 750.4409215134725, 766.4136935244538, 13869.445222191112, 14357.425702971868, 14472.63818404539, 14904.18027008514, 14933.551993440187, 12892.0, 13227.20636761795, 13227.272500012496, 13238.470461181565, 13257.270850103241, 844.311885813062, 868.7784614923186, 869.2871845087031, 869.4739682492065, 869.9864657673802, 674.8582797612507, 696.3523922751839, 702.7751119641716, 703.152700995302, 869.1135042256894, 724.8550289942007, 885.5218182181615, 914.0448231597996, 916.1662886773038, 925.4863550729342, 913.3332667152949, 914.1498406481969, 915.56053094515, 915.6795121707415, 916.1617120334886, 672.7813460625267, 673.8820706376365, 673.9191297044387, 675.7702381190419, 676.1716896801379, 11662.475188616449, 12135.817962730565, 12145.939270303592, 12148.878511214863, 12152.57465988686, 10387.857335693068, 12688.936555317257, 12700.936495317557, 13170.736585268336, 13459.057865949388, 10925.726856828564, 11094.223404361697, 11207.189555551151, 11456.369899655516, 11579.710507237301, 3306.8511916963794, 3343.782654127474, 3386.6782655647676, 3466.046837119787, 3466.0641626760885, 75332.0, 109599.45200274028, 114590.0, 116521.0, 117441.0, 10162.695119146438, 10178.0, 10380.844287335545, 10620.0, 10625.096866766256, 20435.182592696125, 20754.58490830175, 20795.68806467904, 20823.375298741063, 20836.583268334547, 7142.250063743336, 7190.7483238086415, 7364.2635736427, 7436.553806771611, 7448.068991376078, 3907.5310962684093, 3936.6653834423823, 3973.6423721864594, 4005.7796821175116, 4155.625993660585, 3318.7179089777637, 3386.7121294689737, 3430.468277417004, 3437.7937323760652, 3441.5525981622227, 637.5377851058039, 642.0625886206097, 643.0036032090529, 657.4674513643964, 661.5435349608755, 505.6435213174703, 537.8413368056433, 542.6445678080863, 613.0191693290699, 641.2593454559991, 8737.344699147588, 8746.781330466789, 8814.294856411472, 8834.337424693174, 8960.0, 416.45183040494817, 550.8292429346915, 602.4638072115821, 642.787879999599, 644.7195469970578, 3301.5542901707963, 3525.259695463981, 3645.927081458356, 3927.56796752357, 3931.3906344516645, 148011.0, 153554.0, 156207.0, 163890.18054909888, 171594.14202928907, 66524.0, 91823.0, 94181.5290923541, 126742.0, 135124.97312540124, 122284.0, 123487.3825630866, 124121.75878241102, 124613.13080303621, 127843.36078319549, 8266.214709602578, 8369.288610468178, 8390.832183356177, 8515.604823489759, 8613.784655383666, 58733.70633146807, 59676.308883544894, 61572.076418854645, 62623.0, 66412.0, 77440.61279693566, 90338.0, 93747.53126234325, 95727.52136239554, 96954.0, 12636.30500322491, 12720.427580759073, 12802.039847011325, 12840.293783841978, 12991.0, 14511.564653060424, 14550.0, 14558.563243102724, 14673.486427975397, 15166.31751571204, 13450.192988420726, 13456.394462249027, 13466.797998030233, 13599.260203443737, 13963.720725585634, 13268.46926122983, 13332.466701331838, 13418.0, 13951.37218825135, 14252.770290506389, 13124.409401577142, 13449.865501345254, 13464.865351346361, 13574.321283935962, 13731.519396821062, 10634.0, 10664.10066911039, 10735.94632026835, 10945.890541094806, 11339.943300283447, 10846.620368287073, 10860.0, 10902.836457452986, 10995.175361847734, 11114.444277785917], "value": 7432.659456855994, "unit": "score"}], "name": "dromaeo_css", "value": 7292.027502725079, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 131.0, 112.0, 112.0, 113.0, 111.0, 112.0, 110.0, 111.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 132.0, 162.0, 106.0, 129.0, 125.0, 104.0, 160.0, 108.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 182.0, 186.0, 184.0, 182.0, 185.0, 186.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 93.0, 105.0, 176.0, 57.0, 59.0, 62.0, 69.0, 144.0, 72.0], "value": 91.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 134.0, 70.0, 73.0, 81.0, 118.0, 68.0, 70.0, 119.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 89.0, 88.0, 92.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 97.0, 141.0, 94.0, 94.0, 93.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 85.0, 84.0, 84.0, 86.0, 83.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 58.0, 52.0, 53.0, 53.0, 53.0, 62.0, 67.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 69.0, 68.0, 73.0, 76.0, 71.0, 70.0, 74.0, 74.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 89.0, 92.0, 106.0, 104.0, 106.0, 105.0, 89.0, 91.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 136.0, 139.0, 143.0, 136.0, 131.0, 155.0, 181.0, 157.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 220.0, 172.0, 169.0, 168.0, 170.0, 170.0, 169.0, 168.0, 169.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1488.1, "alertThreshold": 2.0}]} 20:01:44 INFO - Return code: 0 20:01:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:01:44 INFO - # TBPL SUCCESS # 20:01:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:01:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:01:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:01:44 INFO - 2016-08-25 20:01:44.745 screenresolution[1963:17658] starting screenresolution argv=screenresolution get 20:01:44 INFO - 2016-08-25 20:01:44.748 screenresolution[1963:17658] Display 0: 1600x1200x32@60 20:01:44 INFO - 2016-08-25 20:01:44.755 screenresolution[1964:17660] starting screenresolution argv=screenresolution list 20:01:44 INFO - Available Modes on Display 0 20:01:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:01:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:01:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:01:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:01:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:01:44 INFO - Intel Iris: 20:01:44 INFO - Chipset Model: Intel Iris 20:01:44 INFO - Type: GPU 20:01:44 INFO - Bus: Built-In 20:01:44 INFO - VRAM (Dynamic, Max): 1536 MB 20:01:44 INFO - Vendor: Intel (0x8086) 20:01:44 INFO - Device ID: 0x0a2e 20:01:44 INFO - Revision ID: 0x0009 20:01:44 INFO - Displays: 20:01:44 INFO - Display: 20:01:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:01:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:01:44 INFO - Display Serial Number: 1600x1200 60 20:01:44 INFO - Main Display: Yes 20:01:44 INFO - Mirror: Off 20:01:44 INFO - Online: Yes 20:01:44 INFO - Rotation: Supported 20:01:44 INFO - Return code: 0 20:01:44 INFO - Running post-action listener: _resource_record_post_action 20:01:44 INFO - [mozharness: 2016-08-26 03:01:44.997880Z] Finished run-tests step (success) 20:01:44 INFO - Running post-run listener: _resource_record_post_run 20:01:45 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 868352; Write bytes: 708516864; Read time: 8; Write time: 2047 20:01:45 INFO - TinderboxPrint: CPU usage
53.4% 20:01:45 INFO - TinderboxPrint: I/O read bytes / time
868,352 / 8 20:01:45 INFO - TinderboxPrint: I/O write bytes / time
708,516,864 / 2,047 20:01:45 INFO - TinderboxPrint: CPU idle
2,262.7 (46.6%) 20:01:45 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 20:01:45 INFO - TinderboxPrint: CPU user
1,622.3 (33.4%) 20:01:45 INFO - TinderboxPrint: Swap in / out
407,379,968 / 0 20:01:45 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403689984; Write bytes: 398554112; Read time: 30009; Write time: 866 20:01:45 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 819200; Write bytes: 296257536; Read time: 6; Write time: 1140 20:01:45 INFO - Running post-run listener: _upload_blobber_files 20:01:45 INFO - Blob upload gear active. 20:01:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:01:45 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 20:01:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:01:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:01:45 INFO - (blobuploader) - INFO - Open directory for files ... 20:01:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:01:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:01:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:01:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:01:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:01:46 INFO - (blobuploader) - INFO - Done attempting. 20:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:01:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:01:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:01:48 INFO - (blobuploader) - INFO - Done attempting. 20:01:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:01:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:01:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:01:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:01:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:01:50 INFO - (blobuploader) - INFO - Done attempting. 20:01:50 INFO - (blobuploader) - INFO - Iteration through files over. 20:01:50 INFO - Return code: 0 20:01:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:01:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:01:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb513dca4d605601fae9afed92a0dade7e9a7bac708317892258df3949c7db5378a3b84f574dea191ee1c0ccac6f78a7548bd71f9d0695a98ee3f1dfd856ce5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffb43f2ed187c85c4764b7f46bdbb6631214e6462619d6950b99b06c3d42daa416a8cde97d681c7ebd6c029f398c7ddd4eace0515aaa380db2da0b578ca75a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e01f8bb69d338042ae32e7c37b65bef5b4e1aa904ad3a4da23b2d6c3ad3e48a6bfffacb64e5ee7e19ab4ea53aed36a1e4b0a2a331be3b1fe4663fba77148fac"} 20:01:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:01:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:01:50 INFO - Contents: 20:01:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb513dca4d605601fae9afed92a0dade7e9a7bac708317892258df3949c7db5378a3b84f574dea191ee1c0ccac6f78a7548bd71f9d0695a98ee3f1dfd856ce5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffb43f2ed187c85c4764b7f46bdbb6631214e6462619d6950b99b06c3d42daa416a8cde97d681c7ebd6c029f398c7ddd4eace0515aaa380db2da0b578ca75a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e01f8bb69d338042ae32e7c37b65bef5b4e1aa904ad3a4da23b2d6c3ad3e48a6bfffacb64e5ee7e19ab4ea53aed36a1e4b0a2a331be3b1fe4663fba77148fac"} 20:01:50 INFO - Running post-run listener: copy_logs_to_upload_dir 20:01:50 INFO - Copying logs to upload dir... 20:01:50 INFO - mkdir: /builds/slave/test/build/upload/logs 20:01:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.577779 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-25 20:01:50.293375) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 20:01:50.297005) ========= 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.6c5IUpdCNe/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.WYOM5CvviE/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/Firefox/sha512/abb513dca4d605601fae9afed92a0dade7e9a7bac708317892258df3949c7db5378a3b84f574dea191ee1c0ccac6f78a7548bd71f9d0695a98ee3f1dfd856ce5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffb43f2ed187c85c4764b7f46bdbb6631214e6462619d6950b99b06c3d42daa416a8cde97d681c7ebd6c029f398c7ddd4eace0515aaa380db2da0b578ca75a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e01f8bb69d338042ae32e7c37b65bef5b4e1aa904ad3a4da23b2d6c3ad3e48a6bfffacb64e5ee7e19ab4ea53aed36a1e4b0a2a331be3b1fe4663fba77148fac"} build_url:https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/BIurt9GwT9-JKb9N7_IJlg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb513dca4d605601fae9afed92a0dade7e9a7bac708317892258df3949c7db5378a3b84f574dea191ee1c0ccac6f78a7548bd71f9d0695a98ee3f1dfd856ce5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffb43f2ed187c85c4764b7f46bdbb6631214e6462619d6950b99b06c3d42daa416a8cde97d681c7ebd6c029f398c7ddd4eace0515aaa380db2da0b578ca75a5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e01f8bb69d338042ae32e7c37b65bef5b4e1aa904ad3a4da23b2d6c3ad3e48a6bfffacb64e5ee7e19ab4ea53aed36a1e4b0a2a331be3b1fe4663fba77148fac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 20:01:50.334452) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 20:01:50.334885) ========= 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.6c5IUpdCNe/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.WYOM5CvviE/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005222 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 20:01:50.385845) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 20:01:50.386435) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 20:01:50.387167) ========= ========= Total master_lag: 0.18 =========